-- Logs begin at Sun 2024-04-07 09:49:46 UTC, end at Sun 2024-04-07 09:51:28 UTC. -- Apr 07 09:50:00 volumio wireless.js[405]: trying... Apr 07 09:50:00 volumio sudo[722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 07 09:50:00 volumio sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:00 volumio sudo[722]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:01 volumio wireless.js[405]: trying... Apr 07 09:50:01 volumio sudo[725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 07 09:50:01 volumio sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:01 volumio sudo[725]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:02 volumio wireless.js[405]: trying... Apr 07 09:50:02 volumio sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 07 09:50:02 volumio sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:02 volumio sudo[728]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:03 volumio wireless.js[405]: trying... Apr 07 09:50:03 volumio sudo[731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 07 09:50:03 volumio sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:03 volumio sudo[731]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:03 volumio dhcpcd[468]: wlan0: leased 192.168.1.23 for 86400 seconds Apr 07 09:50:03 volumio avahi-daemon[403]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.23. Apr 07 09:50:03 volumio avahi-daemon[403]: New relevant interface wlan0.IPv4 for mDNS. Apr 07 09:50:03 volumio avahi-daemon[403]: Registering new address record for 192.168.1.23 on wlan0.IPv4. Apr 07 09:50:03 volumio dhcpcd[468]: wlan0: adding route to 192.168.1.0/24 Apr 07 09:50:03 volumio dhcpcd[468]: wlan0: adding default route via 192.168.1.1 Apr 07 09:50:03 volumio ntpd[574]: ntpd exiting on signal 15 (Terminated) Apr 07 09:50:03 volumio systemd[1]: Stopping Network Time Service... Apr 07 09:50:03 volumio systemd[1]: ntp.service: Succeeded. Apr 07 09:50:03 volumio systemd[1]: Stopped Network Time Service. Apr 07 09:50:03 volumio systemd[1]: Starting Network Time Service... Apr 07 09:50:03 volumio ntpd[767]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 07 09:50:03 volumio ntpd[767]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 07 09:50:03 volumio systemd[1]: Started Network Time Service. Apr 07 09:50:03 volumio ntpd[773]: proto: precision = 0.207 usec (-22) Apr 07 09:50:03 volumio ntpd[773]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 07 09:50:03 volumio ntpd[773]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 07 09:50:03 volumio ntpd[773]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 467 days ago Apr 07 09:50:03 volumio ntpd[773]: Listen and drop on 0 v6wildcard [::]:123 Apr 07 09:50:03 volumio ntpd[773]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 07 09:50:03 volumio ntpd[773]: Listen normally on 2 lo 127.0.0.1:123 Apr 07 09:50:03 volumio ntpd[773]: Listen normally on 3 wlan0 192.168.1.23:123 Apr 07 09:50:03 volumio ntpd[773]: Listening on routing socket on fd #20 for interface updates Apr 07 09:50:03 volumio ntpd[773]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 07 09:50:03 volumio ntpd[773]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 07 09:50:03 volumio volumio-remote-updater[429]: [2024-04-07 09:50:03] [info] asio async_connect error: system:111 (Connection refused) Apr 07 09:50:03 volumio volumio-remote-updater[429]: [2024-04-07 09:50:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 07 09:50:03 volumio volumio-remote-updater[429]: [2024-04-07 09:50:03] [error] handle_connect error: Underlying Transport Error Apr 07 09:50:04 volumio wireless.js[405]: trying... Apr 07 09:50:04 volumio sudo[776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 07 09:50:04 volumio sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:04 volumio sudo[776]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:04 volumio wireless.js[405]: Connected to: ----Vodafone-A67840873 Apr 07 09:50:04 volumio wireless.js[405]: ---- Apr 07 09:50:04 volumio sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 09:50:04 volumio sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:04 volumio sudo[779]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:04 volumio wireless.js[405]: ... joined AP, wlan0 IPv4 is 192.168.1.23, ipV6 is undefined Apr 07 09:50:04 volumio wireless.js[405]: It's done! AP Apr 07 09:50:04 volumio iwconfig[783]: Error for wireless request "Set Power Management" (8B2C) : Apr 07 09:50:04 volumio iwconfig[783]: SET failed on device wlan0 ; Operation not supported. Apr 07 09:50:04 volumio systemd[1]: Started Wireless Services. Apr 07 09:50:04 volumio systemd[1]: Started Volumio Backend Module. Apr 07 09:50:04 volumio systemd[1]: Started Start Volumio Kiosk. Apr 07 09:50:04 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 07 09:50:04 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Apr 07 09:50:04 volumio volumio-cpu-tweak[786]: Setting RT Priority for mpd Apr 07 09:50:04 volumio volumio-cpu-tweak[786]: pid 35's current scheduling policy: SCHED_OTHER Apr 07 09:50:04 volumio volumio-cpu-tweak[786]: pid 35's current scheduling priority: 0 Apr 07 09:50:04 volumio volumio-cpu-tweak[786]: Not enough cores to set MPD affinity Apr 07 09:50:04 volumio volumio-cpu-tweak[786]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 07 09:50:04 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 07 09:50:04 volumio ntpd[773]: Soliciting pool server 162.159.200.1 Apr 07 09:50:04 volumio startx[785]: X.Org X Server 1.20.4 Apr 07 09:50:04 volumio startx[785]: X Protocol Version 11, Revision 0 Apr 07 09:50:04 volumio startx[785]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Apr 07 09:50:04 volumio startx[785]: Current Operating System: Linux volumio 6.6.7-volumio #2 SMP PREEMPT_DYNAMIC Mon Dec 18 16:22:28 CET 2023 x86_64 Apr 07 09:50:04 volumio startx[785]: Kernel command line: BOOT_IMAGE=/vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=6166fcd6-f6d7-411a-979f-a6a05821ab8f bootpart=UUID=F2D1-63F3 datapart=UUID=e7573f5a-025d-491f-85fa-8ed55ed44083 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 Apr 07 09:50:04 volumio startx[785]: Build Date: 18 December 2021 09:05:36AM Apr 07 09:50:04 volumio startx[785]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Apr 07 09:50:04 volumio startx[785]: Current version of pixman: 0.36.0 Apr 07 09:50:04 volumio startx[785]: Before reporting problems, check http://wiki.x.org Apr 07 09:50:04 volumio startx[785]: to make sure that you have the latest version. Apr 07 09:50:04 volumio startx[785]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 07 09:50:04 volumio startx[785]: (++) from command line, (!!) notice, (II) informational, Apr 07 09:50:04 volumio startx[785]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 07 09:50:04 volumio startx[785]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Apr 7 09:50:04 2024 Apr 07 09:50:04 volumio startx[785]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 07 09:50:04 volumio startx[785]: (II) [KMS] Kernel modesetting enabled. Apr 07 09:50:05 volumio ntpd[773]: Soliciting pool server 37.247.53.178 Apr 07 09:50:05 volumio ntpd[773]: Soliciting pool server 212.45.144.206 Apr 07 09:50:05 volumio systemd[1]: soundcard-init.service: Succeeded. Apr 07 09:50:06 volumio ntpd[773]: Soliciting pool server 162.159.200.123 Apr 07 09:50:06 volumio ntpd[773]: Soliciting pool server 31.14.133.122 Apr 07 09:50:06 volumio ntpd[773]: Soliciting pool server 149.62.187.162 Apr 07 09:50:06 volumio nmbd[546]: [2024/04/07 09:50:06.922114, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 07 09:50:06 volumio systemd[1]: Started Samba NMB Daemon. Apr 07 09:50:06 volumio nmbd[546]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 07 09:50:06 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 07 09:50:07 volumio winbindd[938]: [2024/04/07 09:50:07.411185, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 07 09:50:07 volumio winbindd[938]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 07 09:50:07 volumio systemd[1]: Started Samba Winbind Daemon. Apr 07 09:50:07 volumio winbindd[938]: [2024/04/07 09:50:07.426547, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 07 09:50:07 volumio winbindd[938]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 07 09:50:07 volumio ntpd[773]: Soliciting pool server 93.94.88.51 Apr 07 09:50:07 volumio ntpd[773]: Soliciting pool server 85.199.214.99 Apr 07 09:50:07 volumio ntpd[773]: Soliciting pool server 212.6.50.243 Apr 07 09:50:07 volumio systemd[1]: Starting Samba SMB Daemon... Apr 07 09:50:07 volumio ntpd[773]: Soliciting pool server 95.110.254.234 Apr 07 09:50:07 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:07 volumio volumio[784]: info: ----- Volumio3 ---- Apr 07 09:50:07 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:07 volumio volumio[784]: info: ----- System startup ---- Apr 07 09:50:07 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:08 volumio smbd[969]: [2024/04/07 09:50:08.218912, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 07 09:50:08 volumio smbd[969]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 07 09:50:08 volumio systemd[1]: Started Samba SMB Daemon. Apr 07 09:50:08 volumio systemd[1]: Reached target Multi-User System. Apr 07 09:50:08 volumio systemd[1]: Reached target Graphical Interface. Apr 07 09:50:08 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 07 09:50:08 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 07 09:50:08 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 07 09:50:08 volumio systemd[1]: Startup finished in 22.692s (kernel) + 24.063s (userspace) = 46.756s. Apr 07 09:50:08 volumio ntpd[773]: Soliciting pool server 193.204.114.233 Apr 07 09:50:08 volumio ntpd[773]: Soliciting pool server 212.45.144.3 Apr 07 09:50:08 volumio ntpd[773]: Soliciting pool server 95.230.240.5 Apr 07 09:50:09 volumio ntpd[773]: Soliciting pool server 93.49.6.247 Apr 07 09:50:09 volumio ntpd[773]: Soliciting pool server 93.94.88.50 Apr 07 09:50:09 volumio volumio[784]: info: MYVOLUMIO Environment detected Apr 07 09:50:09 volumio volumio[784]: info: Plugin folders cleanup Apr 07 09:50:09 volumio volumio[784]: info: Scanning into folder /volumio/app/plugins/ Apr 07 09:50:09 volumio volumio[784]: info: Scanning category audio_interface Apr 07 09:50:09 volumio volumio[784]: info: Scanning category miscellanea Apr 07 09:50:09 volumio volumio[784]: info: Scanning category music_service Apr 07 09:50:09 volumio volumio[784]: info: Scanning category plugins.json Apr 07 09:50:09 volumio volumio[784]: info: Scanning category system_controller Apr 07 09:50:09 volumio volumio[784]: info: Scanning category user_interface Apr 07 09:50:09 volumio volumio[784]: info: Scanning into folder /data/plugins/ Apr 07 09:50:09 volumio volumio[784]: info: Scanning category music_service Apr 07 09:50:09 volumio volumio[784]: info: Plugin folders cleanup completed Apr 07 09:50:09 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:09 volumio volumio[784]: info: ----- Core plugins startup ---- Apr 07 09:50:09 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:09 volumio volumio[784]: info: Loading plugins from folder /volumio/app/plugins/ Apr 07 09:50:09 volumio volumio[784]: info: Adding plugin upnp to MyMusic Plugins Apr 07 09:50:09 volumio volumio[784]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 07 09:50:09 volumio volumio[784]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 07 09:50:09 volumio volumio[784]: info: Loading plugins from folder /data/plugins/ Apr 07 09:50:09 volumio volumio[784]: info: Loading plugin "system"... Apr 07 09:50:09 volumio volumio[784]: info: Loading plugin "appearance"... Apr 07 09:50:10 volumio ntpd[773]: Soliciting pool server 185.157.229.254 Apr 07 09:50:10 volumio ntpd[773]: Soliciting pool server 2a03:b020:0:404::51 Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "network"... Apr 07 09:50:13 volumio volumio[784]: info: Refreshing Cached IP Addresses Apr 07 09:50:13 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 09:50:13 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:13 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "services"... Apr 07 09:50:13 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 09:50:13 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "alsa_controller"... Apr 07 09:50:13 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:13 volumio sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 07 09:50:13 volumio sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:13 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "wizard"... Apr 07 09:50:13 volumio volumio-remote-updater[429]: [2024-04-07 09:50:13] [connect] Successful connection Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "networkfs"... Apr 07 09:50:13 volumio volumio[784]: info: Starting Udev Watcher for removable devices Apr 07 09:50:13 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.2/Public /mnt/NAS/Public Apr 07 09:50:13 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:13 volumio volumio[784]: info: Ignoring mount for partition: boot Apr 07 09:50:13 volumio volumio[784]: info: Ignoring mount for partition: volumio Apr 07 09:50:13 volumio volumio[784]: info: Ignoring mount for partition: volumio_data Apr 07 09:50:13 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "volumio_command_line_client"... Apr 07 09:50:13 volumio kernel: FS-Cache: Loaded Apr 07 09:50:13 volumio kernel: Key type dns_resolver registered Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "upnp"... Apr 07 09:50:13 volumio volumio[784]: info: [1712483413836] Starting Upmpd Daemon Apr 07 09:50:13 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "my_music"... Apr 07 09:50:13 volumio volumio[784]: info: Loading plugin "mpd"... Apr 07 09:50:13 volumio kernel: Key type cifs.spnego registered Apr 07 09:50:13 volumio kernel: Key type cifs.idmap registered Apr 07 09:50:13 volumio 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. Apr 07 09:50:13 volumio kernel: CIFS: Attempting to mount //192.168.1.2/Public Apr 07 09:50:14 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:14 volumio ntpd[773]: receive: Unexpected origin timestamp 0xe9bcead6.6f8bf54e does not match aorg 0000000000.00000000 from server@149.62.187.162 xmt 0xe9bcead6.972a6918 Apr 07 09:50:14 volumio ntpd[773]: receive: Unexpected origin timestamp 0xe9bcead6.6f8a174d does not match aorg 0000000000.00000000 from server@212.6.50.243 xmt 0xe9bcead6.960c84f1 Apr 07 09:50:14 volumio ntpd[773]: receive: Unexpected origin timestamp 0xe9bcead6.6f83ce1c does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xe9bcead6.954d02b3 Apr 07 09:50:14 volumio ntpd[773]: receive: Unexpected origin timestamp 0xe9bcead6.6f8831a9 does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xe9bcead6.9811d3fd Apr 07 09:50:14 volumio volumio[784]: info: Loading plugin "upnp_browser"... Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "alarm-clock"... Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "airplay_emulation"... Apr 07 09:50:15 volumio volumio[784]: info: Starting Shairport Sync Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "last_100"... Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "webradio"... Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "i2s_dacs"... Apr 07 09:50:15 volumio volumio[784]: info: I2S DAC not set, start Auto-detection Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "volumiodiscovery"... Apr 07 09:50:15 volumio volumio[784]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 09:50:15 volumio node[784]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 09:50:15 volumio volumio[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 09:50:15 volumio volumio[784]: *** WARNING *** For more information see Apr 07 09:50:15 volumio volumio[784]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 09:50:15 volumio volumio[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 09:50:15 volumio volumio[784]: *** WARNING *** For more information see Apr 07 09:50:15 volumio node[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 09:50:15 volumio node[784]: *** WARNING *** For more information see Apr 07 09:50:15 volumio node[784]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 09:50:15 volumio node[784]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 09:50:15 volumio node[784]: *** WARNING *** For more information see Apr 07 09:50:15 volumio volumio[784]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 07 09:50:15 volumio volumio[784]: info: Discovery: Started advertising with name: Volumio Apr 07 09:50:15 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "outputs"... Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "albumart"... Apr 07 09:50:15 volumio volumio[784]: info: Plugin example_plugin is not enabled Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "inputs"... Apr 07 09:50:15 volumio volumio[784]: info: Loading plugin "updater_comm"... Apr 07 09:50:16 volumio volumio[784]: Forking 1 albumart workers Apr 07 09:50:16 volumio volumio[784]: info: Plugin mpdemulation is not enabled Apr 07 09:50:16 volumio volumio[784]: info: Loading plugin "rest_api"... Apr 07 09:50:16 volumio volumio[784]: info: Loading plugin "websocket"... Apr 07 09:50:16 volumio volumio[784]: info: Loading plugin "lms"... Apr 07 09:50:16 volumio volumio[784]: info: Loading plugin "minidlna"... Apr 07 09:50:17 volumio volumio[784]: info: Applying required configuration parameters for plugin minidlna Apr 07 09:50:17 volumio volumio[784]: Starting albumart workers Apr 07 09:50:17 volumio volumio[784]: info: Loading i18n strings for locale it Apr 07 09:50:17 volumio volumio[784]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Apr 07 09:50:17 volumio volumio[784]: Updating browse sources language Apr 07 09:50:17 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::initPlayerControls Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 09:50:18 volumio volumio[784]: Express server listening on port 3000 Apr 07 09:50:18 volumio volumio[784]: [Metrics] WebUI: 11s 627.28ms Apr 07 09:50:18 volumio volumio[784]: info: Setting Device type: x86 Apr 07 09:50:18 volumio volumio[784]: info: CoreStateMachine::resetVolumioState Apr 07 09:50:18 volumio volumio[784]: info: CoreStateMachine::getcurrentVolume Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:18 volumio volumio[784]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 07 09:50:18 volumio volumio[784]: info: Completed loading Core Plugins Apr 07 09:50:18 volumio volumio[784]: info: Preparing to generate the ALSA configuration file Apr 07 09:50:18 volumio volumio[784]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 07 09:50:18 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 1 Apr 07 09:50:18 volumio sudo[1027]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:18 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 2 Apr 07 09:50:18 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 3 Apr 07 09:50:18 volumio volumio-remote-updater[429]: [2024-04-07 09:50:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712483413 101 Apr 07 09:50:18 volumio volumio[784]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Apr 07 09:50:18 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Apr 07 09:50:18 volumio volumio[784]: info: Reloading queue from file Apr 07 09:50:18 volumio volumio[784]: info: Asound.conf file unchanged, so no further update is needed Apr 07 09:50:18 volumio volumio[784]: info: Output device has changed, restarting MPD Apr 07 09:50:18 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 07 09:50:18 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 09:50:18 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:18 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:18 volumio volumio[784]: info: Output device has changed, restarting Shairport Sync Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 09:50:18 volumio volumio[784]: info: ___________ START PLUGINS ___________ Apr 07 09:50:18 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 09:50:18 volumio volumio[784]: info: ControllerMpd::onStart: Initializing MPD Apr 07 09:50:18 volumio volumio[784]: info: Creating MPD Configuration file Apr 07 09:50:18 volumio sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:18 volumio systemd[1]: Listening on mpd.socket. Apr 07 09:50:18 volumio systemd[1]: Starting Music Player Daemon... Apr 07 09:50:18 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 09:50:18 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 09:50:18 volumio volumio[784]: info: [1712483418954] CoreMusicLibrary::Adding element Server multimediali Apr 07 09:50:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:18 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:18 volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 09:50:18 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 07 09:50:19 volumio systemd[1]: mpd.service: Succeeded. Apr 07 09:50:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 09:50:19 volumio systemd[1]: Starting Music Player Daemon... Apr 07 09:50:19 volumio volumio[784]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:19 volumio volumio[784]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 09:50:19 volumio volumio[784]: info: [1712483419188] CoreMusicLibrary::Adding element Last_100 Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 09:50:19 volumio volumio[784]: info: [1712483419193] CoreMusicLibrary::Adding element Webradio Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 09:50:19 volumio volumio[784]: info: Loading i18n strings for locale it Apr 07 09:50:19 volumio volumio[784]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Apr 07 09:50:19 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Apr 07 09:50:19 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:19 volumio 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. Apr 07 09:50:19 volumio systemd[1]: Starting Logitech Media Server Daemon... Apr 07 09:50:19 volumio volumio[784]: info: Volumio Calling Home Apr 07 09:50:19 volumio systemd[1]: Started Logitech Media Server Daemon. Apr 07 09:50:19 volumio systemd[1141]: logitechmediaserver.service: Failed to execute command: No such file or directory Apr 07 09:50:19 volumio systemd[1141]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Apr 07 09:50:19 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:19 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Apr 07 09:50:19 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Apr 07 09:50:19 volumio volumio[784]: info: CoreStateMachine::setRepeat true single undefined Apr 07 09:50:19 volumio volumio[784]: info: CoreStateMachine::pushState Apr 07 09:50:19 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::volumioPushState Apr 07 09:50:19 volumio volumio[784]: info: CoreStateMachine::setRandom false Apr 07 09:50:19 volumio volumio[784]: info: CoreStateMachine::pushState Apr 07 09:50:19 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:19 volumio sh[351]: timed out Apr 07 09:50:19 volumio sh[351]: dhcpcd exited Apr 07 09:50:19 volumio dhcpcd[392]: timed out Apr 07 09:50:19 volumio dhcpcd[392]: dhcpcd exited Apr 07 09:50:19 volumio sh[351]: ifup: failed to bring up eth0 Apr 07 09:50:19 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::volumioPushState Apr 07 09:50:19 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 07 09:50:19 volumio volumio[784]: info: VolumeController:: Volume=100 Mute =false Apr 07 09:50:19 volumio volumio[784]: info: CoreStateMachine::pushState Apr 07 09:50:19 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::volumioPushState Apr 07 09:50:19 volumio volumio[784]: info: CoreStateMachine::updateTrackBlock Apr 07 09:50:19 volumio volumio[784]: info: CorePlayQueue::getTrackBlock Apr 07 09:50:19 volumio volumio[784]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 09:50:20 volumio volumio[784]: info: logitechmediaserver started Apr 07 09:50:20 volumio volumio[784]: info: MPD Permissions set Apr 07 09:50:20 volumio volumio[784]: info: Discovery: adding 40ca8de4-8c37-49f7-8729-082c6a8387f9 Apr 07 09:50:20 volumio volumio[784]: info: Discovery: Found device Volumio Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:20 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:20 volumio volumio[784]: info: Volumio called home Apr 07 09:50:20 volumio volumio[784]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 07 09:50:20 volumio dbus-daemon[400]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.10' (uid=0 pid=995 comm="/usr/lib/chromium/chromium --show-component-extens") Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:20 volumio systemd[1]: Starting Bluetooth service... Apr 07 09:50:20 volumio volumio[784]: info: VolumeController:: Volume=100 Mute =false Apr 07 09:50:20 volumio volumio[784]: info: CoreStateMachine::pushState Apr 07 09:50:20 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:20 volumio volumio[784]: info: CoreCommandRouter::volumioPushState Apr 07 09:50:20 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 7 Apr 07 09:50:20 volumio volumio[784]: info: minidlna: Found miniDLNA version 1.2.1 Apr 07 09:50:20 volumio volumio[784]: info: minidlna: Starting minidlna.service Apr 07 09:50:20 volumio volumio[784]: info: Starting Shairport Sync Apr 07 09:50:20 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Apr 07 09:50:20 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:20 volumio volumio[784]: info: Starting Shairport Sync Apr 07 09:50:20 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Apr 07 09:50:20 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:20 volumio volumio[784]: info: Starting Shairport Sync Apr 07 09:50:20 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 09:50:20 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:20 volumio sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 09:50:20 volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 09:50:21 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 07 09:50:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 09:50:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 09:50:21 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:21 volumio sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 09:50:21 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:21 volumio volumio[784]: info: minidlna: systemctl start minidlna.service succeeded. Apr 07 09:50:21 volumio sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 09:50:21 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 07 09:50:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 09:50:21 volumio volumio[784]: info: Shairport-Sync Started Apr 07 09:50:21 volumio volumio[784]: Error adding Membership: Error: addMembership EINVAL Apr 07 09:50:21 volumio volumio[784]: info: Shairport-Sync Started Apr 07 09:50:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 09:50:21 volumio sudo[1212]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:21 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:21 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:21 volumio volumio[784]: info: Shairport-Sync Started Apr 07 09:50:21 volumio kernel: Bluetooth: Core ver 2.22 Apr 07 09:50:21 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Apr 07 09:50:21 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 07 09:50:21 volumio kernel: Bluetooth: HCI socket layer initialized Apr 07 09:50:21 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 07 09:50:21 volumio kernel: Bluetooth: SCO socket layer initialized Apr 07 09:50:21 volumio bluetoothd[1162]: Bluetooth daemon 5.50 Apr 07 09:50:21 volumio bluetoothd[1162]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 07 09:50:21 volumio dbus-daemon[400]: [system] Successfully activated service 'org.bluez' Apr 07 09:50:21 volumio systemd[1]: Started Bluetooth service. Apr 07 09:50:21 volumio bluetoothd[1162]: Starting SDP server Apr 07 09:50:21 volumio bluetoothd[1162]: Excluding (cli) sap Apr 07 09:50:21 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 07 09:50:21 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 07 09:50:21 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 07 09:50:21 volumio dbus-daemon[400]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.12' (uid=0 pid=1162 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 07 09:50:21 volumio bluetoothd[1162]: Bluetooth management interface 1.22 initialized Apr 07 09:50:21 volumio systemd[1]: Starting Hostname Service... Apr 07 09:50:21 volumio dbus-daemon[400]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 07 09:50:21 volumio systemd[1]: Started Hostname Service. Apr 07 09:50:23 volumio mpd[1127]: Apr 07 09:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 09:50:23 volumio systemd[1]: Started Music Player Daemon. Apr 07 09:50:23 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:23 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:23 volumio volumio[784]: info: Completed starting Core Plugins Apr 07 09:50:23 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:23 volumio volumio[784]: info: ----- MyVolumio plugins startup ---- Apr 07 09:50:23 volumio volumio[784]: info: ------------------------------------------- Apr 07 09:50:23 volumio volumio[784]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 07 09:50:23 volumio volumio[784]: info: MPD running with PID1127 Apr 07 09:50:23 volumio volumio[784]: ,establishing connection Apr 07 09:50:23 volumio volumio[784]: error: MPD error: The expression evaluated to a falsy value: Apr 07 09:50:23 volumio volumio[784]: assert.ok(self.idling) Apr 07 09:50:23 volumio volumio[784]: error: The expression evaluated to a falsy value: Apr 07 09:50:23 volumio volumio[784]: assert.ok(self.idling) Apr 07 09:50:23 volumio volumio[784]: error: MPD error: The expression evaluated to a falsy value: Apr 07 09:50:23 volumio volumio[784]: assert.ok(self.idling) Apr 07 09:50:23 volumio volumio[784]: error: The expression evaluated to a falsy value: Apr 07 09:50:23 volumio volumio[784]: assert.ok(self.idling) Apr 07 09:50:23 volumio volumio[784]: error: updateQueue error: null Apr 07 09:50:24 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 09:50:24 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:24 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:24 volumio sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 09:50:24 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:24 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:24 volumio sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 07 09:50:24 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:24 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 07 09:50:24 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:24 volumio volumio[784]: info: Upmpdcli Daemon Started Apr 07 09:50:24 volumio volumio[1291]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 07 09:50:28 volumio volumio[784]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 07 09:50:30 volumio volumio[784]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 8 Apr 07 09:50:30 volumio volumio[1291]: .........................................................................................................................................................................................++++ Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 07 09:50:32 volumio volumio[784]: info: Adding plugin bluetooth to MyMusic Plugins Apr 07 09:50:32 volumio volumio[784]: info: Adding plugin multiroom to MyMusic Plugins Apr 07 09:50:32 volumio volumio[784]: info: Adding plugin metavolumio to MyMusic Plugins Apr 07 09:50:32 volumio volumio[784]: info: Adding plugin cd_controller to MyMusic Plugins Apr 07 09:50:32 volumio volumio[784]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 07 09:50:32 volumio volumio[784]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 07 09:50:32 volumio volumio[784]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 07 09:50:32 volumio volumio[1291]: ......................................................................................++++ Apr 07 09:50:32 volumio volumio[1291]: e is 65537 (0x010001) Apr 07 09:50:32 volumio volumio[1291]: writing RSA key Apr 07 09:50:33 volumio volumio[784]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 07 09:50:33 volumio volumio[784]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 07 09:50:33 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:33 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:33 volumio volumio[784]: info: Starting MyVolumio Remote Streaming Endpoints Apr 07 09:50:33 volumio volumio[784]: info: MyVolumio login type: Token Apr 07 09:50:34 volumio volumio[784]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 07 09:50:34 volumio volumio[784]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 07 09:50:35 volumio volumio[784]: info: Starting Streaming Service Transparent Proxy Apr 07 09:50:35 volumio volumio[784]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 07 09:50:35 volumio volumio[784]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 07 09:50:35 volumio volumio[784]: info: Streaming services startup Apr 07 09:50:35 volumio volumio[784]: info: Starting Streaming Daemon Apr 07 09:50:35 volumio sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 07 09:50:35 volumio sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:35 volumio sudo[1368]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:35 volumio volumio[784]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 07 09:50:36 volumio volumio[784]: error: Cannot start Volumio Streaming Daemon Apr 07 09:50:36 volumio volumio[784]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 07 09:50:36 volumio volumio[784]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 07 09:50:37 volumio volumio[784]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 07 09:50:37 volumio volumio[784]: STREAMING PROXY: Starting server on port 3245 Apr 07 09:50:37 volumio volumio[784]: Node JS runtime: 14 Apr 07 09:50:38 volumio volumio[784]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 9 Apr 07 09:50:38 volumio volumio[784]: info: MyVolumio token set successfully Apr 07 09:50:38 volumio volumio[784]: info: MYVOLUMIO: Adding device Apr 07 09:50:38 volumio volumio[784]: info: MYVOLUMIO: Evaluating Server Apr 07 09:50:38 volumio volumio[784]: info: MyVolumio status changed Apr 07 09:50:38 volumio volumio[784]: info: Streaming services startup Apr 07 09:50:38 volumio volumio[784]: info: Starting Streaming Daemon Apr 07 09:50:39 volumio volumio[784]: info: Removing browser output: myVolumio user plan is not superstar Apr 07 09:50:39 volumio volumio[784]: info: Removing audio output: Apr 07 09:50:39 volumio volumio[784]: info: Stoppping Tunnel 1 Apr 07 09:50:39 volumio sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 07 09:50:39 volumio sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:39 volumio sudo[1560]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:39 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 07 09:50:39 volumio volumio[784]: error: Cannot start Volumio Streaming Daemon Apr 07 09:50:39 volumio volumio[784]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 07 09:50:39 volumio volumio[784]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 07 09:50:39 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:39 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:39 volumio volumio[784]: info: Remote SSH Stopped Apr 07 09:50:39 volumio volumio[784]: info: Setting Geolocation for MyVolumio to eu6 Apr 07 09:50:39 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:39 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:39 volumio volumio[784]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 07 09:50:40 volumio volumio[784]: info: Updating MyVolumio device info Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:40 volumio volumio[784]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 10 Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:40 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:40 volumio volumio[784]: info: Listing playlists Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:40 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 09:50:40 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:40 volumio volumio[784]: info: Listing playlists Apr 07 09:50:40 volumio volumio[784]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 07 09:50:43 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 09:50:43 volumio volumio[784]: info: MYVOLUMIO: Adding device Apr 07 09:50:43 volumio volumio[784]: info: MYVOLUMIO: Evaluating Server Apr 07 09:50:44 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 09:50:44 volumio volumio[784]: info: Setting Geolocation for MyVolumio to eu6 Apr 07 09:50:44 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:44 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:45 volumio volumio[784]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 07 09:50:45 volumio volumio[784]: info: Updating MyVolumio device info Apr 07 09:50:45 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:46 volumio volumio[784]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 07 09:50:48 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:50:49 volumio volumio[784]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 07 09:50:49 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:49 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:49 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 09:50:49 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:49 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 09:50:49 volumio volumio-remote-updater[429]: No test mode Apr 07 09:50:49 volumio volumio-remote-updater[429]: No alpha test mode Apr 07 09:50:49 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 07 09:50:49 volumio volumio[784]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 07 09:50:49 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 07 09:50:51 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 09:50:51 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 09:50:51 volumio volumio[784]: info: Discovery: Getting this device information Apr 07 09:50:51 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:51 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:51 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 09:50:52 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::volumioGetBrowseSources Apr 07 09:50:53 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 09:50:54 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 09:50:54 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:54 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:54 volumio sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 09:50:54 volumio sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:54 volumio sudo[1651]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:54 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 12 Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:55 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::volumioGetQueue Apr 07 09:50:55 volumio volumio[784]: info: CoreStateMachine::getQueue Apr 07 09:50:55 volumio volumio[784]: info: CorePlayQueue::getQueue Apr 07 09:50:55 volumio volumio[784]: info: Listing playlists Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:50:55 volumio volumio[784]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 07 09:50:55 volumio volumio[784]: info: Completed starting MyVolumio Plugin Apr 07 09:50:55 volumio volumio[784]: info: BOOT COMPLETED Apr 07 09:50:55 volumio volumio[784]: [Metrics] CommandRouter: 48s 101.83ms Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::volumiosetStartupVolume Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::Close All Modals sent Apr 07 09:50:55 volumio volumio[784]: info: CoreCommandRouter::Close All Modals sent Apr 07 09:50:56 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: unknown Total Clients: 13 Apr 07 09:50:56 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:56 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:56 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 07 09:50:56 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 07 09:50:56 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 07 09:50:56 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 07 09:50:57 volumio volumio[784]: info: Version has changed, forcing UI Reload Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::Reload Ui Apr 07 09:50:57 volumio sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 09:50:57 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:57 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:57 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 09:50:57 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 09:50:57 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Apr 07 09:50:57 volumio volumio[784]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 12 Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:57 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::volumioGetQueue Apr 07 09:50:57 volumio volumio[784]: info: CoreStateMachine::getQueue Apr 07 09:50:57 volumio volumio[784]: info: CorePlayQueue::getQueue Apr 07 09:50:57 volumio volumio[784]: info: Listing playlists Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 07 09:50:57 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:50:58 volumio volumio[784]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 13 Apr 07 09:50:59 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:50:59 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:50:59 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 09:51:00 volumio volumio[784]: info: Listing playlists Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 07 09:51:00 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:51:02 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 09:51:02 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:51:16 volumio volumio[784]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 07 09:51:16 volumio volumio[784]: info: Received Get System Version Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 09:51:16 volumio volumio[784]: info: Received Get System Info Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 09:51:16 volumio volumio[784]: info: Discovery: Getting this device information Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:51:16 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 09:51:16 volumio volumio[784]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 07 09:51:16 volumio volumio[784]: info: Received Get System Version Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 09:51:16 volumio volumio[784]: info: Received Get System Info Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 09:51:16 volumio volumio[784]: info: Discovery: Getting this device information Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::volumioGetState Apr 07 09:51:16 volumio volumio[784]: info: CorePlayQueue::getTrack 0 Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 09:51:16 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 07 09:51:16 volumio volumio[784]: info: CURURI: music-library Apr 07 09:51:16 volumio volumio[784]: info: Preload queue cleared Apr 07 09:51:17 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 07 09:51:17 volumio volumio[784]: info: CURURI: music-library/NAS Apr 07 09:51:18 volumio volumio[784]: info: Preload queue cleared Apr 07 09:51:18 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 07 09:51:18 volumio volumio[784]: info: CURURI: music-library/NAS/Public Apr 07 09:51:18 volumio volumio[784]: info: Preload queue cleared Apr 07 09:51:20 volumio volumio[784]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 07 09:51:20 volumio volumio[784]: info: CURURI: music-library/NAS/Public/Musica Apr 07 09:51:20 volumio volumio[784]: info: Preload queue cleared Apr 07 09:51:27 volumio volumio[784]: info: Preload queue cleared Apr 07 09:51:27 volumio volumio[784]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 07 09:51:27 volumio volumio[784]: info: CoreStateMachine::ClearQueue Apr 07 09:51:27 volumio volumio[784]: info: CoreStateMachine::stop Apr 07 09:51:27 volumio volumio[784]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 09:51:27 volumio volumio[784]: info: CorePlayQueue::clearPlayQueue Apr 07 09:51:27 volumio volumio[784]: info: CorePlayQueue::saveQueue Apr 07 09:51:27 volumio volumio[784]: info: CoreCommandRouter::volumioPushQueue Apr 07 09:51:27 volumio volumio[784]: info: CoreStateMachine::addQueueItems Apr 07 09:51:27 volumio volumio[784]: info: CorePlayQueue::addQueueItems Apr 07 09:51:27 volumio volumio[784]: info: Preload queue cleared Apr 07 09:51:27 volumio volumio[784]: info: Adding Item to queue: music-library/NAS/Public/Musica/Giovanni Allevi - Love (2015) Apr 07 09:51:27 volumio volumio[784]: info: Exploding uri music-library/NAS/Public/Musica/Giovanni Allevi - Love (2015) in service mpd Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F01.%20Giovanni%20Allevi%20-%20Yuzen.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/01. Giovanni Allevi - Yuzen.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F02.%20Giovanni%20Allevi%20-%20Loving%20you.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/02. Giovanni Allevi - Loving you.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F03.%20Giovanni%20Allevi%20-%20Amor%20sacro.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/03. Giovanni Allevi - Amor sacro.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F04.%20Giovanni%20Allevi%20-%20Asteroid%20111561.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/04. Giovanni Allevi - Asteroid 111561.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F05.%20Giovanni%20Allevi%20-%20The%20other%20side%20of%20me.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/05. Giovanni Allevi - The other side of me.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F06.%20Giovanni%20Allevi%20-%20La%20stanza%20dei%20giochi.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/06. Giovanni Allevi - La stanza dei giochi.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F07.%20Giovanni%20Allevi%20-%20It%20doesn't%20work.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/07. Giovanni Allevi - It doesn't work.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F08.%20Giovanni%20Allevi%20-%20Lovers.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/08. Giovanni Allevi - Lovers.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F09.%20Giovanni%20Allevi%20-%20My%20family.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/09. Giovanni Allevi - My family.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F10.%20Giovanni%20Allevi%20-%20Asian%20eyes.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/10. Giovanni Allevi - Asian eyes.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F11.%20Giovanni%20Allevi%20-%20Come%20with%20me.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/11. Giovanni Allevi - Come with me.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F12.%20Giovanni%20Allevi%20-%20Sweetie%20pie.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/12. Giovanni Allevi - Sweetie pie.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2F13.%20Giovanni%20Allevi%20-%20L'albatros.flac&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/13. Giovanni Allevi - L'albatros.flac Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2FLove.FLAC.cue&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/Love.FLAC.cue Apr 07 09:51:28 volumio volumio[784]: info: ALBUMART /albumart?cacheid=718&web=Giovanni%20Allevi/Love/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FMusica%2FGiovanni%20Allevi%20-%20Love%20(2015)%2FLove.FLAC.cue&metadata=false Apr 07 09:51:28 volumio volumio[784]: info: URI /mnt/NAS/Public/Musica/Giovanni Allevi - Love (2015)/Love.FLAC.cue Apr 07 09:51:28 volumio volumio[784]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 09:51:28 volumio volumio[784]: Error: Unable to resolve or reject the same promise twice Apr 07 09:51:28 volumio volumio[784]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 07 09:51:28 volumio volumio[784]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 07 09:51:28 volumio volumio[784]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 07 09:51:28 volumio volumio[784]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 07 09:51:28 volumio volumio[784]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 07 09:51:28 volumio volumio[784]: at Socket.emit (events.js:400:28) Apr 07 09:51:28 volumio volumio[784]: at addChunk (internal/streams/readable.js:293:12) Apr 07 09:51:28 volumio volumio[784]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 07 09:51:28 volumio volumio[784]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 07 09:51:28 volumio volumio[784]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 07 09:51:28 volumio volumio[784]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 09:51:28 volumio sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 09:50 Apr 07 09:51:28 volumio sudo[1672]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"