-- Logs begin at Fri 2024-04-26 13:30:40 CST, end at Fri 2024-04-26 13:32:28 CST. --
Apr 26 13:31:00 volumio wireless.js[649]: trying...
Apr 26 13:31:00 volumio sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 26 13:31:00 volumio sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:00 volumio sudo[1105]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:01 volumio wireless.js[649]: trying...
Apr 26 13:31:01 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 26 13:31:01 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:01 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:02 volumio wireless.js[649]: trying...
Apr 26 13:31:02 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 26 13:31:02 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:02 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:03 volumio wireless.js[649]: trying...
Apr 26 13:31:03 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 26 13:31:03 volumio sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:03 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:03 volumio wpa_supplicant[1004]: wlan0: Failed to initiate sched scan
Apr 26 13:31:04 volumio wireless.js[649]: trying...
Apr 26 13:31:04 volumio sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 26 13:31:04 volumio sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:04 volumio sudo[1117]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:04 volumio volumio-remote-updater[663]: [2024-04-26 13:31:04] [info] asio async_connect error: system:111 (Connection refused)
Apr 26 13:31:04 volumio volumio-remote-updater[663]: [2024-04-26 13:31:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 26 13:31:04 volumio volumio-remote-updater[663]: [2024-04-26 13:31:04] [error] handle_connect error: Underlying Transport Error
Apr 26 13:31:05 volumio wireless.js[649]: trying...
Apr 26 13:31:05 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 26 13:31:05 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:05 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:06 volumio wireless.js[649]: Overtime, starting plan B
Apr 26 13:31:06 volumio wireless.js[649]: STARTING HOTSPOT
Apr 26 13:31:06 volumio wpa_supplicant[1004]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 26 13:31:06 volumio wpa_supplicant[1004]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 26 13:31:06 volumio wpa_supplicant[1004]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory
Apr 26 13:31:06 volumio wpa_supplicant[1004]: nl80211: Failed to set IPv4 unicast in multicast filter
Apr 26 13:31:06 volumio wpa_supplicant[1004]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0
Apr 26 13:31:06 volumio wpa_supplicant[1004]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING
Apr 26 13:31:06 volumio wpa_supplicant[1004]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Apr 26 13:31:06 volumio wpa_supplicant[1004]: wlan0: CTRL-EVENT-TERMINATING
Apr 26 13:31:09 volumio sudo[1129]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 26 13:31:09 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:09 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:09 volumio sudo[1131]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 26 13:31:09 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:09 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:09 volumio avahi-daemon[678]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Apr 26 13:31:09 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 26 13:31:09 volumio avahi-daemon[678]: New relevant interface wlan0.IPv4 for mDNS.
Apr 26 13:31:09 volumio avahi-daemon[678]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Apr 26 13:31:09 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Apr 26 13:31:09 volumio hostapd[1137]: Configuration file: /etc/hostapd/hostapd.conf
Apr 26 13:31:09 volumio hostapd[1137]: wlan0: Could not connect to kernel driver
Apr 26 13:31:09 volumio hostapd[1137]: Using interface wlan0 with hwaddr 2c:cf:67:1e:35:af and ssid "tikiNB"
Apr 26 13:31:09 volumio dhcpcd[716]: wlan0: carrier acquired
Apr 26 13:31:09 volumio dhcpcd[716]: wlan0: IAID 67:1e:35:af
Apr 26 13:31:09 volumio dhcpcd[716]: wlan0: adding address fe80::2ecf:67ff:fe1e:35af
Apr 26 13:31:09 volumio dhcpcd[716]: ipv6_addaddr1: Permission denied
Apr 26 13:31:09 volumio hostapd[1137]: wlan0: interface state UNINITIALIZED->ENABLED
Apr 26 13:31:09 volumio hostapd[1137]: wlan0: AP-ENABLED
Apr 26 13:31:09 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Apr 26 13:31:09 volumio iwconfig[1141]: Error for wireless request "Set Power Management" (8B2C) :
Apr 26 13:31:09 volumio iwconfig[1141]: SET failed on device wlan0 ; Invalid argument.
Apr 26 13:31:09 volumio systemd[1]: wireless.service: Succeeded.
Apr 26 13:31:09 volumio systemd[1]: Started Wireless Services.
Apr 26 13:31:09 volumio systemd[1]: Started Volumio Backend Module.
Apr 26 13:31:09 volumio systemd[1]: Started Volumio Cpu Tweaker.
Apr 26 13:31:09 volumio systemd[1]: Reached target Multi-User System.
Apr 26 13:31:09 volumio systemd[1]: Reached target Graphical Interface.
Apr 26 13:31:09 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 26 13:31:09 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 26 13:31:09 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 26 13:31:09 volumio systemd[1]: Startup finished in 7.619s (kernel) + 29.615s (userspace) = 37.234s.
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: Setting RT Priority for mpd
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: pid 35's current scheduling policy: SCHED_OTHER
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: pid 35's current scheduling priority: 0
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: Setting MPD Affinity
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: pid 3's current affinity mask: f
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Apr 26 13:31:09 volumio volumio-cpu-tweak[1143]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative
Apr 26 13:31:09 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
Apr 26 13:31:09 volumio dhcpcd[716]: wlan0: soliciting an IPv6 router
Apr 26 13:31:09 volumio dhcpcd[716]: wlan0: soliciting a DHCP lease
Apr 26 13:31:09 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:09 volumio volumio[1142]: info: ----- Volumio3 ----
Apr 26 13:31:09 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:09 volumio volumio[1142]: info: ----- System startup ----
Apr 26 13:31:09 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:10 volumio volumio[1142]: info: MYVOLUMIO Environment detected
Apr 26 13:31:10 volumio volumio[1142]: info: Plugin folders cleanup
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning into folder /volumio/app/plugins/
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category audio_interface
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category miscellanea
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category music_service
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category plugins.json
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category system_controller
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category user_interface
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning into folder /data/plugins/
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category music_service
Apr 26 13:31:10 volumio volumio[1142]: info: Scanning category user_interface
Apr 26 13:31:10 volumio volumio[1142]: info: Plugin folders cleanup completed
Apr 26 13:31:10 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:10 volumio volumio[1142]: info: ----- Core plugins startup ----
Apr 26 13:31:10 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugins from folder /volumio/app/plugins/
Apr 26 13:31:10 volumio volumio[1142]: info: Adding plugin upnp to MyMusic Plugins
Apr 26 13:31:10 volumio volumio[1142]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 26 13:31:10 volumio volumio[1142]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugins from folder /data/plugins/
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "system"...
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "appearance"...
Apr 26 13:31:10 volumio ntpd[902]: Listen normally on 4 wlan0 192.168.211.1:123
Apr 26 13:31:10 volumio ntpd[902]: new interface(s) found: waking up resolver
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "network"...
Apr 26 13:31:10 volumio volumio[1142]: info: Refreshing Cached IP Addresses
Apr 26 13:31:10 volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 26 13:31:10 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "services"...
Apr 26 13:31:10 volumio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 26 13:31:10 volumio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:10 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "alsa_controller"...
Apr 26 13:31:10 volumio sudo[1176]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:10 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 26 13:31:10 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:10 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:10 volumio kernel: ieee80211 phy0: brcmf_vif_set_mgmt_ie: vndr ie set error : -52
Apr 26 13:31:10 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-52)
Apr 26 13:31:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "wizard"...
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "networkfs"...
Apr 26 13:31:10 volumio volumio[1142]: info: Starting Udev Watcher for removable devices
Apr 26 13:31:10 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/HIFI_Music /mnt/NAS/HIFI_Music
Apr 26 13:31:10 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:10 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/Music /mnt/NAS/Music
Apr 26 13:31:10 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:10 volumio volumio[1142]: info: Ignoring mount for partition: bootfs
Apr 26 13:31:10 volumio volumio[1142]: info: Ignoring mount for partition: rootfs
Apr 26 13:31:10 volumio volumio[1142]: info: Ignoring mount for partition: boot
Apr 26 13:31:10 volumio volumio[1142]: info: Ignoring mount for partition: volumio
Apr 26 13:31:10 volumio volumio[1142]: info: Ignoring mount for partition: volumio_data
Apr 26 13:31:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "volumio_command_line_client"...
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "upnp"...
Apr 26 13:31:10 volumio volumio[1142]: info: [1714109470834] Starting Upmpd Daemon
Apr 26 13:31:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "my_music"...
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "mpd"...
Apr 26 13:31:10 volumio kernel: Key type cifs.spnego registered
Apr 26 13:31:10 volumio kernel: Key type cifs.idmap registered
Apr 26 13:31:10 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 26 13:31:10 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\Music
Apr 26 13:31:10 volumio volumio[1142]: info: Loading plugin "upnp_browser"...
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "alarm-clock"...
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "airplay_emulation"...
Apr 26 13:31:11 volumio volumio[1142]: info: Starting Shairport Sync
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "last_100"...
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "webradio"...
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "i2s_dacs"...
Apr 26 13:31:11 volumio volumio[1142]: info: I2S DAC not set, start Auto-detection
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "volumiodiscovery"...
Apr 26 13:31:11 volumio volumio[1142]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 26 13:31:11 volumio node[1142]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 26 13:31:11 volumio node[1142]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 26 13:31:11 volumio volumio[1142]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 26 13:31:11 volumio volumio[1142]: *** WARNING *** For more information see
Apr 26 13:31:11 volumio volumio[1142]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 26 13:31:11 volumio volumio[1142]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 26 13:31:11 volumio volumio[1142]: *** WARNING *** For more information see
Apr 26 13:31:11 volumio node[1142]: *** WARNING *** For more information see
Apr 26 13:31:11 volumio node[1142]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 26 13:31:11 volumio node[1142]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 26 13:31:11 volumio node[1142]: *** WARNING *** For more information see
Apr 26 13:31:11 volumio volumio[1142]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 26 13:31:11 volumio volumio[1142]: info: Discovery: Started advertising with name: Volumio
Apr 26 13:31:11 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 26 13:31:11 volumio volumio[1142]: info: Plugin jellyfin is not enabled
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "spop"...
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "ytmusic"...
Apr 26 13:31:11 volumio volumio[1142]: info: Loading plugin "now_playing"...
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "outputs"...
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "albumart"...
Apr 26 13:31:12 volumio volumio[1142]: info: Plugin example_plugin is not enabled
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "inputs"...
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "updater_comm"...
Apr 26 13:31:12 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 26 13:31:12 volumio volumio[1142]: info: Plugin mpdemulation is not enabled
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "rest_api"...
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "websocket"...
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "RoonBridge"...
Apr 26 13:31:12 volumio volumio[1142]: Forking 3 albumart workers
Apr 26 13:31:12 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Apr 26 13:31:12 volumio volumio[1142]: info: Applying required configuration parameters for plugin RoonBridge
Apr 26 13:31:12 volumio volumio[1142]: info: Plugin volusonic is not enabled
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "lastfm"...
Apr 26 13:31:12 volumio volumio[1142]: Starting albumart workers
Apr 26 13:31:12 volumio volumio[1142]: Starting albumart workers
Apr 26 13:31:12 volumio volumio[1142]: Starting albumart workers
Apr 26 13:31:12 volumio volumio[1142]: info: Loading plugin "randomizer"...
Apr 26 13:31:13 volumio volumio[1142]: info: Applying required configuration parameters for plugin randomizer
Apr 26 13:31:13 volumio volumio[1142]: info: Loading i18n strings for locale zh
Apr 26 13:31:13 volumio volumio[1142]: Updating browse sources language
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::initPlayerControls
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: Express server listening on port 3000
Apr 26 13:31:13 volumio volumio[1142]: [Metrics] WebUI: 3s 403.19ms
Apr 26 13:31:13 volumio volumio[1142]: info: CoreStateMachine::resetVolumioState
Apr 26 13:31:13 volumio volumio[1142]: info: CoreStateMachine::getcurrentVolume
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::volumioRetrievevolume
Apr 26 13:31:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:13 volumio volumio[1142]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Apr 26 13:31:13 volumio volumio[1142]: wlan0 Interface doesn't support scanning : Invalid exchange
Apr 26 13:31:13 volumio volumio[1142]: info: Cannot use regular scanning, forcing with ap-force
Apr 26 13:31:13 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Apr 26 13:31:13 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:14 volumio volumio-remote-updater[663]: [2024-04-26 13:31:14] [connect] Successful connection
Apr 26 13:31:14 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 1
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 2
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 4
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 5
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 6
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 7
Apr 26 13:31:14 volumio volumio[1142]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 9
Apr 26 13:31:14 volumio volumio-remote-updater[663]: [2024-04-26 13:31:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714109474 101
Apr 26 13:31:14 volumio volumio[1142]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10
Apr 26 13:31:14 volumio volumio[1142]: info: Reloading queue from file
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: VolumeController:: Volume=undefined Mute =false
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::pushState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioRetrievevolume
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::setRepeat null single undefined
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::pushState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::setRandom null
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::pushState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState
Apr 26 13:31:14 volumio volumio[1142]: info: Setting Device type: Raspberry PI
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 26 13:31:14 volumio volumio[1142]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0
Apr 26 13:31:14 volumio volumio[1142]: info: VolumeController:: Volume=undefined Mute =false
Apr 26 13:31:14 volumio volumio[1142]: info: CoreStateMachine::pushState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState
Apr 26 13:31:14 volumio volumio[1142]: info: Discovery: adding 831a17cb-61e5-48a3-9035-e9ce96074a65
Apr 26 13:31:14 volumio volumio[1142]: info: Discovery: Found device Volumio
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: Discovery: this is already registered, 831a17cb-61e5-48a3-9035-e9ce96074a65
Apr 26 13:31:14 volumio volumio[1142]: info: Discovery: Found device Volumio
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: Received Get System Info
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 26 13:31:14 volumio volumio[1142]: info: Discovery: Getting this device information
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 26 13:31:14 volumio volumio[1142]: info: Received Get System Info
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 26 13:31:14 volumio volumio[1142]: info: Discovery: Getting this device information
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 26 13:31:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:14 volumio dhcpcd[716]: wlan0: probing for an IPv4LL address
Apr 26 13:31:14 volumio volumio[1142]: info: Listing playlists
Apr 26 13:31:15 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.22 UA: okhttp/4.9.2 Total Clients: 11
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 26 13:31:15 volumio volumio[1142]: info: Received Get System Info
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 26 13:31:15 volumio volumio[1142]: info: Discovery: Getting this device information
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 26 13:31:15 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:15 volumio volumio[1142]: info: Listing playlists
Apr 26 13:31:19 volumio dhcpcd[716]: wlan0: using IPv4LL address 169.254.190.232
Apr 26 13:31:19 volumio avahi-daemon[678]: Registering new address record for 169.254.190.232 on wlan0.IPv4.
Apr 26 13:31:19 volumio dhcpcd[716]: wlan0: adding route to 169.254.0.0/16
Apr 26 13:31:20 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 26 13:31:20 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:20 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:20 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 26 13:31:20 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:20 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:20 volumio sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 26 13:31:20 volumio sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 26 13:31:20 volumio sudo[1298]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:20 volumio volumio[1142]: info: Upmpdcli Daemon Started
Apr 26 13:31:20 volumio volumio[1142]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 26 13:31:21 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:21 volumio volumio[1142]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds
Apr 26 13:31:21 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:31:21 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:31:21 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\HIFI_Music
Apr 26 13:31:21 volumio ntpd[902]: Listen normally on 5 wlan0 169.254.190.232:123
Apr 26 13:31:21 volumio ntpd[902]: new interface(s) found: waking up resolver
Apr 26 13:31:24 volumio volumio[1142]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 26 13:31:26 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/Music /mnt/NAS/Music
Apr 26 13:31:26 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:30 volumio volumio[1142]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 26 13:31:31 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:31 volumio volumio[1142]: info: Cannot mount NAS HIFI_Music at system boot, trial number 1 ,retrying in 5 seconds
Apr 26 13:31:31 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:31:31 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:31:31 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\Music
Apr 26 13:31:36 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/HIFI_Music /mnt/NAS/HIFI_Music
Apr 26 13:31:36 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:40 volumio volumio[1142]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Apr 26 13:31:41 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:41 volumio volumio[1142]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds
Apr 26 13:31:41 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:31:41 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:31:41 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\HIFI_Music
Apr 26 13:31:43 volumio volumio[1142]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Apr 26 13:31:43 volumio volumio[1142]: info: Preparing to generate the ALSA configuration file
Apr 26 13:31:43 volumio volumio[1142]: info: Asound.conf file unchanged, so no further update is needed
Apr 26 13:31:43 volumio volumio[1142]: info: Output device has changed, restarting MPD
Apr 26 13:31:43 volumio sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 26 13:31:43 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: Output device has changed, restarting Shairport Sync
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:43 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 26 13:31:43 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 26 13:31:43 volumio volumio[1142]: info: ___________ START PLUGINS ___________
Apr 26 13:31:43 volumio volumio[1142]: info: ControllerMpd::onStart: Initializing MPD
Apr 26 13:31:43 volumio volumio[1142]: info: Creating MPD Configuration file
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:31:43 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 26 13:31:43 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:43 volumio volumio[1142]: info: [1714109503157] CoreMusicLibrary::Adding element 媒体服务器
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:43 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio systemd[1]: Listening on mpd.socket.
Apr 26 13:31:43 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 26 13:31:43 volumio systemd[1]: Starting Music Player Daemon...
Apr 26 13:31:43 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:43 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 26 13:31:43 volumio systemd[1]: mpd.service: Succeeded.
Apr 26 13:31:43 volumio systemd[1]: Stopped Music Player Daemon.
Apr 26 13:31:43 volumio systemd[1]: Starting Music Player Daemon...
Apr 26 13:31:43 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:43 volumio volumio[1142]: info: [1714109503208] CoreMusicLibrary::Adding element Last_100
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:43 volumio volumio[1142]: info: [1714109503209] CoreMusicLibrary::Adding element Webradio
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 26 13:31:43 volumio volumio[1142]: info: Creating Spotify config file
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:43 volumio volumio[1142]: info: [1714109503242] CoreMusicLibrary::Adding element YouTube Music
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:43 volumio volumio[1142]: Cannot find translation for source YouTube Music
Apr 26 13:31:43 volumio volumio[1142]: info: [now-playing] ConfigUpdater: config is up to date.
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:43 volumio volumio[1142]: info: [1714109503258] CoreMusicLibrary::Adding element LastFM
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:43 volumio volumio[1142]: Cannot find translation for source YouTube Music
Apr 26 13:31:43 volumio volumio[1142]: Cannot find translation for source LastFM
Apr 26 13:31:43 volumio volumio[1142]: info: [LastFM] scrobbler initiated!
Apr 26 13:31:43 volumio volumio[1142]: info: [LastFM] extended logging: false
Apr 26 13:31:43 volumio volumio[1142]: info: [LastFM] try scrobble stream/radio plays: true
Apr 26 13:31:43 volumio volumio[1142]: info: [LastFM] Left init routine
Apr 26 13:31:43 volumio volumio[1142]: info: [LastFM] Socket already connected: true
Apr 26 13:31:43 volumio sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service
Apr 26 13:31:43 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:43 volumio volumio[1142]: info: [1714109503270] CoreMusicLibrary::Adding element Randomizer
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:43 volumio volumio[1142]: Cannot find translation for source YouTube Music
Apr 26 13:31:43 volumio volumio[1142]: Cannot find translation for source LastFM
Apr 26 13:31:43 volumio volumio[1142]: Cannot find translation for source Randomizer
Apr 26 13:31:43 volumio volumio[1142]: info: Volumio Calling Home
Apr 26 13:31:43 volumio systemd[1]: Started RoonBridge.
Apr 26 13:31:43 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio volumio[1142]: info: [now-playing] App is listening on port 4004.
Apr 26 13:31:43 volumio volumio[1142]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Apr 26 13:31:43 volumio volumio[1142]: info: MPD Permissions set
Apr 26 13:31:43 volumio volumio[1142]: info: MPD Permissions set
Apr 26 13:31:43 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12
Apr 26 13:31:43 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13
Apr 26 13:31:43 volumio volumio[1142]: info: Spotify config file written
Apr 26 13:31:43 volumio sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Apr 26 13:31:43 volumio sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio systemd[1]: Started go-librespot Daemon.
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio go-librespot[1388]: Librespot-go daemon starting...
Apr 26 13:31:43 volumio sudo[1385]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:31:43 volumio volumio[1142]: info: Starting Shairport Sync
Apr 26 13:31:43 volumio volumio[1142]: info: Starting Shairport Sync
Apr 26 13:31:43 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 26 13:31:43 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio volumio[1142]: info: Starting Shairport Sync
Apr 26 13:31:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 26 13:31:43 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 26 13:31:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 26 13:31:43 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 26 13:31:43 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 26 13:31:43 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 26 13:31:43 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 26 13:31:43 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 26 13:31:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 26 13:31:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 26 13:31:43 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:43 volumio volumio[1142]: info: Shairport-Sync Started
Apr 26 13:31:43 volumio volumio[1142]: Error adding Membership: Error: addMembership EINVAL
Apr 26 13:31:43 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:43 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:43 volumio volumio[1142]: info: Shairport-Sync Started
Apr 26 13:31:43 volumio volumio[1142]: info: Shairport-Sync Started
Apr 26 13:31:43 volumio go-librespot[1388]: time="2024-04-26T13:31:43+08:00" level=info msg="generated new device id: a05ac1828d4507ce77937b2243f66a6ab0259c38"
Apr 26 13:31:43 volumio go-librespot[1388]: time="2024-04-26T13:31:43+08:00" level=debug msg="stored credentials found for 1247688853"
Apr 26 13:31:43 volumio volumio[1142]: info: [LastFM] finished init: Authenticated successfully!
Apr 26 13:31:43 volumio RoonBridge[1374]: 00:00:00.003 Warn: get lock file path: /tmp/.rnbgem0-
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.151 Trace: [childprocess] using unix child process
Apr 26 13:31:44 volumio mpd[1364]: Apr 26 13:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.174 Debug: PathForResource, filename: ../.update
Apr 26 13:31:44 volumio systemd[1]: Started Music Player Daemon.
Apr 26 13:31:44 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:44 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:44 volumio volumio[1142]: info: Completed starting Core Plugins
Apr 26 13:31:44 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:44 volumio volumio[1142]: info: ----- MyVolumio plugins startup ----
Apr 26 13:31:44 volumio volumio[1142]: info: -------------------------------------------
Apr 26 13:31:44 volumio volumio[1142]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 26 13:31:44 volumio volumio[1142]: info: MPD running with PID1364
Apr 26 13:31:44 volumio volumio[1142]: ,establishing connection
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.215 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.218 Debug: PathForResource, filename: RAATServer
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.218 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.218 Debug: PathForResource, filename: RoonBridgeHelper
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.218 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
Apr 26 13:31:44 volumio RoonBridge[1374]: Initializing
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.232 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
Apr 26 13:31:44 volumio volumio[1142]: error: MPD error: The expression evaluated to a falsy value:
Apr 26 13:31:44 volumio volumio[1142]: assert.ok(self.idling)
Apr 26 13:31:44 volumio volumio[1142]: error: The expression evaluated to a falsy value:
Apr 26 13:31:44 volumio volumio[1142]: assert.ok(self.idling)
Apr 26 13:31:44 volumio volumio[1142]: error: MPD error: The expression evaluated to a falsy value:
Apr 26 13:31:44 volumio volumio[1142]: assert.ok(self.idling)
Apr 26 13:31:44 volumio volumio[1142]: error: The expression evaluated to a falsy value:
Apr 26 13:31:44 volumio volumio[1142]: assert.ok(self.idling)
Apr 26 13:31:44 volumio volumio[1142]: error: updateQueue error: null
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.243 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
Apr 26 13:31:44 volumio RoonBridge[1374]: Not Running (.o)
Apr 26 13:31:44 volumio volumio[1142]: info: Volumio called home
Apr 26 13:31:44 volumio volumio[1142]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 26 13:31:44 volumio volumio[1142]: SPOTIFY: BQBtMWzBbSJYM3-aoWRFTGiOgYyuNyO1ppoFQP-8OH2wpikoEIboGmTFcBAko07ZAk5qlOh8U9CIbWQTawgG6crZ1O8BEyeYRzVMLdYoUZ_zXDUkt92xfS7BOaPBXeflUFU4qLdbXtspqKS45fvVptthW2r1EWXSoGw2h76rwzCzgWozpw7SUHmKXFAstmc73P2bBWHpVBLnZIXKDWl0pNFOa5wdx2pnEHVQRTeCiEnRwfg
Apr 26 13:31:44 volumio volumio[1142]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 26 13:31:44 volumio volumio[1142]: info: New Spotify access token = BQBtMWzBbSJYM3-aoWRFTGiOgYyuNyO1ppoFQP-8OH2wpikoEIboGmTFcBAko07ZAk5qlOh8U9CIbWQTawgG6crZ1O8BEyeYRzVMLdYoUZ_zXDUkt92xfS7BOaPBXeflUFU4qLdbXtspqKS45fvVptthW2r1EWXSoGw2h76rwzCzgWozpw7SUHmKXFAstmc73P2bBWHpVBLnZIXKDWl0pNFOa5wdx2pnEHVQRTeCiEnRwfg
Apr 26 13:31:44 volumio volumio[1142]: info: Spotify credentials grant success - running version from March 24, 2019
Apr 26 13:31:44 volumio RoonBridge[1374]: Running
Apr 26 13:31:44 volumio RoonBridge[1374]: 00:00:00.008 Warn: get lock file path: /tmp/.rnbhgem0-
Apr 26 13:31:44 volumio go-librespot[1388]: time="2024-04-26T13:31:44+08:00" level=debug msg="obtained new client token: AACoDOgYPhz4cwvcHmsdFukXn5uSmS4qDyKkEEF9hEgwQl5UNFbgEj0yH26JfYskh6L0eDj2FPbQPWMHUGDpl+EHqQNmAQnaQmIrJxDQMAN3CRz9p0NtzFLmi79EhVfHdsG3tzdEMY14dqlP1WBTfueR31D5qnbHTC04H2vEt+m9qJX9sW5lDI9fQ7FP+D0/UoJD7NifyMWYviRj6zWG2x/Elh8wRvaCxEBJaiLVaAyv2M9AhfAty9uF4sjEtl4="
Apr 26 13:31:45 volumio volumio[1142]: SPOTIFY: User informations: {"display_name":"tiki","external_urls":{"spotify":"https://open.spotify.com/user/1247688853"},"href":"https://api.spotify.com/v1/users/1247688853","id":"1247688853","images":[{"url":"https://scontent-iad3-1.xx.fbcdn.net/v/t1.18169-1/37956_1467453524870_940372_n.jpg?stp=c7.7.84.84a_cp0_dst-jpg_s50x50&_nc_cat=103&ccb=1-7&_nc_sid=5f2048&_nc_ohc=ZGriEx2iJHMQ7kNvgFtn-y0&_nc_ht=scontent-iad3-1.xx&edm=AP4hL3IEAAAA&oh=00_AfCgLvRO2G0P5Rvy33sz4phpl0G1ZVMN9pRbKA-LWp5hXQ&oe=6652C23F","height":64,"width":64},{"url":"https://scontent-iad3-1.xx.fbcdn.net/v/t1.18169-1/37956_1467453524870_940372_n.jpg?stp=c7.7.84.84a_cp0_dst-jpg&_nc_cat=103&ccb=1-7&_nc_sid=5f2048&_nc_ohc=ZGriEx2iJHMQ7kNvgFtn-y0&_nc_ht=scontent-iad3-1.xx&edm=AP4hL3IEAAAA&oh=00_AfAaMnGgZ1qc2vSzxavr3X0xlbdBJjIa0-iP-uz5YXON7A&oe=6652C23F","height":300,"width":300}],"type":"user","uri":"spotify:user:1247688853","followers":{"href":null,"total":0},"country":"JP","product":"free","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"tiki2009@gmail.com"}
Apr 26 13:31:45 volumio volumio[1142]: info: Spotify Successfully logged in
Apr 26 13:31:45 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 26 13:31:45 volumio volumio[1142]: info: [1714109505517] CoreMusicLibrary::Adding element Spotify
Apr 26 13:31:45 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:45 volumio volumio[1142]: Cannot find translation for source YouTube Music
Apr 26 13:31:45 volumio volumio[1142]: Cannot find translation for source LastFM
Apr 26 13:31:45 volumio volumio[1142]: Cannot find translation for source Randomizer
Apr 26 13:31:45 volumio volumio[1142]: Cannot find translation for source Spotify
Apr 26 13:31:45 volumio go-librespot[1388]: time="2024-04-26T13:31:45+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Apr 26 13:31:45 volumio go-librespot[1388]: time="2024-04-26T13:31:45+08:00" level=debug msg="completed keyexchange"
Apr 26 13:31:46 volumio go-librespot[1388]: time="2024-04-26T13:31:46+08:00" level=debug msg="completed challenge"
Apr 26 13:31:46 volumio volumio[1142]: info: go-librespot daemon successfully initialized
Apr 26 13:31:46 volumio go-librespot[1388]: time="2024-04-26T13:31:46+08:00" level=debug msg="authenticated as 1247688853"
Apr 26 13:31:46 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/Music /mnt/NAS/Music
Apr 26 13:31:46 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:48 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 26 13:31:48 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:48 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:48 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 26 13:31:48 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:48 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:48 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 14
Apr 26 13:31:48 volumio sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 26 13:31:48 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:48 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:48 volumio sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 26 13:31:48 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:48 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:48 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.5.29 from 192.168.5.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 15
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:48 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue
Apr 26 13:31:48 volumio volumio[1142]: info: CoreStateMachine::getQueue
Apr 26 13:31:48 volumio volumio[1142]: info: CorePlayQueue::getQueue
Apr 26 13:31:48 volumio volumio[1142]: info: Listing playlists
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 26 13:31:48 volumio volumio[1142]: info: Received Get System Info
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 26 13:31:48 volumio volumio[1142]: info: Discovery: Getting this device information
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:48 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:48 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 26 13:31:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 26 13:31:48 volumio ntpd[902]: Soliciting pool server 84.16.73.33
Apr 26 13:31:49 volumio go-librespot[1388]: time="2024-04-26T13:31:49+08:00" level=debug msg="authenticated as 1247688853"
Apr 26 13:31:49 volumio volumio[1142]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 26 13:31:49 volumio go-librespot[1388]: time="2024-04-26T13:31:49+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Apr 26 13:31:49 volumio volumio[1142]: info: Initializing connection to go-librespot Websocket
Apr 26 13:31:49 volumio go-librespot[1388]: time="2024-04-26T13:31:49+08:00" level=debug msg="new websocket client"
Apr 26 13:31:49 volumio volumio[1142]: info: Connection to go-librespot Websocket established
Apr 26 13:31:49 volumio ntpd[902]: Soliciting pool server 5.79.108.34
Apr 26 13:31:49 volumio ntpd[902]: Soliciting pool server 202.112.29.82
Apr 26 13:31:49 volumio ntpd[902]: Soliciting pool server 202.112.31.197
Apr 26 13:31:49 volumio go-librespot[1388]: time="2024-04-26T13:31:49+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Apr 26 13:31:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 26 13:31:50 volumio volumio[1142]: info: Received Get System Info
Apr 26 13:31:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 26 13:31:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 26 13:31:50 volumio volumio[1142]: info: Discovery: Getting this device information
Apr 26 13:31:50 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 26 13:31:50 volumio ntpd[902]: Soliciting pool server 139.199.214.202
Apr 26 13:31:50 volumio ntpd[902]: Soliciting pool server 211.68.71.26
Apr 26 13:31:50 volumio ntpd[902]: Soliciting pool server 84.16.67.12
Apr 26 13:31:51 volumio ntpd[902]: Soliciting pool server 111.230.189.174
Apr 26 13:31:51 volumio ntpd[902]: Soliciting pool server 139.199.215.251
Apr 26 13:31:51 volumio ntpd[902]: Soliciting pool server 95.111.202.5
Apr 26 13:31:51 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 26 13:31:51 volumio volumio[1142]: info: CURURI: music-library
Apr 26 13:31:51 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:51 volumio volumio[1142]: info: Preload queue cleared
Apr 26 13:31:51 volumio volumio[1142]: info: Cannot mount NAS HIFI_Music at system boot, trial number 2 ,retrying in 5 seconds
Apr 26 13:31:51 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:31:51 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:31:51 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\Music
Apr 26 13:31:51 volumio go-librespot[1388]: time="2024-04-26T13:31:51+08:00" level=debug msg="dealer connection opened"
Apr 26 13:31:52 volumio go-librespot[1388]: time="2024-04-26T13:31:51+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Apr 26 13:31:52 volumio go-librespot[1388]: time="2024-04-26T13:31:51+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Apr 26 13:31:52 volumio go-librespot[1388]: time="2024-04-26T13:31:51+08:00" level=debug msg="received connection id: M2IzOGVmN2YtNzY1OS00YjMzLTkwMWEtMjE0ZmY5MWI0YjQxK2RlYWxlcit0Y3A6Ly9nYWUyLWRlYWxlci1jYW5hcnktcTM5eC5nYWUyLnNwb3RpZnkubmV0OjU3MDArNEE4NDVDREU2NDIxMjI1NDYwQ0JDMDVCRUMxMzI2OTkwMTlFQUJEQTRFOUNEN0I1RjM5QjZEQzU2QTVDQUE5RQ=="
Apr 26 13:31:52 volumio volumio[1142]: info: Getting Spotify volume
Apr 26 13:31:52 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16
Apr 26 13:31:52 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:52 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:52 volumio ntpd[902]: Soliciting pool server 78.46.102.180
Apr 26 13:31:52 volumio ntpd[902]: Soliciting pool server 116.203.151.74
Apr 26 13:31:53 volumio go-librespot[1388]: time="2024-04-26T13:31:53+08:00" level=debug msg="put connect state because NEW_DEVICE"
Apr 26 13:31:53 volumio go-librespot[1388]: time="2024-04-26T13:31:53+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Apr 26 13:31:53 volumio go-librespot[1388]: time="2024-04-26T13:31:53+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Apr 26 13:31:53 volumio go-librespot[1388]: time="2024-04-26T13:31:53+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513"
Apr 26 13:31:53 volumio volumio[1142]: info: Spotify volume: 100
Apr 26 13:31:53 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 26 13:31:53 volumio volumio[1142]: info: CURURI: music-library/NAS
Apr 26 13:31:53 volumio volumio[1142]: info: Preload queue cleared
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 26 13:31:53 volumio volumio[1142]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 26 13:31:53 volumio volumio[1142]: info: Adding plugin multiroom to MyMusic Plugins
Apr 26 13:31:53 volumio volumio[1142]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 26 13:31:53 volumio volumio[1142]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 26 13:31:53 volumio volumio[1142]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 26 13:31:53 volumio volumio[1142]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 26 13:31:53 volumio ntpd[902]: Soliciting pool server 2001:470:1d:281::123
Apr 26 13:31:53 volumio ntpd[902]: Soliciting pool server 108.59.2.24
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 26 13:31:53 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:53 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:31:53 volumio volumio[1142]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 26 13:31:53 volumio volumio[1142]: info: MyVolumio login type: Token
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 26 13:31:53 volumio volumio[1142]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 26 13:31:54 volumio volumio[1142]: info: Starting Streaming Service Transparent Proxy
Apr 26 13:31:54 volumio volumio[1142]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 26 13:31:54 volumio volumio[1142]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 26 13:31:54 volumio volumio[1142]: info: Streaming services startup
Apr 26 13:31:54 volumio volumio[1142]: info: Starting Streaming Daemon
Apr 26 13:31:54 volumio sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 26 13:31:54 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:54 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root
Apr 26 13:31:54 volumio volumio[1142]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 26 13:31:54 volumio volumio[1142]: error: Cannot start Volumio Streaming Daemon
Apr 26 13:31:54 volumio volumio[1142]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 26 13:31:54 volumio volumio[1142]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 26 13:31:54 volumio volumio[1142]: STREAMING PROXY: Starting server on port 3245
Apr 26 13:31:54 volumio volumio[1142]: Node JS runtime: 14
Apr 26 13:31:56 volumio ntpd[902]: receive: Unexpected origin timestamp 0xe9d5bacc.99c6e369 does not match aorg 0000000000.00000000 from server@202.112.31.197 xmt 0xe9d5bacc.ccb93376
Apr 26 13:31:56 volumio ntpd[902]: receive: Unexpected origin timestamp 0xe9d5bacc.99c42e56 does not match aorg 0000000000.00000000 from server@139.199.215.251 xmt 0xe9d5bacc.cea08812
Apr 26 13:31:56 volumio ntpd[902]: receive: Unexpected origin timestamp 0xe9d5bacc.99c35d4c does not match aorg 0000000000.00000000 from server@95.111.202.5 xmt 0xe9d5bacc.d9816734
Apr 26 13:31:56 volumio ntpd[902]: receive: Unexpected origin timestamp 0xe9d5bacc.99c21042 does not match aorg 0000000000.00000000 from server@116.203.151.74 xmt 0xe9d5bacc.e0d0237f
Apr 26 13:31:56 volumio ntpd[902]: receive: Unexpected origin timestamp 0xe9d5bacc.99c5be31 does not match aorg 0000000000.00000000 from server@5.79.108.34 xmt 0xe9d5bacc.df475343
Apr 26 13:31:57 volumio ntpd[902]: receive: Unexpected origin timestamp 0xe9d5bacc.99bef72f does not match aorg 0000000000.00000000 from server@108.59.2.24 xmt 0xe9d5bacc.ed43f82d
Apr 26 13:31:57 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/HIFI_Music /mnt/NAS/HIFI_Music
Apr 26 13:31:57 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:31:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 26 13:31:57 volumio volumio[1142]: info: Received Get System Info
Apr 26 13:31:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 26 13:31:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 26 13:31:57 volumio volumio[1142]: info: Discovery: Getting this device information
Apr 26 13:31:57 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:31:57 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:31:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 26 13:31:58 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Apr 26 13:31:58 volumio volumio[1142]: info: MyVolumio login type: Token
Apr 26 13:31:59 volumio volumio[1300]: Generating RSA private key, 4096 bit long modulus (2 primes)
Apr 26 13:32:00 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:32:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:32:00 volumio volumio[1300]: ....................++++
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 26 13:32:01 volumio volumio[1142]: info: Retrieving Cloud Streaming UI
Apr 26 13:32:01 volumio volumio[1142]: info: Getting Tidal Cloud Configuration
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 26 13:32:01 volumio volumio[1142]: info: Getting Qobuz Cloud Configuration
Apr 26 13:32:01 volumio volumio[1142]: info: Asking plugin for UI Config
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 26 13:32:01 volumio volumio[1142]: info: Getting Spotify Cloud Configuration
Apr 26 13:32:01 volumio volumio[1142]: info: Asking plugin for UI Config
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 26 13:32:01 volumio volumio[1142]: info: Saving Spotify Acccount
Apr 26 13:32:01 volumio volumio[1142]: info: Got Tidal Cloud Configuration
Apr 26 13:32:01 volumio volumio[1142]: info: Got it
Apr 26 13:32:01 volumio volumio[1142]: info: Got it
Apr 26 13:32:01 volumio volumio[1142]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 26 13:32:01 volumio volumio[1142]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:01 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 26 13:32:02 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root
Apr 26 13:32:02 volumio volumio[1142]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds
Apr 26 13:32:02 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:32:02 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:32:02 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\HIFI_Music
Apr 26 13:32:05 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:05 volumio volumio[1300]: ..........................................................................................................++++
Apr 26 13:32:05 volumio volumio[1300]: e is 65537 (0x010001)
Apr 26 13:32:05 volumio volumio[1300]: writing RSA key
Apr 26 13:32:07 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/Music /mnt/NAS/Music
Apr 26 13:32:07 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:32:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 26 13:32:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:09 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 17
Apr 26 13:32:09 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState
Apr 26 13:32:09 volumio volumio[1142]: info: CorePlayQueue::getTrack 0
Apr 26 13:32:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare
Apr 26 13:32:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 26 13:32:12 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root
Apr 26 13:32:12 volumio volumio[1142]: info: Cannot mount NAS HIFI_Music at system boot, trial number 3 ,retrying in 5 seconds
Apr 26 13:32:12 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:32:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:32:12 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\Music
Apr 26 13:32:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 26 13:32:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:32:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 26 13:32:14 volumio volumio[1142]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 26 13:32:14 volumio volumio[1142]: info: Completed starting MyVolumio Plugin
Apr 26 13:32:14 volumio volumio[1142]: info: BOOT COMPLETED
Apr 26 13:32:14 volumio volumio[1142]: [Metrics] CommandRouter: 64s 295.44ms
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent
Apr 26 13:32:14 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent
Apr 26 13:32:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 26 13:32:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 26 13:32:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 26 13:32:17 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:17 volumio sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tiki,password=wszky,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/HIFI_Music /mnt/NAS/HIFI_Music
Apr 26 13:32:17 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 26 13:32:18 volumio volumio[1142]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object]
Apr 26 13:32:18 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions
Apr 26 13:32:21 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:22 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root
Apr 26 13:32:22 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 26 13:32:22 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 26 13:32:22 volumio kernel: CIFS: Attempting to mount \\192.168.1.253\HIFI_Music
Apr 26 13:32:22 volumio volumio[1142]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds
Apr 26 13:32:24 volumio volumio[1142]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
Apr 26 13:32:24 volumio volumio[1142]: info: Retrying Login Due to Network Error
Apr 26 13:32:25 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 26 13:32:26 volumio volumio-remote-updater[663]: No test mode
Apr 26 13:32:26 volumio volumio-remote-updater[663]: No alpha test mode
Apr 26 13:32:26 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 26 13:32:26 volumio volumio[1142]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Apr 26 13:32:26 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 26 13:32:27 volumio volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 26 13:32:27 volumio volumio[1142]: TypeError: Cannot read property 'replace' of undefined
Apr 26 13:32:27 volumio volumio[1142]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28)
Apr 26 13:32:27 volumio volumio[1142]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20)
Apr 26 13:32:27 volumio volumio[1142]: at listOnTimeout (internal/timers.js:554:17)
Apr 26 13:32:27 volumio volumio[1142]: at processTimers (internal/timers.js:497:7)
Apr 26 13:32:27 volumio volumio[1142]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 26 13:32:28 volumio sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 13:31
Apr 26 13:32:28 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"