-- 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"