-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2024-11-25 13:45:23 CET. -- Nov 25 13:45:00 rpi5-k662 systemd[1]: Starting Daily apt download activities... Nov 25 13:45:00 rpi5-k662 systemd[1]: Starting Daily man-db regeneration... Nov 25 13:45:00 rpi5-k662 systemd-timedated[1294]: Changed local time to Mon Nov 25 13:45:00 2024 Nov 25 13:45:00 rpi5-k662 sudo[1292]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:00 rpi5-k662 volumio-time-update[927]: volumio-time-update-util: System time updated successfully. Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Volumio Time Update Utility. Nov 25 13:45:00 rpi5-k662 systemd[1]: man-db.service: Succeeded. Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Daily man-db regeneration. Nov 25 13:45:00 rpi5-k662 dhcpcd[1193]: wlan0: leased 192.168.1.52 for 604800 seconds Nov 25 13:45:00 rpi5-k662 dhcpcd[1193]: wlan0: adding route to 192.168.1.0/24 Nov 25 13:45:00 rpi5-k662 dhcpcd[1193]: wlan0: adding default route via 192.168.1.1 Nov 25 13:45:00 rpi5-k662 avahi-daemon[939]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.52. Nov 25 13:45:00 rpi5-k662 avahi-daemon[939]: New relevant interface wlan0.IPv4 for mDNS. Nov 25 13:45:00 rpi5-k662 avahi-daemon[939]: Registering new address record for 192.168.1.52 on wlan0.IPv4. Nov 25 13:45:00 rpi5-k662 wireless.js[930]: trying... Nov 25 13:45:00 rpi5-k662 sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 25 13:45:00 rpi5-k662 sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:00 rpi5-k662 sudo[1340]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:00 rpi5-k662 wireless.js[930]: Connected to: ----WieFie_5GHz Nov 25 13:45:00 rpi5-k662 wireless.js[930]: ---- Nov 25 13:45:00 rpi5-k662 sudo[1343]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 13:45:00 rpi5-k662 sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:00 rpi5-k662 sudo[1343]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:00 rpi5-k662 wireless.js[930]: ... joined AP, wlan0 IPv4 is 192.168.1.52, ipV6 is undefined Nov 25 13:45:00 rpi5-k662 wireless.js[930]: It's done! AP Nov 25 13:45:00 rpi5-k662 nmbd[1036]: [2024/11/25 13:45:00.686964, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 25 13:45:00 rpi5-k662 nmbd[1036]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Samba NMB Daemon. Nov 25 13:45:00 rpi5-k662 systemd[1]: Starting Samba Winbind Daemon... Nov 25 13:45:00 rpi5-k662 nmbd[1036]: [2024/11/25 13:45:00.709461, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 25 13:45:00 rpi5-k662 nmbd[1036]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.52 for name WORKGROUP<1d>. Nov 25 13:45:00 rpi5-k662 nmbd[1036]: This response was from IP 192.168.1.200, reporting an IP address of 192.168.1.200. Nov 25 13:45:00 rpi5-k662 nmbd[1036]: [2024/11/25 13:45:00.709584, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 25 13:45:00 rpi5-k662 nmbd[1036]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.98 for name WORKGROUP<1d>. Nov 25 13:45:00 rpi5-k662 nmbd[1036]: This response was from IP 192.168.1.200, reporting an IP address of 192.168.1.200. Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Wireless Services. Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Volumio Backend Module. Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Volumio Cpu Tweaker. Nov 25 13:45:00 rpi5-k662 volumio-cpu-tweak[1352]: Setting RT Priority for mpd Nov 25 13:45:00 rpi5-k662 volumio-cpu-tweak[1352]: Setting MPD Affinity Nov 25 13:45:00 rpi5-k662 volumio-cpu-tweak[1352]: pid 1070's current affinity mask: f Nov 25 13:45:00 rpi5-k662 volumio-cpu-tweak[1352]: pid 1070's new affinity mask: 3 Nov 25 13:45:00 rpi5-k662 volumio-cpu-tweak[1352]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 25 13:45:00 rpi5-k662 volumio-cpu-tweak[1352]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Nov 25 13:45:00 rpi5-k662 winbindd[1349]: [2024/11/25 13:45:00.815654, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 25 13:45:00 rpi5-k662 winbindd[1349]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 25 13:45:00 rpi5-k662 systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 25 13:45:00 rpi5-k662 winbindd[1349]: [2024/11/25 13:45:00.866652, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 25 13:45:00 rpi5-k662 winbindd[1349]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Samba Winbind Daemon. Nov 25 13:45:00 rpi5-k662 systemd[1]: Starting Samba SMB Daemon... Nov 25 13:45:00 rpi5-k662 systemd[1]: apt-daily.service: Succeeded. Nov 25 13:45:00 rpi5-k662 systemd[1]: Started Daily apt download activities. Nov 25 13:45:01 rpi5-k662 systemd[1]: Starting Daily apt upgrade and clean activities... Nov 25 13:45:01 rpi5-k662 smbd[1378]: [2024/11/25 13:45:01.137413, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 25 13:45:01 rpi5-k662 systemd[1]: Started Samba SMB Daemon. Nov 25 13:45:01 rpi5-k662 smbd[1378]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 25 13:45:01 rpi5-k662 systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 25 13:45:01 rpi5-k662 systemd[1]: Started Daily apt upgrade and clean activities. Nov 25 13:45:01 rpi5-k662 volumio[1351]: info: ------------------------------------------- Nov 25 13:45:01 rpi5-k662 volumio[1351]: info: ----- Volumio3 ---- Nov 25 13:45:01 rpi5-k662 volumio[1351]: info: ------------------------------------------- Nov 25 13:45:01 rpi5-k662 volumio[1351]: info: ----- System startup ---- Nov 25 13:45:01 rpi5-k662 volumio[1351]: info: ------------------------------------------- Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: MYVOLUMIO Environment detected Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Plugin folders cleanup Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning into folder /volumio/app/plugins/ Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category audio_interface Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category miscellanea Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category music_service Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category plugins.json Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category system_controller Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category user_interface Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning into folder /data/plugins/ Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category music_service Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category system_hardware Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Scanning category user_interface Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Plugin folders cleanup completed Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: ------------------------------------------- Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: ----- Core plugins startup ---- Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: ------------------------------------------- Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Adding plugin upnp to MyMusic Plugins Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugins from folder /data/plugins/ Nov 25 13:45:02 rpi5-k662 ntpd[1065]: Listen normally on 4 wlan0 192.168.1.52:123 Nov 25 13:45:02 rpi5-k662 ntpd[1065]: new interface(s) found: waking up resolver Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "system"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "appearance"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "network"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Refreshing Cached IP Addresses Nov 25 13:45:02 rpi5-k662 sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 13:45:02 rpi5-k662 sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:02 rpi5-k662 sudo[1473]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:02 rpi5-k662 sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 13:45:02 rpi5-k662 sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:02 rpi5-k662 sudo[1475]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "services"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "alsa_controller"... Nov 25 13:45:02 rpi5-k662 sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 13:45:02 rpi5-k662 sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:02 rpi5-k662 volumio[1351]: warn: Unable to locate the audio output device Pre Box S2 Digital. Please configure a valid output device. Nov 25 13:45:02 rpi5-k662 volumio[1351]: Invalid card number. Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 25 13:45:02 rpi5-k662 volumio[1351]: Invalid card number. Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "wizard"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "networkfs"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Starting Udev Watcher for removable devices Nov 25 13:45:02 rpi5-k662 sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=multimedia,password=123Boydd,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/Music /mnt/NAS/M1S Nov 25 13:45:02 rpi5-k662 sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Ignoring mount for partition: boot Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Ignoring mount for partition: volumio Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Ignoring mount for partition: volumio_data Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "volumio_command_line_client"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "upnp"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: [1732538702828] Starting Upmpd Daemon Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "my_music"... Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "mpd"... Nov 25 13:45:02 rpi5-k662 kernel: Key type cifs.spnego registered Nov 25 13:45:02 rpi5-k662 kernel: Key type cifs.idmap registered Nov 25 13:45:02 rpi5-k662 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. Nov 25 13:45:02 rpi5-k662 kernel: CIFS: Attempting to mount //192.168.1.15/Music Nov 25 13:45:02 rpi5-k662 volumio[1351]: info: Loading plugin "upnp_browser"... Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "alarm-clock"... Nov 25 13:45:03 rpi5-k662 volumio-remote-updater[932]: [2024-11-25 13:45:03] [connect] Successful connection Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "airplay_emulation"... Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Starting Shairport Sync Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "last_100"... Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "webradio"... Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "i2s_dacs"... Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "volumiodiscovery"... Nov 25 13:45:03 rpi5-k662 volumio[1351]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 13:45:03 rpi5-k662 volumio[1351]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 13:45:03 rpi5-k662 volumio[1351]: *** WARNING *** For more information see Nov 25 13:45:03 rpi5-k662 volumio[1351]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 13:45:03 rpi5-k662 volumio[1351]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 13:45:03 rpi5-k662 node[1351]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 13:45:03 rpi5-k662 volumio[1351]: *** WARNING *** For more information see Nov 25 13:45:03 rpi5-k662 node[1351]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 13:45:03 rpi5-k662 node[1351]: *** WARNING *** For more information see Nov 25 13:45:03 rpi5-k662 node[1351]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 13:45:03 rpi5-k662 node[1351]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 13:45:03 rpi5-k662 node[1351]: *** WARNING *** For more information see Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Discovery: Started advertising with name: rPi5-K662 Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 13:45:03 rpi5-k662 volumio[1351]: info: Loading plugin "spop"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "peppy_screensaver"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin peppy_screensaver Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "outputs"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "albumart"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Plugin example_plugin is not enabled Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "inputs"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "updater_comm"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Plugin mpdemulation is not enabled Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "rest_api"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: Forking 3 albumart workers Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "websocket"... Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Starting Socket.io Server version 2.3.0 Nov 25 13:45:04 rpi5-k662 volumio[1351]: info: Loading plugin "80s80s"... Nov 25 13:45:05 rpi5-k662 volumio[1351]: Starting albumart workers Nov 25 13:45:05 rpi5-k662 volumio[1351]: Starting albumart workers Nov 25 13:45:05 rpi5-k662 volumio[1351]: Starting albumart workers Nov 25 13:45:05 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin 80s80s Nov 25 13:45:05 rpi5-k662 volumio[1351]: info: [1732538705254] [80s80s] API delay: 30 Nov 25 13:45:05 rpi5-k662 volumio[1351]: info: Loading plugin "plexamp"... Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin plexamp Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Loading plugin "radio_paradise"... Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin radio_paradise Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: [1732538706359] [RadioParadise] API delay: 5 Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Loading plugin "gpio_control"... Nov 25 13:45:06 rpi5-k662 volumio[1351]: gpiox_arm32 V2.1.0 Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin gpio_control Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Loading plugin "ir_controller"... Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin ir_controller Nov 25 13:45:06 rpi5-k662 volumio[1351]: info: Loading plugin "iractivityled"... Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin iractivityled Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: [IrActivityLed] Initialized Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: Loading plugin "mpd_oled"... Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin mpd_oled Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: Loading plugin "rotaryencoder2"... Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: Loading plugin "Systeminfo"... Nov 25 13:45:07 rpi5-k662 volumio[1351]: info: Loading plugin "randomizer"... Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin randomizer Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Loading plugin "touch_display"... Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Applying required configuration parameters for plugin touch_display Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Loading i18n strings for locale en Nov 25 13:45:08 rpi5-k662 volumio[1351]: Updating browse sources language Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::initPlayerControls Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: Express server listening on port 3000 Nov 25 13:45:08 rpi5-k662 volumio[1351]: [Metrics] WebUI: 7s 0.50ms Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::resetVolumioState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::getcurrentVolume Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::getcurrentVolume Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Volumio Network Manager: Network status updated: 2 Nov 25 13:45:08 rpi5-k662 volumio[1351]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 25 13:45:08 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.93 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 25 13:45:08 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: VolumeController:: Volume=undefined Mute =false Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::pushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioPushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::updateTrackBlock Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrackBlock Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 13:45:08 rpi5-k662 volumio[1351]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Getting this device information Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 13:45:08 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 25 13:45:08 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 25 13:45:08 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 25 13:45:08 rpi5-k662 volumio[1351]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Reloading queue from file Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: VolumeController:: Volume=undefined Mute =false Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::pushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioPushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::updateTrackBlock Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrackBlock Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::setRepeat false single undefined Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::pushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioPushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::setRandom true Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::pushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioPushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Setting Device type: Raspberry PI Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: VolumeController:: Volume=undefined Mute =false Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::pushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioPushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: VolumeController:: Volume=undefined Mute =false Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreStateMachine::pushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioPushState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: adding 2c8a5fee-0f75-443c-a0ed-a32b57cbd0df Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Found device rPi5-K662 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to 192.168.1.98:3000 from 192.168.1.54 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: this is already registered, 2c8a5fee-0f75-443c-a0ed-a32b57cbd0df Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Found device rPi5-K662 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 25 13:45:08 rpi5-k662 systemd[1]: systemd-fsckd.service: Succeeded. Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: adding 656f8e39-ea8c-4013-94ab-63d770127dd9 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Found device Primo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Connecting to remote: 192.168.1.93 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: this is already registered, 656f8e39-ea8c-4013-94ab-63d770127dd9 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Found device Primo Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Connecting to remote: 192.168.1.93 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: adding 3d7d7238-6dcc-4fde-9109-73cb2d81ab6e Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Found device rPi4-WS-DSI Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Connecting to remote: 192.168.1.32 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: this is already registered, 3d7d7238-6dcc-4fde-9109-73cb2d81ab6e Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Found device rPi4-WS-DSI Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Connecting to remote: 192.168.1.32 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Connected to remote: 192.168.1.93 Nov 25 13:45:08 rpi5-k662 volumio[1351]: info: Discovery: Connected to remote: 192.168.1.32 Nov 25 13:45:09 rpi5-k662 sudo[1507]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Cannot mount NAS M1S at system boot, trial number 1 ,retrying in 5 seconds Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Completed loading Core Plugins Nov 25 13:45:09 rpi5-k662 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 25 13:45:09 rpi5-k662 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Preparing to generate the ALSA configuration file Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Reading ALSA contributions from plugins. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Asound.conf file unchanged, so no further update is needed Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Output device has changed, restarting MPD Nov 25 13:45:09 rpi5-k662 sh[754]: timed out Nov 25 13:45:09 rpi5-k662 dhcpcd[805]: timed out Nov 25 13:45:09 rpi5-k662 sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 13:45:09 rpi5-k662 sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Output device has changed, restarting Shairport Sync Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 sh[754]: dhcpcd exited Nov 25 13:45:09 rpi5-k662 dhcpcd[805]: dhcpcd exited Nov 25 13:45:09 rpi5-k662 sh[754]: ifup: failed to bring up eth0 Nov 25 13:45:09 rpi5-k662 sudo[1603]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 25 13:45:09 rpi5-k662 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 25 13:45:09 rpi5-k662 sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 13:45:09 rpi5-k662 sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 systemd[1]: Stopping Music Player Daemon... Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: ___________ START PLUGINS ___________ Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: ControllerMpd::onStart: Initializing MPD Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Creating MPD Configuration file Nov 25 13:45:09 rpi5-k662 sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 13:45:09 rpi5-k662 sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709102] CoreMusicLibrary::Adding element Media Servers Nov 25 13:45:09 rpi5-k662 sudo[1613]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 13:45:09 rpi5-k662 sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709144] CoreMusicLibrary::Adding element Last_100 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709145] CoreMusicLibrary::Adding element Webradio Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Initializing BBC Radios Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Creating Spotify config file Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Loading i18n strings for locale en Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: /tmp/myfifo created Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: /tmp/myfifosa created Nov 25 13:45:09 rpi5-k662 sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Nov 25 13:45:09 rpi5-k662 sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1639]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: snd-dummy loaded Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 systemd[1]: mpd.service: Succeeded. Nov 25 13:45:09 rpi5-k662 systemd[1]: Stopped Music Player Daemon. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 sudo[1660]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/date -s Mon, 25 Nov 2024 12:45:09 GMT#015 Nov 25 13:45:09 rpi5-k662 sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 systemd[1]: Starting Music Player Daemon... Nov 25 13:45:09 rpi5-k662 bash[947]: Mon Nov 25 13:45:09 CET 2024 Nov 25 13:45:09 rpi5-k662 sudo[1660]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 systemd[1]: setdatetime.service: Succeeded. Nov 25 13:45:09 rpi5-k662 systemd[1]: Started Set local time using time server. Nov 25 13:45:09 rpi5-k662 systemd[1]: Reached target Multi-User System. Nov 25 13:45:09 rpi5-k662 systemd[1]: Reached target Graphical Interface. Nov 25 13:45:09 rpi5-k662 systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 13:45:09 rpi5-k662 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 25 13:45:09 rpi5-k662 systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 25 13:45:09 rpi5-k662 sudo[1658]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 13:45:09 rpi5-k662 sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709035] CoreMusicLibrary::Adding element 80s80s Radio Nov 25 13:45:09 rpi5-k662 sudo[1658]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Loading i18n strings for locale en Nov 25 13:45:09 rpi5-k662 volumio[1351]: Updating browse sources language Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709086] CoreMusicLibrary::Adding element Radio Paradise Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Radio Paradise Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Loading i18n strings for locale en Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [IrActivityLed] lirc socket connect: attempt 1 of 20 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 25 13:45:09 rpi5-k662 sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Nov 25 13:45:09 rpi5-k662 sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1675]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Nov 25 13:45:09 rpi5-k662 sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709155] CoreMusicLibrary::Adding element Randomizer Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Radio Paradise Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Randomizer Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Loading i18n strings for locale en Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Volumio Calling Home Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: PlexAmp::Plex initialised[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709182] CoreMusicLibrary::Adding element Plex Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Radio Paradise Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Randomizer Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Plex Nov 25 13:45:09 rpi5-k662 mpd[1669]: Nov 25 13:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Preparing to generate the ALSA configuration file Nov 25 13:45:09 rpi5-k662 sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 25 13:45:09 rpi5-k662 sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1686]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=27 pin_b=17 relative_axis=true steps-per-period=1 Nov 25 13:45:09 rpi5-k662 sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 25 13:45:09 rpi5-k662 sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 25 13:45:09 rpi5-k662 sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 25 13:45:09 rpi5-k662 systemd[1]: Reloading. Nov 25 13:45:09 rpi5-k662 sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Reading ALSA contributions from plugins. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: Backlight interface detected. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: MPD Permissions set Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: MPD Permissions set Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Volumio called home Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Spotify config file written Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Nov 25 13:45:09 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 25 13:45:09 rpi5-k662 sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 25 13:45:09 rpi5-k662 sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 25 13:45:09 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 kernel: rotary-encoder rotary@1b: gray Nov 25 13:45:09 rpi5-k662 kernel: input: rotary@1b as /devices/platform/rotary@1b/input/input7 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: No need to fix Spotify hosts Nov 25 13:45:09 rpi5-k662 sudo[1689]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 25 13:45:09 rpi5-k662 sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Nov 25 13:45:09 rpi5-k662 sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1737]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Starting Shairport Sync Nov 25 13:45:09 rpi5-k662 sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/4-0045/brightness Nov 25 13:45:09 rpi5-k662 sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Starting Shairport Sync Nov 25 13:45:09 rpi5-k662 sudo[1741]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 13:45:09 rpi5-k662 sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Starting Shairport Sync Nov 25 13:45:09 rpi5-k662 systemd-udevd[1647]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Starting Shairport Sync Nov 25 13:45:09 rpi5-k662 sudo[1747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 13:45:09 rpi5-k662 sudo[1747]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 13:45:09 rpi5-k662 sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 13:45:09 rpi5-k662 sudo[1752]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: Raspberry Pi Foundation touch screen detected. Nov 25 13:45:09 rpi5-k662 sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 25 13:45:09 rpi5-k662 sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:09 rpi5-k662 sudo[1759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 25 13:45:09 rpi5-k662 sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Nov 25 13:45:09 rpi5-k662 sudo[1759]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1756]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 25 13:45:09 rpi5-k662 sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 13:45:09 rpi5-k662 sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: ir_controller: LIRC correctly updated. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Nov 25 13:45:09 rpi5-k662 sudo[1762]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1694]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: File permissions for backlight brightness control set. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [IrActivityLed] lirc socket connect: attempt 2 of 20 Nov 25 13:45:09 rpi5-k662 systemd[1]: Reloading. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Asound.conf file unchanged, so no further update is needed Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Output device has changed, restarting MPD Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Output device has changed, restarting Shairport Sync Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 13:45:09 rpi5-k662 sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 sudo[1767]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 13:45:09 rpi5-k662 sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: MPD Permissions set Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: MPD Permissions set Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:09 rpi5-k662 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 25 13:45:09 rpi5-k662 volumio[1351]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 25 13:45:09 rpi5-k662 volumio[1351]: SPOTIFY: BQD6LpzMkpQbQegr1bG5tbnFG5vGGYl3e8vFu03eZ5I8qtbM9fcCF-8PU8XeJd6t0ttUk5EgoMFMxukahkzxUlyc8WtxyVWNWmqE4vsbgYKSl_5xrSK4Zm5d_oUraA0asC1zKGqDNLwcugPlJKpmrpLUwjd6FQkF2UGJRHyQuHaG02fZeisnJ96ohUkXX9nxB3oJBjESirFMZ1tc4B2pDUtERPDtfWT-94wMNPgEOqlHaw7q10rXkF_JF1-tvfJNNcSWX8EqNBtKbnc Nov 25 13:45:09 rpi5-k662 volumio[1351]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: New Spotify access token = BQD6LpzMkpQbQegr1bG5tbnFG5vGGYl3e8vFu03eZ5I8qtbM9fcCF-8PU8XeJd6t0ttUk5EgoMFMxukahkzxUlyc8WtxyVWNWmqE4vsbgYKSl_5xrSK4Zm5d_oUraA0asC1zKGqDNLwcugPlJKpmrpLUwjd6FQkF2UGJRHyQuHaG02fZeisnJ96ohUkXX9nxB3oJBjESirFMZ1tc4B2pDUtERPDtfWT-94wMNPgEOqlHaw7q10rXkF_JF1-tvfJNNcSWX8EqNBtKbnc Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Starting Shairport Sync Nov 25 13:45:09 rpi5-k662 sudo[1800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 13:45:09 rpi5-k662 sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:09 rpi5-k662 volumio[1351]: SPOTIFY: User informations: {"country":"NL","display_name":"Marco","email":"m.botermans@home.nl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/z4ujt0w8jsm3yt93kz6hrseoy"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/z4ujt0w8jsm3yt93kz6hrseoy","id":"z4ujt0w8jsm3yt93kz6hrseoy","images":[],"product":"premium","type":"user","uri":"spotify:user:z4ujt0w8jsm3yt93kz6hrseoy"} Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: Spotify Successfully logged in Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: [1732538709946] CoreMusicLibrary::Adding element Spotify Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source 80s80s Radio Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Radio Paradise Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Randomizer Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Plex Nov 25 13:45:09 rpi5-k662 volumio[1351]: Cannot find translation for source Spotify Nov 25 13:45:09 rpi5-k662 systemd[1]: Started Music Player Daemon. Nov 25 13:45:09 rpi5-k662 sudo[1698]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 sudo[1607]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 systemd[1]: systemd-hostnamed.service: Succeeded. Nov 25 13:45:09 rpi5-k662 sudo[1615]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:09 rpi5-k662 volumio[1351]: info: touch_display: systemctl daemon-reload succeeded. Nov 25 13:45:09 rpi5-k662 sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 25 13:45:09 rpi5-k662 sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: MPD error: The expression evaluated to a falsy value: Nov 25 13:45:10 rpi5-k662 volumio[1351]: assert.ok(self.idling) Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: The expression evaluated to a falsy value: Nov 25 13:45:10 rpi5-k662 volumio[1351]: assert.ok(self.idling) Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: MPD running with PID1669 Nov 25 13:45:10 rpi5-k662 volumio[1351]: ,establishing connection Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: updateQueue error: null Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: updateQueue error: null Nov 25 13:45:10 rpi5-k662 systemd[1]: Started Volumio Kiosk. Nov 25 13:45:10 rpi5-k662 systemd[1]: Stopping Music Player Daemon... Nov 25 13:45:10 rpi5-k662 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 13:45:10 rpi5-k662 systemd[1]: Started go-librespot Daemon. Nov 25 13:45:10 rpi5-k662 sudo[1701]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: Librespot-go daemon starting... Nov 25 13:45:10 rpi5-k662 systemd[1]: shairport-sync.service: Succeeded. Nov 25 13:45:10 rpi5-k662 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 13:45:10 rpi5-k662 sudo[1805]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 sudo[1692]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: Volumio Kiosk started. Nov 25 13:45:10 rpi5-k662 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 13:45:10 rpi5-k662 sudo[1749]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 sudo[1752]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 sudo[1743]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 sudo[1747]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 sudo[1800]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:10 rpi5-k662 sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 13:45:10 rpi5-k662 sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: Shairport-Sync Started Nov 25 13:45:10 rpi5-k662 volumio[1351]: Error adding Membership: Error: addMembership EINVAL Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: Shairport-Sync Started Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: X display number found: Nov 25 13:45:10 rpi5-k662 systemd[1]: mpd.service: Succeeded. Nov 25 13:45:10 rpi5-k662 systemd[1]: Stopped Music Player Daemon. Nov 25 13:45:10 rpi5-k662 systemd[1]: Starting Music Player Daemon... Nov 25 13:45:10 rpi5-k662 sudo[1841]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 13:45:10 rpi5-k662 sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:10 rpi5-k662 sudo[1841]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: X display number found: 0 Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 13:45:10 rpi5-k662 startx[1810]: X.Org X Server 1.20.4 Nov 25 13:45:10 rpi5-k662 startx[1810]: X Protocol Version 11, Revision 0 Nov 25 13:45:10 rpi5-k662 startx[1810]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Nov 25 13:45:10 rpi5-k662 startx[1810]: Current Operating System: Linux rpi5-k662 6.6.56-v8+ #1803 SMP PREEMPT Thu Oct 10 14:12:44 BST 2024 aarch64 Nov 25 13:45:10 rpi5-k662 startx[1810]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe smsc95xx.macaddr=D8:3A:DD:BD:67:B4 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=7dd89884-34c6-4f67-9803-bf297d9c5536 imgfile=/volumio_current.sqsh bootpart=UUID=4F45-7250 datapart=UUID=48c13753-50c0-4277-abbb-34aba99569f4 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Nov 25 13:45:10 rpi5-k662 startx[1810]: Build Date: 04 April 2023 07:50:56AM Nov 25 13:45:10 rpi5-k662 startx[1810]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Nov 25 13:45:10 rpi5-k662 startx[1810]: Current version of pixman: 0.36.0 Nov 25 13:45:10 rpi5-k662 startx[1810]: Before reporting problems, check http://wiki.x.org Nov 25 13:45:10 rpi5-k662 startx[1810]: to make sure that you have the latest version. Nov 25 13:45:10 rpi5-k662 startx[1810]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 25 13:45:10 rpi5-k662 startx[1810]: (++) from command line, (!!) notice, (II) informational, Nov 25 13:45:10 rpi5-k662 startx[1810]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 25 13:45:10 rpi5-k662 startx[1810]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Nov 25 13:45:10 2024 Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lirc socket connect: attempt 3 of 20 Nov 25 13:45:10 rpi5-k662 startx[1810]: (==) Using config directory: "/etc/X11/xorg.conf.d" Nov 25 13:45:10 rpi5-k662 startx[1810]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: touch_display: X display number found: 0 Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Nov 25 13:45:10 rpi5-k662 mpd[1843]: Nov 25 13:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=info msg="generated new device id: 8a049686d66a5ba410d27e2efa72eacb1461d68f" Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="stored credentials found for z4ujt0w8jsm3yt93kz6hrseoy" Nov 25 13:45:10 rpi5-k662 sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Nov 25 13:45:10 rpi5-k662 sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:10 rpi5-k662 startx[1810]: MESA-LOADER: failed to open drm-rp1-dsi (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) Nov 25 13:45:10 rpi5-k662 startx[1810]: failed to load driver: drm-rp1-dsi Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lirc socket connect: attempt 4 of 20 Nov 25 13:45:10 rpi5-k662 systemd[1]: Starting lircd(8) initialization helper tool... Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Nov 25 13:45:10 rpi5-k662 lircd-setup[1861]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Nov 25 13:45:10 rpi5-k662 lircd-setup[1861]: parser = configparser.SafeConfigParser() Nov 25 13:45:10 rpi5-k662 systemd[1]: lircd-setup.service: Succeeded. Nov 25 13:45:10 rpi5-k662 systemd[1]: Started lircd(8) initialization helper tool. Nov 25 13:45:10 rpi5-k662 systemd[1]: Starting Flexible IR remote input/output application support... Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: lircd: Opening log, level: Info Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Version: lircd 0.10.1 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: System info: Linux rpi5-k662 6.6.56-v8+ #1803 SMP PREEMPT Thu Oct 10 14:12:44 BST 2024 aarch64 GNU/Linux Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="zeroconf server listening on port 39363" Nov 25 13:45:10 rpi5-k662 systemd[1]: Started Music Player Daemon. Nov 25 13:45:10 rpi5-k662 sudo[1769]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 sudo[1832]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: MPD error: The expression evaluated to a falsy value: Nov 25 13:45:10 rpi5-k662 volumio[1351]: assert.ok(self.idling) Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: The expression evaluated to a falsy value: Nov 25 13:45:10 rpi5-k662 volumio[1351]: assert.ok(self.idling) Nov 25 13:45:10 rpi5-k662 volumio[1351]: error: updateQueue error: null Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: Initial device: /dev/lirc0 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: Initial device: /dev/lirc0 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: [lirc] protocol is enabled Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: driver: default Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: output: /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Info: lircd: Opening log, level: Info Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: nodaemon: 1 Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Notice: Using systemd fd Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Warning: Running as root Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Info: Using remote: majorityrc. Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: logfile: syslog Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: immediate-init: 0 Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: permission: 666 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: driver-options: Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: listen: 0 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: connect: (null) Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: userelease: 0 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: effective_user: (null) Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: release_suffix: _EVUP Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: allow_simulate: 0 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: repeat_max: 600 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Options: dynamic_codes: (null) Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Current driver: default Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Driver API version: 3 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Driver version: 0.10.0 Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: lircd: Opening log, level: Info Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: Using systemd fd Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Warning: Running as root Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: Using remote: majorityrc. Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 systemd[1]: Started MPD OLED Plugin. Nov 25 13:45:10 rpi5-k662 systemd[1]: Started Flexible IR remote input/output application support. Nov 25 13:45:10 rpi5-k662 sudo[1859]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 systemd[1]: Startup finished in 11.892s (kernel) + 34.543s (userspace) = 46.435s. Nov 25 13:45:10 rpi5-k662 sudo[1677]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: ir_controller: systemctl restart lircd.service succeeded. Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="obtained new client token: AAAjPeOykxxBr8KYB12vXt0sk02ynG663ZTuA8mC3mQ5vex2SAat4ngP/ufh1sACrzrmDOjBZiNl6LudP5BrPONElWeosdJcV23FkJuz74oFihe7PdT2buVZBNkEwmv5nkpjaqRuyNpycKrq3HqlUn3GUwI2NtMGanIn4BHVs0SBb/LID2W5EbX4AyxwS1JiQo1quUWv5g+frxl5HZZiGIDjzPpSgMQW6B4K+cwo6IgJ08S6Ma/aQGVOcSYA2o0=" Nov 25 13:45:10 rpi5-k662 sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Nov 25 13:45:10 rpi5-k662 sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 25 13:45:10 rpi5-k662 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Nov 25 13:45:10 rpi5-k662 sudo[1877]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: ir_controller: systemctl restart irexec.service succeeded. Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lirc socket connect: attempt 5 of 20 Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] lircd service status: active Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Notice: accepted new client on /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: accepted new client on /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Info: [lirc] protocol is enabled Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Info: [lirc] protocol is enabled Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] Connected to lirc socket Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] initializing built-in LED Nov 25 13:45:10 rpi5-k662 lircd[1864]: lircd-0.10.1[1864]: Notice: accepted new client on /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 lircd-0.10.1[1864]: Notice: accepted new client on /var/run/lirc/lircd Nov 25 13:45:10 rpi5-k662 sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /sys/class/leds/led1/brightness /sys/class/leds/led1/trigger Nov 25 13:45:10 rpi5-k662 sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:10 rpi5-k662 sudo[1883]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:10 rpi5-k662 volumio[1351]: info: [IrActivityLed] Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /sys/class/leds/led1/brightness /sys/class/leds/led1/trigger Nov 25 13:45:10 rpi5-k662 volumio[1351]: /bin/chmod: cannot access '/sys/class/leds/led1/brightness': No such file or directory Nov 25 13:45:10 rpi5-k662 volumio[1351]: /bin/chmod: cannot access '/sys/class/leds/led1/trigger': No such file or directory Nov 25 13:45:10 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:10+01:00" level=debug msg="completed keyexchange" Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 kernel: broken atomic modeset userspace detected, disabling atomic Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="completed challenge" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="authenticated as z4ujt0w8jsm3yt93kz6hrseoy" Nov 25 13:45:11 rpi5-k662 startx[1810]: V3D 7.1 not supported by this version of Mesa. Nov 25 13:45:11 rpi5-k662 kernel: broken atomic modeset userspace detected, disabling atomic Nov 25 13:45:11 rpi5-k662 startx[1810]: (II) modeset(G0): Initializing kms color map for depth 24, 8 bpc. Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="authenticated as z4ujt0w8jsm3yt93kz6hrseoy" Nov 25 13:45:11 rpi5-k662 startx[1810]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:11 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="dealer connection opened" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="initializing zeroconf session, username: z4ujt0w8jsm3yt93kz6hrseoy" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="autoplay enabled: false" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="received connection id: ZDcxMDAwMTktOTEyYi00MDJlLTllMTgtMjk5ZTYwZDliM2Y0K2RlYWxlcit0Y3A6Ly8wYWNhNTgwZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMjI0OENCRDlGRDU1ODEwNDAzQURFNjE5QzI1MzVBQTVFOTgxQTcxODJGNEM0QUI0NUZCOTFFM0E1QkVEOUFEMw==" Nov 25 13:45:11 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:11+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 25 13:45:12 rpi5-k662 sudo[1974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 13:45:12 rpi5-k662 sudo[1974]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:12 rpi5-k662 sudo[1974]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:12 rpi5-k662 sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 13:45:12 rpi5-k662 sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:12 rpi5-k662 sudo[1977]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:12 rpi5-k662 sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 25 13:45:12 rpi5-k662 sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:12 rpi5-k662 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 25 13:45:12 rpi5-k662 sudo[1980]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: Upmpdcli Daemon Started Nov 25 13:45:12 rpi5-k662 volumio[1982]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:12 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: go-librespot daemon successfully initialized Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:13 rpi5-k662 sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=multimedia,password=123Boydd,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/Music /mnt/NAS/M1S Nov 25 13:45:13 rpi5-k662 sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 13:45:13 rpi5-k662 kernel: CIFS: Attempting to mount //192.168.1.15/Music Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:13 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:14 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:15 rpi5-k662 volumio[1351]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 25 13:45:15 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:15 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:16 rpi5-k662 sudo[1488]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:16 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:16 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:16 rpi5-k662 volumio[1351]: info: Initializing connection to go-librespot Websocket Nov 25 13:45:17 rpi5-k662 go-librespot[1811]: time="2024-11-25T13:45:17+01:00" level=debug msg="new websocket client" Nov 25 13:45:17 rpi5-k662 volumio[1351]: info: Connection to go-librespot Websocket established Nov 25 13:45:17 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:17 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:18 rpi5-k662 volumio-remote-updater[932]: [2024-11-25 13:45:18] [connect] Successful connection Nov 25 13:45:18 rpi5-k662 volumio-remote-updater[932]: [2024-11-25 13:45:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732538718 101 Nov 25 13:45:18 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 Nov 25 13:45:19 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:19 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:19 rpi5-k662 sudo[2057]: pam_unix(sudo:session): session closed for user root Nov 25 13:45:19 rpi5-k662 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 25 13:45:19 rpi5-k662 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 25 13:45:20 rpi5-k662 volumio[1351]: info: Cannot mount NAS M1S at system boot, trial number 2 ,retrying in 5 seconds Nov 25 13:45:20 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:20 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:20 rpi5-k662 volumio[1351]: info: Getting Spotify volume Nov 25 13:45:20 rpi5-k662 volumio[1982]: ..........................................................................................................................................................................++++ Nov 25 13:45:21 rpi5-k662 volumio[1982]: .........++++ Nov 25 13:45:21 rpi5-k662 volumio[1982]: e is 65537 (0x010001) Nov 25 13:45:21 rpi5-k662 volumio[1982]: writing RSA key Nov 25 13:45:21 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:21 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:21 rpi5-k662 volumio[1351]: info: Spotify volume: 100 Nov 25 13:45:21 rpi5-k662 volumio[1351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 25 13:45:23 rpi5-k662 volumio[1351]: info: CoreCommandRouter::volumioGetState Nov 25 13:45:23 rpi5-k662 volumio[1351]: info: CorePlayQueue::getTrack 0 Nov 25 13:45:23 rpi5-k662 volumio[1351]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 13:45:23 rpi5-k662 volumio[1351]: TypeError: Cannot read property 'get' of undefined Nov 25 13:45:23 rpi5-k662 volumio[1351]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:249:48) Nov 25 13:45:23 rpi5-k662 volumio[1351]: at listOnTimeout (internal/timers.js:554:17) Nov 25 13:45:23 rpi5-k662 volumio[1351]: at processTimers (internal/timers.js:497:7) Nov 25 13:45:23 rpi5-k662 volumio[1351]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 13:45:23 rpi5-k662 sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 13:44 Nov 25 13:45:23 rpi5-k662 sudo[2468]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"