-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Tue 2025-06-17 10:24:58 JST. -- Jun 17 10:23:00 volumio volumio[860]: info: Plugin folders cleanup Jun 17 10:23:00 volumio volumio[860]: info: Scanning into folder /volumio/app/plugins/ Jun 17 10:23:00 volumio volumio[860]: info: Scanning category audio_interface Jun 17 10:23:00 volumio volumio[860]: info: Scanning category miscellanea Jun 17 10:23:00 volumio volumio[860]: info: Scanning category music_service Jun 17 10:23:00 volumio volumio[860]: info: Scanning category plugins.json Jun 17 10:23:00 volumio volumio[860]: info: Scanning category system_controller Jun 17 10:23:00 volumio volumio[860]: info: Scanning category user_interface Jun 17 10:23:00 volumio volumio[860]: info: Scanning into folder /data/plugins/ Jun 17 10:23:00 volumio volumio[860]: info: Scanning category user_interface Jun 17 10:23:00 volumio volumio[860]: info: Plugin folders cleanup completed Jun 17 10:23:00 volumio volumio[860]: info: ------------------------------------------- Jun 17 10:23:00 volumio volumio[860]: info: ----- Core plugins startup ---- Jun 17 10:23:00 volumio volumio[860]: info: ------------------------------------------- Jun 17 10:23:00 volumio volumio[860]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 10:23:00 volumio volumio[860]: info: Adding plugin upnp to MyMusic Plugins Jun 17 10:23:00 volumio volumio[860]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 10:23:00 volumio volumio[860]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 10:23:00 volumio volumio[860]: info: Loading plugins from folder /data/plugins/ Jun 17 10:23:00 volumio volumio[860]: info: Loading plugin "system"... Jun 17 10:23:00 volumio volumio[860]: info: Loading plugin "appearance"... Jun 17 10:23:01 volumio volumio-time-update[675]: volumio-time-update-util: Fetching time from Volumio... Jun 17 10:23:01 volumio volumio-time-update[675]: volumio-time-update-util: Date not found in response Jun 17 10:23:01 volumio volumio-time-update[675]: volumio-time-update-util: Retrying in 5 seconds... Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "network"... Jun 17 10:23:02 volumio volumio[860]: info: Refreshing Cached IP Addresses Jun 17 10:23:02 volumio sh[560]: eth0: leased 192.168.1.16 for 14400 seconds Jun 17 10:23:02 volumio sh[560]: eth0: adding route to 192.168.1.0/24 Jun 17 10:23:02 volumio sh[560]: eth0: adding default route via 192.168.1.1 Jun 17 10:23:02 volumio dhcpcd[603]: eth0: leased 192.168.1.16 for 14400 seconds Jun 17 10:23:02 volumio avahi-daemon[667]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.16. Jun 17 10:23:02 volumio dhcpcd[603]: eth0: adding route to 192.168.1.0/24 Jun 17 10:23:02 volumio avahi-daemon[667]: New relevant interface eth0.IPv4 for mDNS. Jun 17 10:23:02 volumio dhcpcd[603]: eth0: adding default route via 192.168.1.1 Jun 17 10:23:02 volumio avahi-daemon[667]: Registering new address record for 192.168.1.16 on eth0.IPv4. Jun 17 10:23:02 volumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 10:23:02 volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:02 volumio sudo[919]: pam_unix(sudo:session): session closed for user root Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "services"... Jun 17 10:23:02 volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "alsa_controller"... Jun 17 10:23:02 volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:02 volumio sudo[922]: pam_unix(sudo:session): session closed for user root Jun 17 10:23:02 volumio sh[560]: forked to background, child pid 961 Jun 17 10:23:02 volumio dhcpcd[603]: forked to background, child pid 961 Jun 17 10:23:02 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "wizard"... Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "networkfs"... Jun 17 10:23:02 volumio volumio[860]: info: Starting Udev Watcher for removable devices Jun 17 10:23:02 volumio sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=omv_user,password=Falcon908,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //omvpi.local/Music /mnt/NAS/Nas Jun 17 10:23:02 volumio sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:02 volumio volumio[860]: info: Ignoring mount for partition: boot Jun 17 10:23:02 volumio volumio[860]: info: Ignoring mount for partition: volumio Jun 17 10:23:02 volumio volumio[860]: info: Ignoring mount for partition: volumio_data Jun 17 10:23:02 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "volumio_command_line_client"... Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "upnp"... Jun 17 10:23:02 volumio volumio[860]: info: [1750123382514] Starting Upmpd Daemon Jun 17 10:23:02 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "my_music"... Jun 17 10:23:02 volumio ifplugd(eth0)[811]: client: ifup: interface eth0 already configured Jun 17 10:23:02 volumio volumio[860]: info: Loading plugin "mpd"... Jun 17 10:23:02 volumio sh[560]: eth0=eth0 Jun 17 10:23:02 volumio ifplugd(eth0)[811]: Program executed successfully. Jun 17 10:23:02 volumio kernel: Key type cifs.spnego registered Jun 17 10:23:02 volumio kernel: Key type cifs.idmap registered Jun 17 10:23:02 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. Jun 17 10:23:02 volumio kernel: CIFS: Attempting to mount //omvpi.local/Music Jun 17 10:23:03 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jun 17 10:23:03 volumio nmbd[775]: [2025/06/17 10:23:03.207575, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 17 10:23:03 volumio nmbd[775]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 17 10:23:03 volumio systemd[1]: Started Samba NMB Daemon. Jun 17 10:23:03 volumio sudo[981]: pam_unix(sudo:session): session closed for user root Jun 17 10:23:03 volumio volumio[860]: info: Loading plugin "upnp_browser"... Jun 17 10:23:03 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 17 10:23:03 volumio volumio-remote-updater[674]: [2025-06-17 10:23:03] [connect] Successful connection Jun 17 10:23:03 volumio ntpd[821]: Listen normally on 3 eth0 192.168.1.16:123 Jun 17 10:23:03 volumio ntpd[821]: new interface(s) found: waking up resolver Jun 17 10:23:03 volumio winbindd[1026]: [2025/06/17 10:23:03.774437, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 17 10:23:03 volumio winbindd[1026]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 17 10:23:03 volumio winbindd[1026]: [2025/06/17 10:23:03.807861, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 17 10:23:03 volumio systemd[1]: Started Samba Winbind Daemon. Jun 17 10:23:03 volumio winbindd[1026]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 17 10:23:03 volumio systemd[1]: Starting Samba SMB Daemon... Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "alarm-clock"... Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "airplay_emulation"... Jun 17 10:23:04 volumio volumio[860]: info: Starting Shairport Sync Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "last_100"... Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "webradio"... Jun 17 10:23:04 volumio smbd[1031]: [2025/06/17 10:23:04.597777, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 17 10:23:04 volumio systemd[1]: Started Samba SMB Daemon. Jun 17 10:23:04 volumio smbd[1031]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "i2s_dacs"... Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "volumiodiscovery"... Jun 17 10:23:04 volumio volumio[860]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 10:23:04 volumio volumio[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 10:23:04 volumio volumio[860]: *** WARNING *** For more information see Jun 17 10:23:04 volumio node[860]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 10:23:04 volumio volumio[860]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 10:23:04 volumio volumio[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 10:23:04 volumio volumio[860]: *** WARNING *** For more information see Jun 17 10:23:04 volumio node[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 10:23:04 volumio node[860]: *** WARNING *** For more information see Jun 17 10:23:04 volumio node[860]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 10:23:04 volumio node[860]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 10:23:04 volumio node[860]: *** WARNING *** For more information see Jun 17 10:23:04 volumio volumio[860]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 10:23:04 volumio volumio[860]: info: Discovery: Started advertising with name: Volumio Jun 17 10:23:04 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 10:23:04 volumio volumio[860]: info: Plugin peppy_screensaver is not enabled Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "outputs"... Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "albumart"... Jun 17 10:23:04 volumio volumio[860]: info: Plugin example_plugin is not enabled Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "inputs"... Jun 17 10:23:04 volumio volumio[860]: info: Loading plugin "updater_comm"... Jun 17 10:23:05 volumio volumio[860]: info: Plugin mpdemulation is not enabled Jun 17 10:23:05 volumio volumio[860]: info: Loading plugin "rest_api"... Jun 17 10:23:05 volumio volumio[860]: info: Loading plugin "websocket"... Jun 17 10:23:05 volumio volumio[860]: info: Starting Socket.io Server version 2.3.0 Jun 17 10:23:05 volumio volumio[860]: info: Plugin touch_display is not enabled Jun 17 10:23:05 volumio volumio[860]: info: Loading i18n strings for locale ja Jun 17 10:23:05 volumio volumio[860]: Updating browse sources language Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 10:23:05 volumio volumio[860]: Forking 3 albumart workers Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::initPlayerControls Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 10:23:05 volumio volumio[860]: Express server listening on port 3000 Jun 17 10:23:05 volumio volumio[860]: [Metrics] WebUI: 8s 506.78ms Jun 17 10:23:05 volumio volumio[860]: info: CoreStateMachine::resetVolumioState Jun 17 10:23:05 volumio volumio[860]: info: CoreStateMachine::getcurrentVolume Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 10:23:05 volumio volumio[860]: info: CoreStateMachine::pushState Jun 17 10:23:05 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 10:23:05 volumio volumio[860]: info: CoreCommandRouter::volumioPushState Jun 17 10:23:06 volumio volumio-time-update[675]: volumio-time-update-util: Fetching time from Volumio... Jun 17 10:23:06 volumio volumio[860]: info: Volumio Network Manager: Network status updated: 1 Jun 17 10:23:06 volumio volumio[860]: verbose: New Socket.io Connection to 192.168.1.16 from 192.168.1.29 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 1 Jun 17 10:23:06 volumio volumio-remote-updater[674]: [2025-06-17 10:23:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750123383 101 Jun 17 10:23:06 volumio volumio[860]: 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: 2 Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 10:23:06 volumio volumio[860]: info: Discovery: Getting this device information Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:23:06 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 10:23:06 volumio volumio[860]: Starting albumart workers Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:23:06 volumio volumio[860]: info: Reloading queue from file Jun 17 10:23:06 volumio volumio[860]: Starting albumart workers Jun 17 10:23:06 volumio volumio[860]: info: CoreStateMachine::setRepeat false single undefined Jun 17 10:23:06 volumio volumio[860]: info: CoreStateMachine::pushState Jun 17 10:23:06 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::volumioPushState Jun 17 10:23:06 volumio volumio-time-update[675]: volumio-time-update-util: Setting system time to: 2025-06-17 10:24:25 Jun 17 10:23:06 volumio volumio[860]: info: CoreStateMachine::setRandom false Jun 17 10:23:06 volumio volumio[860]: info: CoreStateMachine::pushState Jun 17 10:23:06 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::volumioPushState Jun 17 10:23:06 volumio volumio[860]: Starting albumart workers Jun 17 10:23:06 volumio volumio[860]: info: Setting Device type: Raspberry PI Jun 17 10:23:06 volumio volumio[860]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 17 10:23:06 volumio volumio[860]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 17 10:23:06 volumio volumio[860]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 17 10:23:06 volumio volumio[860]: info: Completed loading Core Plugins Jun 17 10:23:06 volumio volumio[860]: info: Preparing to generate the ALSA configuration file Jun 17 10:23:06 volumio sudo[1082]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-17 10:24:25 Jun 17 10:23:06 volumio sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:06 volumio volumio[860]: info: Asound.conf file unchanged, so no further update is needed Jun 17 10:23:06 volumio volumio[860]: info: Output device has changed, restarting MPD Jun 17 10:23:06 volumio dbus-daemon[660]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=1083 comm="timedatectl set-time 2025-06-17 10:24:25 ") Jun 17 10:23:06 volumio volumio[860]: info: Output device has changed, restarting Shairport Sync Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:23:06 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:23:06 volumio sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 10:23:06 volumio sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:06 volumio sudo[1085]: pam_unix(sudo:session): session closed for user root Jun 17 10:23:06 volumio sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 10:23:06 volumio sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:06 volumio systemd[1]: Starting Time & Date Service... Jun 17 10:23:06 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 10:23:06 volumio volumio[860]: info: ___________ START PLUGINS ___________ Jun 17 10:23:07 volumio volumio[860]: info: ControllerMpd::onStart: Initializing MPD Jun 17 10:23:07 volumio volumio[860]: info: Creating MPD Configuration file Jun 17 10:23:07 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 10:23:07 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 10:23:07 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 10:23:07 volumio volumio[860]: info: [1750123387122] CoreMusicLibrary::Adding element DLNAサーバー Jun 17 10:23:07 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 10:23:07 volumio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 10:23:07 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:07 volumio sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 10:23:07 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:23:07 volumio systemd[1]: mpd.service: Succeeded. Jun 17 10:23:07 volumio dbus-daemon[660]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 17 10:23:07 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 10:23:07 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Jun 17 10:23:07 volumio systemd[1]: Started Time & Date Service. Jun 17 10:24:25 volumio systemd-timedated[1087]: Changed local time to Tue Jun 17 10:24:25 2025 Jun 17 10:24:25 volumio systemd[1]: Starting Music Player Daemon... Jun 17 10:24:25 volumio volumio-time-update[675]: volumio-time-update-util: System time updated successfully. Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:25 volumio sudo[1082]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:25 volumio systemd[1]: Started Volumio Time Update Utility. Jun 17 10:24:25 volumio systemd[1]: Reached target Multi-User System. Jun 17 10:24:25 volumio systemd[1]: Reached target Graphical Interface. Jun 17 10:24:25 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 17 10:24:25 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:25 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 17 10:24:25 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 17 10:24:25 volumio sudo[1100]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 10:24:25 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 10:24:25 volumio sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 10:24:25 volumio volumio[860]: info: [1750123465234] CoreMusicLibrary::Adding element Last_100 Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 10:24:25 volumio volumio[860]: info: [1750123465239] CoreMusicLibrary::Adding element Webradio Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 10:24:25 volumio sudo[1100]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:25 volumio volumio[860]: info: Initializing BBC Radios Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:25 volumio volumio[860]: info: Volumio Calling Home Jun 17 10:24:25 volumio volumio[860]: info: Discovery: adding 5317421e-6343-4454-8bea-809fe0b2a915 Jun 17 10:24:25 volumio volumio[860]: info: Discovery: Found device Volumio Jun 17 10:24:25 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:25 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:25 volumio volumio[860]: info: MPD Permissions set Jun 17 10:24:25 volumio volumio[860]: info: MPD Permissions set Jun 17 10:24:26 volumio volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:26 volumio volumio[860]: verbose: New Socket.io Connection to 192.168.1.16 from 192.168.1.29 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 10:24:26 volumio volumio[860]: info: Received Get System Info Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 10:24:26 volumio volumio[860]: info: Discovery: Getting this device information Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:26 volumio volumio[860]: info: Listing playlists Jun 17 10:24:26 volumio volumio[860]: info: Starting Shairport Sync Jun 17 10:24:26 volumio volumio[860]: info: Starting Shairport Sync Jun 17 10:24:26 volumio volumio[860]: info: Starting Shairport Sync Jun 17 10:24:26 volumio sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 10:24:26 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:26 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 10:24:26 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:26 volumio sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 10:24:26 volumio sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 10:24:26 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 10:24:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 10:24:26 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:26 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:26 volumio volumio[860]: info: Volumio called home Jun 17 10:24:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 10:24:26 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:26 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 10:24:26 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 17 10:24:26 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 10:24:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 10:24:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 10:24:26 volumio sudo[1161]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:26 volumio volumio[860]: info: Shairport-Sync Started Jun 17 10:24:26 volumio volumio[860]: Error adding Membership: Error: addMembership EINVAL Jun 17 10:24:26 volumio volumio[860]: info: Shairport-Sync Started Jun 17 10:24:26 volumio volumio[860]: info: Shairport-Sync Started Jun 17 10:24:27 volumio mpd[1109]: Jun 17 10:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 10:24:27 volumio systemd[1]: Started Music Player Daemon. Jun 17 10:24:27 volumio systemd[1]: Startup finished in 13.289s (kernel) + 26.360s (userspace) = 39.650s. Jun 17 10:24:27 volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:27 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:27 volumio volumio[860]: info: Completed starting Core Plugins Jun 17 10:24:27 volumio volumio[860]: info: ------------------------------------------- Jun 17 10:24:27 volumio volumio[860]: info: ----- MyVolumio plugins startup ---- Jun 17 10:24:27 volumio volumio[860]: info: ------------------------------------------- Jun 17 10:24:27 volumio volumio[860]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 17 10:24:27 volumio volumio[860]: error: MPD error: The expression evaluated to a falsy value: Jun 17 10:24:27 volumio volumio[860]: assert.ok(self.idling) Jun 17 10:24:27 volumio volumio[860]: error: The expression evaluated to a falsy value: Jun 17 10:24:27 volumio volumio[860]: assert.ok(self.idling) Jun 17 10:24:27 volumio volumio[860]: error: updateQueue error: null Jun 17 10:24:27 volumio volumio[860]: info: MPD running with PID1109 Jun 17 10:24:27 volumio volumio[860]: ,establishing connection Jun 17 10:24:27 volumio volumio[860]: error: updateQueue error: null Jun 17 10:24:30 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 10:24:30 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:30 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:30 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 10:24:30 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:30 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:30 volumio sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 17 10:24:30 volumio sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 17 10:24:30 volumio sudo[1194]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:30 volumio volumio[860]: info: Upmpdcli Daemon Started Jun 17 10:24:30 volumio volumio[1196]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 17 10:24:33 volumio volumio[860]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 17 10:24:36 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 17 10:24:37 volumio volumio-remote-updater[674]: No test mode Jun 17 10:24:37 volumio volumio-remote-updater[674]: No alpha test mode Jun 17 10:24:38 volumio volumio[860]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 17 10:24:38 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin bluetooth to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin multiroom to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin metavolumio to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin cd_controller to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 17 10:24:38 volumio volumio[860]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 17 10:24:40 volumio volumio[860]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 17 10:24:40 volumio volumio[860]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 17 10:24:40 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:40 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:40 volumio volumio[860]: info: Starting MyVolumio Remote Streaming Endpoints Jun 17 10:24:40 volumio volumio[860]: info: MyVolumio login type: Token Jun 17 10:24:40 volumio volumio[860]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 17 10:24:40 volumio volumio[860]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 17 10:24:40 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 17 10:24:42 volumio volumio[860]: info: Starting Streaming Service Transparent Proxy Jun 17 10:24:42 volumio volumio[860]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 17 10:24:42 volumio volumio[860]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 17 10:24:42 volumio volumio[860]: info: Streaming services startup Jun 17 10:24:42 volumio volumio[860]: info: Starting Streaming Daemon Jun 17 10:24:42 volumio sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 10:24:42 volumio sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:42 volumio sudo[1241]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:42 volumio volumio[860]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 17 10:24:42 volumio volumio[860]: info: CoreCommandRouter::Close All Modals sent Jun 17 10:24:42 volumio volumio[860]: error: Cannot start Volumio Streaming Daemon Jun 17 10:24:42 volumio volumio[860]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 17 10:24:42 volumio volumio[860]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 17 10:24:43 volumio volumio[860]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 17 10:24:43 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 17 10:24:43 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 17 10:24:43 volumio volumio[860]: STREAMING PROXY: Starting server on port 3245 Jun 17 10:24:43 volumio volumio[860]: Node JS runtime: 14 Jun 17 10:24:44 volumio volumio[860]: info: MyVolumio token set successfully Jun 17 10:24:44 volumio volumio[860]: info: MYVOLUMIO: Adding device Jun 17 10:24:44 volumio volumio[860]: info: MYVOLUMIO: Evaluating Server Jun 17 10:24:45 volumio volumio[860]: info: MyVolumio status changed Jun 17 10:24:45 volumio volumio[860]: info: Streaming services startup Jun 17 10:24:45 volumio volumio[860]: info: Starting Streaming Daemon Jun 17 10:24:45 volumio volumio[860]: info: Removing browser output: myVolumio user plan is not superstar Jun 17 10:24:45 volumio volumio[860]: info: Removing audio output: Jun 17 10:24:45 volumio volumio[860]: info: Stoppping Tunnel 1 Jun 17 10:24:45 volumio sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 10:24:45 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:45 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:45 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 17 10:24:45 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:45 volumio volumio[860]: error: Cannot start Volumio Streaming Daemon Jun 17 10:24:45 volumio volumio[860]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 17 10:24:45 volumio volumio[860]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 17 10:24:45 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:45 volumio volumio[860]: info: Remote SSH Stopped Jun 17 10:24:45 volumio volumio[860]: info: Setting Geolocation for MyVolumio to as1 Jun 17 10:24:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:45 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:46 volumio volumio[860]: info: Enabling plugin peppy_screensaver Jun 17 10:24:46 volumio volumio[860]: info: Loading plugin "peppy_screensaver"... Jun 17 10:24:46 volumio volumio[860]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 17 10:24:46 volumio volumio[860]: info: Preparing to generate the ALSA configuration file Jun 17 10:24:46 volumio volumio[860]: info: Updating MyVolumio device info Jun 17 10:24:46 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:46 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:46 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:46 volumio volumio[860]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 17 10:24:46 volumio volumio[860]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 17 10:24:46 volumio volumio[860]: info: Reading ALSA contributions from plugins. Jun 17 10:24:46 volumio volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 17 10:24:47 volumio volumio[860]: info: Asound.conf file written Jun 17 10:24:47 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 17 10:24:47 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:47 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:47 volumio volumio[860]: info: Output device has changed, restarting MPD Jun 17 10:24:47 volumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 10:24:47 volumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:47 volumio volumio[860]: info: Output device has changed, restarting Shairport Sync Jun 17 10:24:47 volumio sudo[1278]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:47 volumio sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 10:24:47 volumio sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:47 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 10:24:47 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 10:24:47 volumio volumio[860]: info: PLUGIN START: peppy_screensaver Jun 17 10:24:47 volumio volumio[860]: info: Loading i18n strings for locale ja Jun 17 10:24:47 volumio systemd[1]: mpd.service: Succeeded. Jun 17 10:24:47 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 10:24:47 volumio volumio[860]: info: /tmp/myfifo created Jun 17 10:24:47 volumio volumio[860]: info: /tmp/myfifosa created Jun 17 10:24:47 volumio systemd[1]: Starting Music Player Daemon... Jun 17 10:24:47 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jun 17 10:24:47 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:47 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:47 volumio volumio[860]: info: snd-dummy loaded Jun 17 10:24:47 volumio sudo[1286]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 10:24:47 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:47 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:47 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:47 volumio volumio[860]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 10:24:47 volumio volumio[860]: info: Done. Jun 17 10:24:47 volumio volumio[860]: info: Enabling plugin touch_display Jun 17 10:24:47 volumio volumio[860]: info: Loading plugin "touch_display"... Jun 17 10:24:48 volumio volumio[860]: info: Applying required configuration parameters for plugin touch_display Jun 17 10:24:48 volumio volumio[860]: info: PLUGIN START: touch_display Jun 17 10:24:48 volumio volumio[860]: info: Loading i18n strings for locale ja Jun 17 10:24:48 volumio volumio[860]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 17 10:24:48 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 10:24:48 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 17 10:24:48 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:49 volumio systemd[1]: Stopping Getty on tty1... Jun 17 10:24:49 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Jun 17 10:24:49 volumio systemd[1]: getty@tty1.service: Succeeded. Jun 17 10:24:49 volumio systemd[1]: Stopped Getty on tty1. Jun 17 10:24:49 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:49 volumio sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 17 10:24:49 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:49 volumio sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 17 10:24:49 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:49 volumio volumio[860]: info: MPD Permissions set Jun 17 10:24:49 volumio systemd[1]: Reloading. Jun 17 10:24:49 volumio volumio[860]: info: touch_display: No backlight interface detected. Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:49 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:49 volumio volumio[860]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jun 17 10:24:49 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:49 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:49 volumio volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 17 10:24:49 volumio volumio[860]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 17 10:24:49 volumio mpd[1301]: Jun 17 10:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 10:24:49 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jun 17 10:24:49 volumio volumio[860]: info: Starting Shairport Sync Jun 17 10:24:49 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 10:24:49 volumio volumio[860]: info: Starting Shairport Sync Jun 17 10:24:49 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:50 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:50 volumio 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. Jun 17 10:24:50 volumio volumio[860]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 17 10:24:50 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 10:24:50 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:50 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 10:24:50 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:50 volumio volumio[860]: info: MPD Permissions set Jun 17 10:24:50 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 17 10:24:50 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:50 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 17 10:24:50 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:50 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:50 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:50 volumio volumio[860]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 17 10:24:50 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:50 volumio systemd[1]: Started Music Player Daemon. Jun 17 10:24:50 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 10:24:50 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:50 volumio volumio[860]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 17 10:24:50 volumio sudo[1281]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:50 volumio volumio[860]: info: Enabling plugin touch_display Jun 17 10:24:50 volumio volumio[860]: info: Loading plugin "touch_display"... Jun 17 10:24:50 volumio volumio[860]: info: Applying required configuration parameters for plugin touch_display Jun 17 10:24:50 volumio volumio[860]: info: PLUGIN START: touch_display Jun 17 10:24:50 volumio volumio[860]: info: Loading i18n strings for locale ja Jun 17 10:24:50 volumio systemd[1]: Reloading. Jun 17 10:24:50 volumio volumio[860]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 17 10:24:50 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 10:24:51 volumio sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 17 10:24:51 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:51 volumio volumio[860]: info: touch_display: systemctl daemon-reload succeeded. Jun 17 10:24:51 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 17 10:24:51 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:51 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 17 10:24:51 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:51 volumio volumio[860]: info: MYVOLUMIO: Adding device Jun 17 10:24:51 volumio volumio[860]: info: MYVOLUMIO: Evaluating Server Jun 17 10:24:51 volumio volumio[860]: info: touch_display: No backlight interface detected. Jun 17 10:24:51 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 17 10:24:51 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:51 volumio volumio[860]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Jun 17 10:24:51 volumio volumio[860]: error: updateQueue error: null Jun 17 10:24:51 volumio volumio[860]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 17 10:24:51 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 17 10:24:51 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:51 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:51 volumio 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. Jun 17 10:24:51 volumio sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 17 10:24:51 volumio sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:51 volumio sudo[1412]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:51 volumio volumio[860]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 17 10:24:51 volumio volumio[860]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 17 10:24:52 volumio sudo[1316]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:52 volumio volumio[860]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 17 10:24:52 volumio volumio[860]: info: Setting Geolocation for MyVolumio to as1 Jun 17 10:24:52 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:52 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:52 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:52 volumio systemd[1]: Reloading. Jun 17 10:24:52 volumio 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. Jun 17 10:24:52 volumio volumio[860]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 17 10:24:53 volumio volumio[860]: info: Updating MyVolumio device info Jun 17 10:24:53 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:53 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:53 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:53 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:53 volumio systemd[1]: Reloading. Jun 17 10:24:53 volumio volumio[860]: info: touch_display: systemctl daemon-reload succeeded. Jun 17 10:24:53 volumio sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 17 10:24:53 volumio sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:53 volumio 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. Jun 17 10:24:53 volumio volumio[860]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 17 10:24:54 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio volumio[860]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 17 10:24:54 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 10:24:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 10:24:54 volumio systemd[1]: Started Volumio Kiosk. Jun 17 10:24:54 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 10:24:54 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 10:24:54 volumio volumio[860]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 17 10:24:54 volumio volumio[860]: info: touch_display: Volumio Kiosk started. Jun 17 10:24:54 volumio systemd[1]: mpd.service: Succeeded. Jun 17 10:24:54 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 10:24:54 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio sudo[1443]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio systemd[1]: Starting Music Player Daemon... Jun 17 10:24:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 10:24:54 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:54 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:54 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio volumio[860]: info: Done. Jun 17 10:24:54 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:54 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:54 volumio volumio[860]: info: Shairport-Sync Started Jun 17 10:24:54 volumio volumio[860]: info: Shairport-Sync Started Jun 17 10:24:54 volumio volumio[860]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 17 10:24:54 volumio volumio[860]: info: touch_display: Volumio Kiosk started. Jun 17 10:24:54 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:54 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:54 volumio volumio[860]: info: Done. Jun 17 10:24:54 volumio volumio[860]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 17 10:24:54 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:54 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:54 volumio volumio[860]: info: touch_display: X display number found: Jun 17 10:24:54 volumio volumio[860]: info: touch_display: X display number found: Jun 17 10:24:54 volumio sudo[1467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 10:24:54 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 10:24:54 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:54 volumio startx[1458]: X.Org X Server 1.20.4 Jun 17 10:24:54 volumio startx[1458]: X Protocol Version 11, Revision 0 Jun 17 10:24:54 volumio startx[1458]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jun 17 10:24:54 volumio startx[1458]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jun 17 10:24:54 volumio startx[1458]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D,margin_left=32,margin_right=32,margin_top=32,margin_bottom=32 smsc95xx.macaddr=D8:3A:DD:CD:20:A5 vc_mem.mem_base=0x3ec00000 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=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a 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 Jun 17 10:24:54 volumio startx[1458]: Build Date: 04 April 2023 07:50:56AM Jun 17 10:24:54 volumio startx[1458]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jun 17 10:24:54 volumio startx[1458]: Current version of pixman: 0.36.0 Jun 17 10:24:54 volumio startx[1458]: Before reporting problems, check http://wiki.x.org Jun 17 10:24:54 volumio startx[1458]: to make sure that you have the latest version. Jun 17 10:24:54 volumio startx[1458]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 17 10:24:54 volumio startx[1458]: (++) from command line, (!!) notice, (II) informational, Jun 17 10:24:54 volumio startx[1458]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 17 10:24:54 volumio startx[1458]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jun 17 10:24:54 2025 Jun 17 10:24:54 volumio startx[1458]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 17 10:24:54 volumio startx[1458]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 17 10:24:54 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:55 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:55 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 10:24:55 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 10:24:55 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 10:24:56 volumio volumio-remote-updater[674]: No test mode Jun 17 10:24:56 volumio volumio-remote-updater[674]: No alpha test mode Jun 17 10:24:56 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 17 10:24:56 volumio volumio[860]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: CoreCommandRouter::volumioGetState Jun 17 10:24:56 volumio volumio[860]: info: CorePlayQueue::getTrack 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 17 10:24:56 volumio volumio[860]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:56 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio mpd[1492]: Jun 17 10:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio systemd[1]: Started Music Player Daemon. Jun 17 10:24:57 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: error: updateQueue error: null Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:57 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:58 volumio volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 17 10:24:58 volumio volumio[860]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 17 10:24:58 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:58 volumio volumio[860]: info: touch_display: X display number found: 0 Jun 17 10:24:58 volumio volumio[860]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 10:24:58 volumio volumio[860]: Error: connect EISCONN /tmp/.X11-unix/X0 Jun 17 10:24:58 volumio volumio[860]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 17 10:24:58 volumio volumio[860]: errno: -106, Jun 17 10:24:58 volumio volumio[860]: code: 'EISCONN', Jun 17 10:24:58 volumio volumio[860]: syscall: 'connect', Jun 17 10:24:58 volumio volumio[860]: address: '/tmp/.X11-unix/X0' Jun 17 10:24:58 volumio volumio[860]: } Jun 17 10:24:58 volumio volumio[860]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 10:24:58 volumio startx[1458]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jun 17 10:24:58 volumio volumio[1196]: .............................................................................................................................++++ Jun 17 10:24:58 volumio sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 10:23 Jun 17 10:24:58 volumio sudo[1626]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"