-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Mon 2024-09-30 06:51:48 BST. -- Sep 30 06:50:02 volumiou volumio[1021]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 30 06:50:09 volumiou volumio[1021]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Sep 30 06:50:09 volumiou volumio[1021]: info: Preparing to generate the ALSA configuration file Sep 30 06:50:09 volumiou volumio[1021]: info: Asound.conf file written Sep 30 06:50:09 volumiou sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 30 06:50:09 volumiou sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou sudo[1202]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:09 volumiou volumio[1021]: info: Output device has changed, restarting MPD Sep 30 06:50:09 volumiou sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 30 06:50:09 volumiou sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou volumio[1021]: info: Output device has changed, restarting Shairport Sync Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:50:09 volumiou sudo[1207]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:09 volumiou sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 30 06:50:09 volumiou sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 30 06:50:09 volumiou volumio[1021]: info: ___________ START PLUGINS ___________ Sep 30 06:50:09 volumiou volumio[1021]: info: ControllerMpd::onStart: Initializing MPD Sep 30 06:50:09 volumiou volumio[1021]: info: Creating MPD Configuration file Sep 30 06:50:09 volumiou systemd[1]: Listening on mpd.socket. Sep 30 06:50:09 volumiou sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 30 06:50:09 volumiou sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou systemd[1]: Starting Music Player Daemon... Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:50:09 volumiou volumio[1021]: info: [1727675409486] CoreMusicLibrary::Adding element Media Servers Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:50:09 volumiou sudo[1217]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:09 volumiou sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 30 06:50:09 volumiou sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:50:09 volumiou systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 30 06:50:09 volumiou systemd[1]: mpd.service: Succeeded. Sep 30 06:50:09 volumiou systemd[1]: Stopped Music Player Daemon. Sep 30 06:50:09 volumiou systemd[1]: Starting Music Player Daemon... Sep 30 06:50:09 volumiou volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:50:09 volumiou volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:50:09 volumiou volumio[1021]: info: [1727675409581] CoreMusicLibrary::Adding element Last_100 Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:50:09 volumiou volumio[1021]: info: [1727675409584] CoreMusicLibrary::Adding element Webradio Sep 30 06:50:09 volumiou sudo[1224]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:50:09 volumiou sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:50:09 volumiou volumio[1021]: info: Initializing BBC Radios Sep 30 06:50:09 volumiou sudo[1224]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 30 06:50:09 volumiou sudo[1224]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:50:09 volumiou volumio[1021]: info: [1727675409689] CoreMusicLibrary::Adding element YouTube Music Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:50:09 volumiou volumio[1021]: Cannot find translation for source YouTube Music Sep 30 06:50:09 volumiou volumio[1021]: info: [now-playing] ConfigUpdater: config is up to date. Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:50:09 volumiou volumio[1021]: info: [1727675409711] CoreMusicLibrary::Adding element LastFM Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:50:09 volumiou volumio[1021]: Cannot find translation for source YouTube Music Sep 30 06:50:09 volumiou volumio[1021]: Cannot find translation for source LastFM Sep 30 06:50:09 volumiou volumio[1021]: info: [LastFM] scrobbler initiated! Sep 30 06:50:09 volumiou volumio[1021]: info: [LastFM] extended logging: false Sep 30 06:50:09 volumiou volumio[1021]: info: [LastFM] try scrobble stream/radio plays: false Sep 30 06:50:09 volumiou volumio[1021]: info: [LastFM] Left init routine Sep 30 06:50:09 volumiou volumio[1021]: info: [LastFM] Socket already connected: true Sep 30 06:50:09 volumiou volumio[1021]: info: Loading i18n strings for locale en Sep 30 06:50:09 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:50:09 volumiou volumio[1021]: info: Volumio Calling Home Sep 30 06:50:09 volumiou sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 30 06:50:09 volumiou sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 30 06:50:09 volumiou sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 30 06:50:09 volumiou sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:09 volumiou volumio[1021]: info: [now-playing] Fetching API key... Sep 30 06:50:09 volumiou systemd[1]: Reloading. Sep 30 06:50:09 volumiou volumio[1021]: info: [now-playing] App is listening on port 4004. Sep 30 06:50:09 volumiou volumio[1021]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 30 06:50:09 volumiou volumio[1021]: info: touch_display: Backlight interface detected. Sep 30 06:50:09 volumiou volumio[1021]: info: MPD Permissions set Sep 30 06:50:09 volumiou volumio[1021]: info: MPD Permissions set Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:50:10 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:50:10 volumiou sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Sep 30 06:50:10 volumiou sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:10 volumiou sudo[1277]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:10 volumiou volumio[1021]: info: touch_display: Raspberry Pi Foundation touch screen detected. Sep 30 06:50:10 volumiou volumio[1021]: info: Starting Shairport Sync Sep 30 06:50:10 volumiou sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 30 06:50:10 volumiou sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:10 volumiou volumio[1021]: info: Starting Shairport Sync Sep 30 06:50:10 volumiou sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 30 06:50:10 volumiou sudo[1280]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:10 volumiou sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:10 volumiou volumio[1021]: info: Starting Shairport Sync Sep 30 06:50:10 volumiou sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 30 06:50:10 volumiou sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:10 volumiou volumio[1021]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 30 06:50:10 volumiou volumio[1021]: info: touch_display: File permissions for backlight brightness control set. Sep 30 06:50:10 volumiou sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 30 06:50:10 volumiou sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:10 volumiou 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. Sep 30 06:50:10 volumiou sudo[1248]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:10 volumiou volumio[1021]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 30 06:50:10 volumiou systemd[1]: Reloading. Sep 30 06:50:10 volumiou 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. Sep 30 06:50:11 volumiou sudo[1252]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: systemctl daemon-reload succeeded. Sep 30 06:50:11 volumiou systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 30 06:50:11 volumiou sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 30 06:50:11 volumiou systemd[1]: shairport-sync.service: Succeeded. Sep 30 06:50:11 volumiou systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 30 06:50:11 volumiou sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:11 volumiou sudo[1246]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 30 06:50:11 volumiou systemd[1]: Started Volumio Kiosk. Sep 30 06:50:11 volumiou systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 30 06:50:11 volumiou sudo[1286]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou sudo[1289]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou sudo[1283]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou volumio[1021]: info: Shairport-Sync Started Sep 30 06:50:11 volumiou volumio[1021]: Error adding Membership: Error: addMembership EINVAL Sep 30 06:50:11 volumiou volumio[1021]: info: Shairport-Sync Started Sep 30 06:50:11 volumiou volumio[1021]: info: Shairport-Sync Started Sep 30 06:50:11 volumiou sudo[1307]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: Volumio Kiosk started. Sep 30 06:50:11 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetState Sep 30 06:50:11 volumiou volumio[1021]: info: CorePlayQueue::getTrack 0 Sep 30 06:50:11 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetState Sep 30 06:50:11 volumiou volumio[1021]: info: CorePlayQueue::getTrack 0 Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: X display number found: Sep 30 06:50:11 volumiou mpd[1231]: Sep 30 06:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: X display number found: 0 Sep 30 06:50:11 volumiou startx[1309]: X.Org X Server 1.20.4 Sep 30 06:50:11 volumiou startx[1309]: X Protocol Version 11, Revision 0 Sep 30 06:50:11 volumiou startx[1309]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Sep 30 06:50:11 volumiou startx[1309]: Current Operating System: Linux volumiou 6.6.47-v7l+ #1792 SMP Thu Aug 22 15:09:42 BST 2024 armv7l Sep 30 06:50:11 volumiou startx[1309]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:12:DE:C3 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=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def 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 Sep 30 06:50:11 volumiou startx[1309]: Build Date: 04 April 2023 07:50:56AM Sep 30 06:50:11 volumiou startx[1309]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Sep 30 06:50:11 volumiou startx[1309]: Current version of pixman: 0.36.0 Sep 30 06:50:11 volumiou startx[1309]: Before reporting problems, check http://wiki.x.org Sep 30 06:50:11 volumiou startx[1309]: to make sure that you have the latest version. Sep 30 06:50:11 volumiou startx[1309]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 30 06:50:11 volumiou startx[1309]: (++) from command line, (!!) notice, (II) informational, Sep 30 06:50:11 volumiou startx[1309]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 30 06:50:11 volumiou startx[1309]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep 30 06:50:11 2024 Sep 30 06:50:11 volumiou startx[1309]: (==) Using config directory: "/etc/X11/xorg.conf.d" Sep 30 06:50:11 volumiou startx[1309]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 30 06:50:11 volumiou volumio[1021]: info: touch_display: X display number found: 0 Sep 30 06:50:11 volumiou systemd[1]: Started Music Player Daemon. Sep 30 06:50:11 volumiou sudo[1210]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou sudo[1221]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:11 volumiou volumio[1021]: error: MPD error: The expression evaluated to a falsy value: Sep 30 06:50:11 volumiou volumio[1021]: assert.ok(self.idling) Sep 30 06:50:11 volumiou volumio[1021]: error: The expression evaluated to a falsy value: Sep 30 06:50:11 volumiou volumio[1021]: assert.ok(self.idling) Sep 30 06:50:11 volumiou volumio[1021]: info: MPD running with PID1231 Sep 30 06:50:11 volumiou volumio[1021]: ,establishing connection Sep 30 06:50:11 volumiou volumio[1021]: error: updateQueue error: null Sep 30 06:50:11 volumiou volumio[1021]: error: updateQueue error: null Sep 30 06:50:12 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetState Sep 30 06:50:12 volumiou volumio[1021]: info: CorePlayQueue::getTrack 0 Sep 30 06:50:12 volumiou volumio[1021]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 30 06:50:17 volumiou volumio[1021]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 30 06:50:20 volumiou volumio[1021]: Cannot compose Albumart path Sep 30 06:50:21 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 30 06:50:21 volumiou volumio[1021]: info: Discovery: Getting this device information Sep 30 06:50:21 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetState Sep 30 06:50:21 volumiou volumio[1021]: info: CorePlayQueue::getTrack 0 Sep 30 06:50:21 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 30 06:50:21 volumiou volumio[1021]: info: Discovery: adding e1d30af9-0939-43d8-95c9-4d5ebbd5699f Sep 30 06:50:21 volumiou volumio[1021]: info: Discovery: Found device VolumioU Sep 30 06:50:21 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetState Sep 30 06:50:21 volumiou volumio[1021]: info: CorePlayQueue::getTrack 0 Sep 30 06:50:21 volumiou volumio[1021]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 30 06:50:21 volumiou volumio[1157]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 30 06:50:22 volumiou volumio[1021]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 30 06:50:22 volumiou volumio[1021]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 30 06:50:22 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 30 06:50:23 volumiou volumio[1021]: info: Listing playlists Sep 30 06:50:23 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetQueue Sep 30 06:50:23 volumiou volumio[1021]: info: CoreStateMachine::getQueue Sep 30 06:50:23 volumiou volumio[1021]: info: CorePlayQueue::getQueue Sep 30 06:50:23 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetVisibleSources Sep 30 06:50:23 volumiou volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:50:23 volumiou volumio[1021]: info: CoreCommandRouter::volumioGetState Sep 30 06:50:23 volumiou volumio[1021]: info: CorePlayQueue::getTrack 0 Sep 30 06:50:29 volumiou volumio[1021]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 30 06:50:29 volumiou volumio[1021]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 30 06:50:29 volumiou volumio[1021]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Sep 30 06:50:29 volumiou volumio[1021]: errno: -3007, Sep 30 06:50:29 volumiou volumio[1021]: code: 'ENOTFOUND', Sep 30 06:50:29 volumiou volumio[1021]: syscall: 'getaddrinfo', Sep 30 06:50:29 volumiou volumio[1021]: hostname: 'ws.audioscrobbler.com' Sep 30 06:50:29 volumiou volumio[1021]: } Sep 30 06:50:29 volumiou volumio[1021]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 30 06:50:30 volumiou sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-30 06:49 Sep 30 06:50:30 volumiou sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:50:30 volumiou sudo[1765]: pam_unix(sudo:session): session closed for user root Sep 30 06:50:30 volumiou ntpd[825]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 30 06:50:33 volumiou volumio[1157]: .......................................................................................................................................++++ Sep 30 06:50:47 volumiou volumio[1157]: ...........................................................................................................................................................++++ Sep 30 06:50:47 volumiou volumio[1157]: e is 65537 (0x010001) Sep 30 06:50:47 volumiou volumio[1157]: writing RSA key Sep 30 06:50:50 volumiou ntpd[825]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 30 06:50:59 volumiou volumio-remote-updater[677]: [2024-09-30 06:50:59] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 30 06:51:04 volumiou volumio-remote-updater[677]: [2024-09-30 06:51:04] [connect] Successful connection Sep 30 06:51:09 volumiou systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 30 06:51:09 volumiou sudo[1077]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:09 volumiou systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 30 06:51:09 volumiou systemd[1]: Started dynamicswap service. Sep 30 06:51:09 volumiou systemd[1]: dynamicswap.service: Succeeded. Sep 30 06:51:10 volumiou systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 30 06:51:10 volumiou systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 30 06:51:10 volumiou systemd[1]: Started dynamicswap service. Sep 30 06:51:10 volumiou systemd[1]: Stopped Volumio Backend Module. Sep 30 06:51:10 volumiou systemd[1]: Started Volumio Backend Module. Sep 30 06:51:10 volumiou systemd[1]: dynamicswap.service: Succeeded. Sep 30 06:51:10 volumiou ntpd[825]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 30 06:51:11 volumiou volumio[1849]: info: ------------------------------------------- Sep 30 06:51:11 volumiou volumio[1849]: info: ----- Volumio3 ---- Sep 30 06:51:11 volumiou volumio[1849]: info: ------------------------------------------- Sep 30 06:51:11 volumiou volumio[1849]: info: ----- System startup ---- Sep 30 06:51:11 volumiou volumio[1849]: info: ------------------------------------------- Sep 30 06:51:11 volumiou volumio[1849]: info: MYVOLUMIO Environment detected Sep 30 06:51:11 volumiou volumio[1849]: info: Plugin folders cleanup Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning into folder /volumio/app/plugins/ Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category audio_interface Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category miscellanea Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category music_service Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category plugins.json Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category system_controller Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category user_interface Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning into folder /data/plugins/ Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category music_service Sep 30 06:51:11 volumiou volumio[1849]: info: Scanning category user_interface Sep 30 06:51:11 volumiou volumio[1849]: info: Plugin folders cleanup completed Sep 30 06:51:11 volumiou volumio[1849]: info: ------------------------------------------- Sep 30 06:51:11 volumiou volumio[1849]: info: ----- Core plugins startup ---- Sep 30 06:51:11 volumiou volumio[1849]: info: ------------------------------------------- Sep 30 06:51:11 volumiou volumio[1849]: info: Loading plugins from folder /volumio/app/plugins/ Sep 30 06:51:11 volumiou volumio[1849]: info: Adding plugin upnp to MyMusic Plugins Sep 30 06:51:11 volumiou volumio[1849]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 30 06:51:11 volumiou volumio[1849]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 30 06:51:11 volumiou volumio[1849]: info: Loading plugins from folder /data/plugins/ Sep 30 06:51:11 volumiou volumio[1849]: info: Loading plugin "system"... Sep 30 06:51:11 volumiou volumio[1849]: info: Loading plugin "appearance"... Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "network"... Sep 30 06:51:12 volumiou volumio[1849]: info: Refreshing Cached IP Addresses Sep 30 06:51:12 volumiou sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 30 06:51:12 volumiou sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:12 volumiou sudo[1875]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:12 volumiou sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "services"... Sep 30 06:51:12 volumiou sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "alsa_controller"... Sep 30 06:51:12 volumiou sudo[1877]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:12 volumiou sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 30 06:51:12 volumiou sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:12 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "wizard"... Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "networkfs"... Sep 30 06:51:12 volumiou volumio[1849]: info: Starting Udev Watcher for removable devices Sep 30 06:51:12 volumiou sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 10.100.0.150:/share/Media/Music /mnt/NAS/Quint Sep 30 06:51:12 volumiou sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:12 volumiou volumio[1849]: info: Ignoring mount for partition: boot Sep 30 06:51:12 volumiou volumio[1849]: info: Ignoring mount for partition: volumio Sep 30 06:51:12 volumiou volumio[1849]: info: Ignoring mount for partition: volumio_data Sep 30 06:51:12 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "volumio_command_line_client"... Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "upnp"... Sep 30 06:51:12 volumiou volumio[1849]: info: [1727675472518] Starting Upmpd Daemon Sep 30 06:51:12 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "my_music"... Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "mpd"... Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "upnp_browser"... Sep 30 06:51:12 volumiou volumio[1849]: info: Loading plugin "alarm-clock"... Sep 30 06:51:13 volumiou volumio[1849]: info: Loading plugin "airplay_emulation"... Sep 30 06:51:13 volumiou volumio[1849]: info: Starting Shairport Sync Sep 30 06:51:13 volumiou volumio[1849]: info: Loading plugin "last_100"... Sep 30 06:51:13 volumiou volumio[1849]: info: Loading plugin "webradio"... Sep 30 06:51:13 volumiou volumio[1849]: info: Loading plugin "i2s_dacs"... Sep 30 06:51:13 volumiou volumio[1849]: info: Loading plugin "volumiodiscovery"... Sep 30 06:51:13 volumiou volumio[1849]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 30 06:51:13 volumiou volumio[1849]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 30 06:51:13 volumiou node[1849]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 30 06:51:13 volumiou volumio[1849]: *** WARNING *** For more information see Sep 30 06:51:13 volumiou volumio[1849]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 30 06:51:13 volumiou volumio[1849]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 30 06:51:13 volumiou volumio[1849]: *** WARNING *** For more information see Sep 30 06:51:13 volumiou node[1849]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 30 06:51:13 volumiou node[1849]: *** WARNING *** For more information see Sep 30 06:51:13 volumiou node[1849]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 30 06:51:13 volumiou node[1849]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 30 06:51:13 volumiou node[1849]: *** WARNING *** For more information see Sep 30 06:51:13 volumiou volumio[1849]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 30 06:51:13 volumiou volumio[1849]: info: Discovery: Started advertising with name: VolumioU Sep 30 06:51:13 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 30 06:51:13 volumiou volumio[1849]: info: Loading plugin "ytcr"... Sep 30 06:51:14 volumiou volumio[1849]: info: Loading plugin "ytmusic"... Sep 30 06:51:15 volumiou volumio[1849]: info: Loading plugin "now_playing"... Sep 30 06:51:15 volumiou volumio[1849]: info: Loading plugin "outputs"... Sep 30 06:51:15 volumiou volumio[1849]: info: Loading plugin "albumart"... Sep 30 06:51:15 volumiou volumio[1849]: info: Plugin example_plugin is not enabled Sep 30 06:51:15 volumiou volumio[1849]: info: Loading plugin "inputs"... Sep 30 06:51:15 volumiou volumio[1849]: info: Loading plugin "updater_comm"... Sep 30 06:51:16 volumiou volumio[1849]: info: Plugin mpdemulation is not enabled Sep 30 06:51:16 volumiou volumio[1849]: info: Loading plugin "rest_api"... Sep 30 06:51:16 volumiou volumio[1849]: info: Loading plugin "websocket"... Sep 30 06:51:16 volumiou volumio[1849]: info: Starting Socket.io Server version 2.3.0 Sep 30 06:51:16 volumiou volumio[1849]: info: Loading plugin "Systeminfo"... Sep 30 06:51:16 volumiou volumio[1849]: Forking 3 albumart workers Sep 30 06:51:16 volumiou sudo[1880]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:16 volumiou volumio[1849]: info: Loading plugin "lastfm"... Sep 30 06:51:16 volumiou volumio[1849]: info: Loading plugin "touch_display"... Sep 30 06:51:16 volumiou volumio[1849]: Starting albumart workers Sep 30 06:51:16 volumiou volumio[1849]: Starting albumart workers Sep 30 06:51:16 volumiou volumio[1849]: info: Applying required configuration parameters for plugin touch_display Sep 30 06:51:16 volumiou volumio[1849]: Starting albumart workers Sep 30 06:51:16 volumiou volumio[1849]: info: Loading i18n strings for locale en Sep 30 06:51:16 volumiou volumio[1849]: Updating browse sources language Sep 30 06:51:16 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::initPlayerControls Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: Express server listening on port 3000 Sep 30 06:51:17 volumiou volumio[1849]: [Metrics] WebUI: 6s 130.79ms Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::resetVolumioState Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::getcurrentVolume Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioRetrievevolume Sep 30 06:51:17 volumiou volumio-remote-updater[677]: [2024-09-30 06:51:17] [connect] Successful connection Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::pushState Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getTrack 0 Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioPushState Sep 30 06:51:17 volumiou volumio[1849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 30 06:51:17 volumiou volumio-remote-updater[677]: [2024-09-30 06:51:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727675477 101 Sep 30 06:51:17 volumiou volumio[1849]: 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 Sep 30 06:51:17 volumiou volumio[1849]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 30 06:51:17 volumiou volumio[1849]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Sep 30 06:51:17 volumiou volumio[1849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 30 06:51:17 volumiou volumio[1849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:51:17 volumiou volumio[1849]: info: Reloading queue from file Sep 30 06:51:17 volumiou volumio[1849]: info: Listing playlists Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetQueue Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::getQueue Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getQueue Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetVisibleSources Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetState Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getTrack 0 Sep 30 06:51:17 volumiou volumio[1849]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 30 06:51:17 volumiou volumio[1849]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Sep 30 06:51:17 volumiou volumio[1849]: info: Listing playlists Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetQueue Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::getQueue Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getQueue Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetVisibleSources Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetState Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getTrack 0 Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::setRepeat null single undefined Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::pushState Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getTrack 0 Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioPushState Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::setRandom null Sep 30 06:51:17 volumiou volumio[1849]: info: CoreStateMachine::pushState Sep 30 06:51:17 volumiou volumio[1849]: info: CorePlayQueue::getTrack 0 Sep 30 06:51:17 volumiou volumio[1849]: info: CoreCommandRouter::volumioPushState Sep 30 06:51:17 volumiou volumio[1849]: info: Setting Device type: Raspberry PI Sep 30 06:51:17 volumiou volumio[1849]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 30 06:51:17 volumiou volumio[1849]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Sep 30 06:51:17 volumiou volumio[1849]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 30 06:51:20 volumiou wpa_supplicant[915]: RRM: Ignoring radio measurement request: Not RRM network Sep 30 06:51:22 volumiou sudo[1955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 30 06:51:22 volumiou sudo[1955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:22 volumiou sudo[1955]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:22 volumiou sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 30 06:51:22 volumiou sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:22 volumiou sudo[1958]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:22 volumiou sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 30 06:51:22 volumiou sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:22 volumiou sudo[1961]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:22 volumiou volumio[1849]: info: Upmpdcli Daemon Started Sep 30 06:51:28 volumiou dhcpcd[927]: wlan0: offered 10.0.1.72 from 10.0.1.200 Sep 30 06:51:28 volumiou dhcpcd[927]: wlan0: probing address 10.0.1.72/24 Sep 30 06:51:30 volumiou ntpd[825]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 30 06:51:32 volumiou dhcpcd[927]: wlan0: leased 10.0.1.72 for 600 seconds Sep 30 06:51:32 volumiou avahi-daemon[660]: Registering new address record for 10.0.1.72 on wlan0.IPv4. Sep 30 06:51:32 volumiou dhcpcd[927]: wlan0: adding route to 10.0.1.0/24 Sep 30 06:51:32 volumiou dhcpcd[927]: wlan0: changing default route via 10.0.1.254 Sep 30 06:51:32 volumiou avahi-daemon[660]: Withdrawing address record for 169.254.91.1 on wlan0. Sep 30 06:51:32 volumiou avahi-daemon[660]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.91.1. Sep 30 06:51:32 volumiou dhcpcd[927]: wlan0: deleting route to 169.254.0.0/16 Sep 30 06:51:32 volumiou avahi-daemon[660]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.1.72. Sep 30 06:51:32 volumiou dhcpcd[927]: wlan0: pid 927 deleted default route via 10.0.1.254 Sep 30 06:51:34 volumiou wpa_supplicant[915]: RRM: Ignoring radio measurement request: Not RRM network Sep 30 06:51:34 volumiou ntpd[825]: Listen normally on 4 wlan0 10.0.1.72:123 Sep 30 06:51:34 volumiou ntpd[825]: Deleting interface #3 wlan0, 169.254.91.1#123, interface stats: received=0, sent=0, dropped=0, active_time=125 secs Sep 30 06:51:34 volumiou ntpd[825]: new interface(s) found: waking up resolver Sep 30 06:51:35 volumiou ntpd[825]: Soliciting pool server 51.89.151.183 Sep 30 06:51:36 volumiou ntpd[825]: Soliciting pool server 45.63.100.187 Sep 30 06:51:37 volumiou ntpd[825]: Soliciting pool server 81.187.217.217 Sep 30 06:51:38 volumiou ntpd[825]: Soliciting pool server 46.17.88.212 Sep 30 06:51:39 volumiou ntpd[825]: Soliciting pool server 85.199.214.102 Sep 30 06:51:40 volumiou ntpd[825]: Soliciting pool server 85.199.214.99 Sep 30 06:51:40 volumiou volumio[1849]: info: Discovery: adding e1d30af9-0939-43d8-95c9-4d5ebbd5699f Sep 30 06:51:40 volumiou volumio[1849]: info: Discovery: Found device VolumioU Sep 30 06:51:40 volumiou volumio[1849]: info: CoreCommandRouter::volumioGetState Sep 30 06:51:40 volumiou volumio[1849]: info: CorePlayQueue::getTrack 0 Sep 30 06:51:41 volumiou ntpd[825]: Soliciting pool server 131.111.8.63 Sep 30 06:51:42 volumiou ntpd[825]: Soliciting pool server 77.104.162.218 Sep 30 06:51:42 volumiou ntpd[825]: Soliciting pool server 85.199.214.100 Sep 30 06:51:42 volumiou ntpd[825]: Soliciting pool server 90.155.73.34 Sep 30 06:51:42 volumiou ntpd[825]: Soliciting pool server 2a00:2381:19c6::100 Sep 30 06:51:42 volumiou ntpd[825]: Soliciting pool server 131.111.8.60 Sep 30 06:51:42 volumiou dhcpcd[927]: wlan0: carrier lost Sep 30 06:51:42 volumiou wpa_supplicant[915]: wlan0: Associated with 34:60:f9:aa:34:9b Sep 30 06:51:42 volumiou wpa_supplicant[915]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:60:f9:aa:34:9b completed [id=0 id_str=] Sep 30 06:51:42 volumiou wpa_supplicant[915]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 30 06:51:42 volumiou avahi-daemon[660]: Withdrawing address record for 10.0.1.72 on wlan0. Sep 30 06:51:42 volumiou avahi-daemon[660]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.1.72. Sep 30 06:51:42 volumiou avahi-daemon[660]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 30 06:51:42 volumiou dhcpcd[927]: wlan0: deleting route to 10.0.1.0/24 Sep 30 06:51:42 volumiou volumio[1849]: info: Discovery: A device disappeared from network Sep 30 06:51:42 volumiou volumio[1849]: info: Discovery: Device volumiou disappeared from network Sep 30 06:51:42 volumiou dhcpcd[927]: wlan0: carrier acquired Sep 30 06:51:42 volumiou dhcpcd[927]: wlan0: IAID dd:12:de:c4 Sep 30 06:51:42 volumiou dhcpcd[927]: wlan0: rebinding lease of 10.0.1.72 Sep 30 06:51:42 volumiou dhcpcd[927]: wlan0: probing address 10.0.1.72/24 Sep 30 06:51:43 volumiou dhcpcd[927]: wlan0: soliciting an IPv6 router Sep 30 06:51:44 volumiou ntpd[825]: Deleting interface #4 wlan0, 10.0.1.72#123, interface stats: received=14, sent=19, dropped=6, active_time=10 secs Sep 30 06:51:44 volumiou ntpd[825]: 85.199.214.100 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 77.104.162.218 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 131.111.8.63 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 85.199.214.99 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 85.199.214.102 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 46.17.88.212 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 45.63.100.187 local addr 10.0.1.72 -> Sep 30 06:51:44 volumiou ntpd[825]: 51.89.151.183 local addr 10.0.1.72 -> Sep 30 06:51:46 volumiou nmbd[795]: [2024/09/30 06:51:46.177382, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Sep 30 06:51:46 volumiou nmbd[795]: reload_interfaces: No subnets to listen to. Waiting.. Sep 30 06:51:46 volumiou wpa_supplicant[915]: RRM: Ignoring radio measurement request: Not RRM network Sep 30 06:51:46 volumiou volumio[1849]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Sep 30 06:51:46 volumiou volumio[1849]: info: Preparing to generate the ALSA configuration file Sep 30 06:51:46 volumiou volumio[1849]: info: Asound.conf file unchanged, so no further update is needed Sep 30 06:51:46 volumiou volumio[1849]: info: Output device has changed, restarting MPD Sep 30 06:51:46 volumiou sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 30 06:51:46 volumiou sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:46 volumiou volumio[1849]: info: Output device has changed, restarting Shairport Sync Sep 30 06:51:46 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:46 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:51:46 volumiou sudo[2053]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:46 volumiou sudo[2056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 30 06:51:46 volumiou sudo[2056]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou systemd[1]: Stopping Music Player Daemon... Sep 30 06:51:47 volumiou volumio[1849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 30 06:51:47 volumiou volumio[1849]: info: ___________ START PLUGINS ___________ Sep 30 06:51:47 volumiou volumio[1849]: info: ControllerMpd::onStart: Initializing MPD Sep 30 06:51:47 volumiou volumio[1849]: info: Creating MPD Configuration file Sep 30 06:51:47 volumiou systemd[1]: mpd.service: Succeeded. Sep 30 06:51:47 volumiou systemd[1]: Stopped Music Player Daemon. Sep 30 06:51:47 volumiou sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 30 06:51:47 volumiou sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:51:47 volumiou volumio[1849]: info: [1727675507070] CoreMusicLibrary::Adding element Media Servers Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:51:47 volumiou volumio[1849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:51:47 volumiou sudo[2062]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:47 volumiou systemd[1]: Starting Music Player Daemon... Sep 30 06:51:47 volumiou sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 30 06:51:47 volumiou sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 30 06:51:47 volumiou systemd[1]: mpd.service: Succeeded. Sep 30 06:51:47 volumiou systemd[1]: Stopped Music Player Daemon. Sep 30 06:51:47 volumiou systemd[1]: Starting Music Player Daemon... Sep 30 06:51:47 volumiou volumio[1849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:51:47 volumiou volumio[1849]: info: [1727675507164] CoreMusicLibrary::Adding element Last_100 Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:51:47 volumiou volumio[1849]: info: [1727675507166] CoreMusicLibrary::Adding element Webradio Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:51:47 volumiou volumio[1849]: info: Initializing BBC Radios Sep 30 06:51:47 volumiou sudo[2074]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 30 06:51:47 volumiou sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou sudo[2074]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:51:47 volumiou volumio[1849]: info: [1727675507265] CoreMusicLibrary::Adding element YouTube Music Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:47 volumiou volumio[1849]: Cannot find translation for source YouTube Music Sep 30 06:51:47 volumiou volumio[1849]: info: [now-playing] ConfigUpdater: config is up to date. Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 30 06:51:47 volumiou volumio[1849]: info: [1727675507283] CoreMusicLibrary::Adding element LastFM Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 30 06:51:47 volumiou volumio[1849]: Cannot find translation for source YouTube Music Sep 30 06:51:47 volumiou volumio[1849]: Cannot find translation for source LastFM Sep 30 06:51:47 volumiou volumio[1849]: info: [LastFM] scrobbler initiated! Sep 30 06:51:47 volumiou volumio[1849]: info: [LastFM] extended logging: false Sep 30 06:51:47 volumiou volumio[1849]: info: [LastFM] try scrobble stream/radio plays: false Sep 30 06:51:47 volumiou volumio[1849]: info: [LastFM] Left init routine Sep 30 06:51:47 volumiou volumio[1849]: info: [LastFM] Socket already connected: true Sep 30 06:51:47 volumiou volumio[1849]: info: Loading i18n strings for locale en Sep 30 06:51:47 volumiou volumio[1849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 30 06:51:47 volumiou volumio[1849]: info: Volumio Calling Home Sep 30 06:51:47 volumiou sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 30 06:51:47 volumiou sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 30 06:51:47 volumiou sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 30 06:51:47 volumiou sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:47 volumiou sudo[2091]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:47 volumiou systemd[1]: Reloading. Sep 30 06:51:47 volumiou volumio[1849]: info: [now-playing] Fetching API key... Sep 30 06:51:47 volumiou volumio[1849]: info: [now-playing] App is listening on port 4004. Sep 30 06:51:47 volumiou volumio[1849]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 30 06:51:47 volumiou volumio[1849]: Unhandled rejection Error: No sockets available, cannot start. Sep 30 06:51:47 volumiou volumio[1849]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 30 06:51:47 volumiou volumio[1849]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 30 06:51:47 volumiou volumio[1849]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 30 06:51:47 volumiou volumio[1849]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 30 06:51:47 volumiou volumio[1849]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 30 06:51:47 volumiou volumio[1849]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 30 06:51:47 volumiou volumio[1849]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 30 06:51:47 volumiou volumio[1849]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 30 06:51:47 volumiou volumio[1849]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 30 06:51:47 volumiou volumio[1849]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 30 06:51:47 volumiou volumio[1849]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 30 06:51:47 volumiou volumio[1849]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 30 06:51:47 volumiou volumio[1849]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 30 06:51:47 volumiou volumio[1849]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 30 06:51:47 volumiou volumio[1849]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 30 06:51:47 volumiou volumio[1849]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 30 06:51:47 volumiou volumio[1849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 30 06:51:47 volumiou dhcpcd[927]: wlan0: leased 10.0.1.72 for 600 seconds Sep 30 06:51:47 volumiou dhcpcd[927]: wlan0: adding route to 10.0.1.0/24 Sep 30 06:51:47 volumiou dhcpcd[927]: wlan0: adding default route via 10.0.1.254 Sep 30 06:51:47 volumiou volumio[1849]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Sep 30 06:51:47 volumiou volumio[1849]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Sep 30 06:51:47 volumiou volumio[1849]: errno: -16, Sep 30 06:51:47 volumiou volumio[1849]: code: 'EBUSY', Sep 30 06:51:47 volumiou volumio[1849]: syscall: 'getaddrinfo', Sep 30 06:51:47 volumiou volumio[1849]: hostname: 'ws.audioscrobbler.com' Sep 30 06:51:47 volumiou volumio[1849]: } Sep 30 06:51:47 volumiou volumio[1849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 30 06:51:47 volumiou avahi-daemon[660]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.1.72. Sep 30 06:51:47 volumiou avahi-daemon[660]: New relevant interface wlan0.IPv4 for mDNS. Sep 30 06:51:47 volumiou avahi-daemon[660]: Registering new address record for 10.0.1.72 on wlan0.IPv4. Sep 30 06:51:47 volumiou 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. Sep 30 06:51:48 volumiou sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-30 06:50 Sep 30 06:51:48 volumiou sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 30 06:51:48 volumiou sudo[2094]: pam_unix(sudo:session): session closed for user root Sep 30 06:51:48 volumiou systemd[1]: Reloading. 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="35b821ff7c588ea2ff28d7b2bc9bb02e49e3ab6c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 18 Sep 2024 02:17:42 PM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cfa71b4386e95e4d11d00ae35602129a"