-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Wed 2024-06-19 17:19:28 UTC. -- Jun 19 17:18:00 volumio volumio-remote-updater[402]: [2024-06-19 17:18:00] [info] asio async_connect error: system:111 (Connection refused) Jun 19 17:18:00 volumio volumio-remote-updater[402]: [2024-06-19 17:18:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 19 17:18:00 volumio volumio-remote-updater[402]: [2024-06-19 17:18:00] [error] handle_connect error: Underlying Transport Error Jun 19 17:18:01 volumio kernel: wlan0: authenticate with c0:c9:e3:0c:8a:ae Jun 19 17:18:00 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with c0:c9:e3:0c:8a:ae (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:01 volumio kernel: wlan0: send auth to c0:c9:e3:0c:8a:ae (try 1/3) Jun 19 17:18:01 volumio sudo[685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:01 volumio sudo[685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:01 volumio sudo[685]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:01 volumio wireless.js[415]: trying... Jun 19 17:18:01 volumio sudo[688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:01 volumio sudo[688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:01 volumio sudo[688]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:01 volumio kernel: wlan0: send auth to c0:c9:e3:0c:8a:ae (try 2/3) Jun 19 17:18:01 volumio kernel: wlan0: send auth to c0:c9:e3:0c:8a:ae (try 3/3) Jun 19 17:18:01 volumio kernel: wlan0: authentication with c0:c9:e3:0c:8a:ae timed out Jun 19 17:18:02 volumio wireless.js[415]: trying... Jun 19 17:18:03 volumio sudo[691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:03 volumio sudo[691]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:03 volumio sudo[691]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:03 volumio wireless.js[415]: trying... Jun 19 17:18:03 volumio sudo[694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:03 volumio sudo[694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:03 volumio sudo[694]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:03 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with e8:48:b8:14:73:d0 (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:03 volumio kernel: wlan0: authenticate with e8:48:b8:14:73:d0 Jun 19 17:18:03 volumio kernel: wlan0: send auth to e8:48:b8:14:73:d0 (try 1/3) Jun 19 17:18:03 volumio kernel: wlan0: send auth to e8:48:b8:14:73:d0 (try 2/3) Jun 19 17:18:03 volumio kernel: wlan0: send auth to e8:48:b8:14:73:d0 (try 3/3) Jun 19 17:18:04 volumio kernel: wlan0: authentication with e8:48:b8:14:73:d0 timed out Jun 19 17:18:04 volumio wireless.js[415]: trying... Jun 19 17:18:06 volumio sudo[697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:06 volumio sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:06 volumio sudo[697]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:06 volumio wireless.js[415]: trying... Jun 19 17:18:06 volumio sudo[700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:06 volumio sudo[700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:06 volumio sudo[700]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:06 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with 98:25:4a:71:b5:64 (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:06 volumio kernel: wlan0: authenticate with 98:25:4a:71:b5:64 Jun 19 17:18:06 volumio kernel: wlan0: send auth to 98:25:4a:71:b5:64 (try 1/3) Jun 19 17:18:06 volumio kernel: wlan0: send auth to 98:25:4a:71:b5:64 (try 2/3) Jun 19 17:18:06 volumio kernel: wlan0: send auth to 98:25:4a:71:b5:64 (try 3/3) Jun 19 17:18:06 volumio kernel: wlan0: authentication with 98:25:4a:71:b5:64 timed out Jun 19 17:18:07 volumio wireless.js[415]: trying... Jun 19 17:18:08 volumio sudo[703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:08 volumio sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:08 volumio sudo[703]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:08 volumio wireless.js[415]: trying... Jun 19 17:18:08 volumio kernel: wlan0: authenticate with 34:60:f9:53:8e:4e Jun 19 17:18:08 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with 34:60:f9:53:8e:4e (SSID='The Internet' freq=2437 MHz) Jun 19 17:18:08 volumio sudo[706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:08 volumio sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:08 volumio sudo[706]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:08 volumio kernel: wlan0: send auth to 34:60:f9:53:8e:4e (try 1/3) Jun 19 17:18:08 volumio kernel: wlan0: send auth to 34:60:f9:53:8e:4e (try 2/3) Jun 19 17:18:08 volumio kernel: wlan0: send auth to 34:60:f9:53:8e:4e (try 3/3) Jun 19 17:18:09 volumio kernel: wlan0: authentication with 34:60:f9:53:8e:4e timed out Jun 19 17:18:09 volumio wireless.js[415]: trying... Jun 19 17:18:11 volumio volumio-remote-updater[402]: [2024-06-19 17:18:11] [info] asio async_connect error: system:111 (Connection refused) Jun 19 17:18:11 volumio volumio-remote-updater[402]: [2024-06-19 17:18:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 19 17:18:11 volumio volumio-remote-updater[402]: [2024-06-19 17:18:11] [error] handle_connect error: Underlying Transport Error Jun 19 17:18:11 volumio sudo[709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:11 volumio sudo[709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:11 volumio sudo[709]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:11 volumio wireless.js[415]: trying... Jun 19 17:18:11 volumio sudo[712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 17:18:11 volumio sudo[712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:11 volumio sudo[712]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:12 volumio wireless.js[415]: Overtime, starting plan B Jun 19 17:18:13 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with c0:c9:e3:0c:8a:ae (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:13 volumio kernel: wlan0: authenticate with c0:c9:e3:0c:8a:ae Jun 19 17:18:13 volumio systemd[1]: Started Wireless Services. Jun 19 17:18:13 volumio systemd[1]: Started Volumio Backend Module. Jun 19 17:18:13 volumio kernel: wlan0: send auth to c0:c9:e3:0c:8a:ae (try 1/3) Jun 19 17:18:13 volumio systemd[1]: Started Volumio Streaming Daemon. Jun 19 17:18:13 volumio kernel: wlan0: send auth to c0:c9:e3:0c:8a:ae (try 2/3) Jun 19 17:18:13 volumio kernel: wlan0: send auth to c0:c9:e3:0c:8a:ae (try 3/3) Jun 19 17:18:13 volumio wpa_supplicant[643]: wlan0: Trying to associate with c0:c9:e3:0c:8a:ae (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:13 volumio kernel: wlan0: authenticated Jun 19 17:18:13 volumio kernel: wlan0: associate with c0:c9:e3:0c:8a:ae (try 1/3) Jun 19 17:18:13 volumio kernel: wlan0: associate with c0:c9:e3:0c:8a:ae (try 2/3) Jun 19 17:18:13 volumio volumio-streaming-daemon[717]: ############################ Jun 19 17:18:13 volumio volumio-streaming-daemon[717]: # Volumio Streaming Daemon # Jun 19 17:18:13 volumio volumio-streaming-daemon[717]: # Running on port 7777 # Jun 19 17:18:13 volumio volumio-streaming-daemon[717]: ############################ Jun 19 17:18:13 volumio volumio-streaming-daemon[717]: Environment: production Jun 19 17:18:14 volumio kernel: wlan0: associate with c0:c9:e3:0c:8a:ae (try 3/3) Jun 19 17:18:14 volumio kernel: wlan0: association with c0:c9:e3:0c:8a:ae timed out Jun 19 17:18:14 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="The Internet" auth_failures=1 duration=10 reason=CONN_FAILED Jun 19 17:18:21 volumio volumio-remote-updater[402]: [2024-06-19 17:18:21] [connect] Successful connection Jun 19 17:18:21 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:21 volumio volumio[716]: info: ----- Volumio3 ---- Jun 19 17:18:21 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:21 volumio volumio[716]: info: ----- System startup ---- Jun 19 17:18:21 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:24 volumio volumio[716]: info: MYVOLUMIO Environment detected Jun 19 17:18:24 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="The Internet" Jun 19 17:18:24 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with 34:60:f9:53:8e:4e (SSID='The Internet' freq=2437 MHz) Jun 19 17:18:24 volumio kernel: wlan0: authenticate with 34:60:f9:53:8e:4e Jun 19 17:18:24 volumio wpa_supplicant[643]: wlan0: Trying to associate with 34:60:f9:53:8e:4e (SSID='The Internet' freq=2437 MHz) Jun 19 17:18:24 volumio kernel: wlan0: send auth to 34:60:f9:53:8e:4e (try 1/3) Jun 19 17:18:24 volumio kernel: wlan0: authenticated Jun 19 17:18:24 volumio kernel: wlan0: associate with 34:60:f9:53:8e:4e (try 1/3) Jun 19 17:18:24 volumio volumio[716]: info: Plugin folders cleanup Jun 19 17:18:24 volumio volumio[716]: info: Scanning into folder /volumio/app/plugins/ Jun 19 17:18:24 volumio volumio[716]: info: Scanning category audio_interface Jun 19 17:18:24 volumio kernel: wlan0: associate with 34:60:f9:53:8e:4e (try 2/3) Jun 19 17:18:24 volumio volumio[716]: info: Scanning category miscellanea Jun 19 17:18:24 volumio volumio[716]: info: Scanning category music_service Jun 19 17:18:24 volumio volumio[716]: info: Scanning category plugins.json Jun 19 17:18:24 volumio volumio[716]: info: Scanning category system_controller Jun 19 17:18:24 volumio volumio[716]: info: Scanning category user_interface Jun 19 17:18:24 volumio volumio[716]: info: Scanning into folder /data/plugins/ Jun 19 17:18:24 volumio volumio[716]: info: Scanning category user_interface Jun 19 17:18:24 volumio volumio[716]: info: Plugin folders cleanup completed Jun 19 17:18:24 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:24 volumio volumio[716]: info: ----- Core plugins startup ---- Jun 19 17:18:24 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:24 volumio volumio[716]: info: Loading plugins from folder /volumio/app/plugins/ Jun 19 17:18:24 volumio volumio[716]: info: Adding plugin upnp to MyMusic Plugins Jun 19 17:18:24 volumio volumio[716]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 19 17:18:24 volumio volumio[716]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 19 17:18:24 volumio volumio[716]: info: Loading plugins from folder /data/plugins/ Jun 19 17:18:24 volumio volumio[716]: info: Loading plugin "system"... Jun 19 17:18:24 volumio kernel: wlan0: associate with 34:60:f9:53:8e:4e (try 3/3) Jun 19 17:18:24 volumio volumio[716]: info: Loading plugin "appearance"... Jun 19 17:18:24 volumio kernel: wlan0: association with 34:60:f9:53:8e:4e timed out Jun 19 17:18:24 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="The Internet" auth_failures=2 duration=23 reason=CONN_FAILED Jun 19 17:18:28 volumio volumio[716]: info: Loading plugin "network"... Jun 19 17:18:28 volumio volumio[716]: info: Refreshing Cached IP Addresses Jun 19 17:18:28 volumio sudo[740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 17:18:28 volumio sudo[740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:28 volumio volumio[716]: info: Loading plugin "services"... Jun 19 17:18:28 volumio sudo[742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 17:18:28 volumio sudo[742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:28 volumio sudo[740]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:28 volumio volumio[716]: info: Loading plugin "alsa_controller"... Jun 19 17:18:28 volumio sudo[742]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:28 volumio sudo[745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 19 17:18:28 volumio sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:29 volumio volumio[716]: info: Loading plugin "wizard"... Jun 19 17:18:29 volumio volumio[716]: info: Loading plugin "volumio_command_line_client"... Jun 19 17:18:29 volumio volumio[716]: info: Plugin upnp is not enabled Jun 19 17:18:29 volumio volumio[716]: info: Loading plugin "my_music"... Jun 19 17:18:29 volumio volumio[716]: info: Loading plugin "mpd"... Jun 19 17:18:30 volumio volumio[716]: info: Creating MPD Configuration file Jun 19 17:18:30 volumio volumio[716]: info: Plugin upnp_browser is not enabled Jun 19 17:18:30 volumio volumio[716]: info: Loading plugin "networkfs"... Jun 19 17:18:31 volumio sudo[753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 17:18:31 volumio sudo[755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 17:18:31 volumio sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:31 volumio sudo[755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:31 volumio sudo[753]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:31 volumio sudo[745]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:31 volumio systemd[1]: Stopping Music Player Daemon... Jun 19 17:18:31 volumio volumio[716]: info: Starting Udev Watcher for removable devices Jun 19 17:18:31 volumio sudo[760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jhoeft,password=8ztsGP3o,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.25/home/music /mnt/NAS/DiskStation Jun 19 17:18:31 volumio sudo[760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:32 volumio systemd[1]: mpd.service: Succeeded. Jun 19 17:18:32 volumio systemd[1]: Stopped Music Player Daemon. Jun 19 17:18:32 volumio systemd[1]: Starting Music Player Daemon... Jun 19 17:18:32 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Jun 19 17:18:32 volumio kernel: Key type cifs.spnego registered Jun 19 17:18:32 volumio kernel: Key type cifs.idmap registered Jun 19 17:18:32 volumio kernel: CIFS: Attempting to mount //192.168.0.25/home/music Jun 19 17:18:32 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 19 17:18:32 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 19 17:18:32 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 19 17:18:32 volumio sudo[760]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:32 volumio volumio[716]: info: Ignoring mount for partition: boot Jun 19 17:18:32 volumio volumio[716]: info: Ignoring mount for partition: volumio Jun 19 17:18:32 volumio volumio[716]: info: Ignoring mount for partition: volumio_data Jun 19 17:18:32 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 17:18:32 volumio volumio[716]: info: Loading plugin "alarm-clock"... Jun 19 17:18:33 volumio volumio[716]: info: Plugin airplay_emulation is not enabled Jun 19 17:18:33 volumio volumio[716]: info: Loading plugin "last_100"... Jun 19 17:18:33 volumio volumio[716]: info: Loading plugin "webradio"... Jun 19 17:18:34 volumio volumio[716]: info: Loading plugin "i2s_dacs"... Jun 19 17:18:34 volumio volumio[716]: info: I2S DAC not set, start Auto-detection Jun 19 17:18:34 volumio volumio[716]: info: Loading plugin "volumiodiscovery"... Jun 19 17:18:34 volumio volumio[716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 17:18:34 volumio volumio[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:18:34 volumio node[716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 17:18:34 volumio volumio[716]: *** WARNING *** For more information see Jun 19 17:18:34 volumio volumio[716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 17:18:34 volumio volumio[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:18:34 volumio volumio[716]: *** WARNING *** For more information see Jun 19 17:18:34 volumio node[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:18:34 volumio node[716]: *** WARNING *** For more information see Jun 19 17:18:34 volumio node[716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 17:18:34 volumio node[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:18:34 volumio node[716]: *** WARNING *** For more information see Jun 19 17:18:34 volumio volumio[716]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 19 17:18:34 volumio volumio[716]: info: Discovery: Started advertising with name: Volumio Jun 19 17:18:34 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 17:18:34 volumio volumio[716]: info: Loading plugin "now_playing"... Jun 19 17:18:35 volumio mpd[776]: Jun 19 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 17:18:36 volumio volumio-remote-updater[402]: [2024-06-19 17:18:36] [connect] Successful connection Jun 19 17:18:36 volumio ntpd[573]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 19 17:18:38 volumio ntpd[573]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 19 17:18:38 volumio ntpd[573]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 19 17:18:38 volumio ntpd[573]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 19 17:18:39 volumio volumio[716]: info: Loading plugin "outputs"... Jun 19 17:18:39 volumio volumio[716]: info: Loading plugin "albumart"... Jun 19 17:18:39 volumio volumio[716]: info: Plugin example_plugin is not enabled Jun 19 17:18:39 volumio volumio[716]: info: Loading plugin "inputs"... Jun 19 17:18:39 volumio volumio[716]: info: Loading plugin "updater_comm"... Jun 19 17:18:40 volumio volumio[716]: info: Plugin mpdemulation is not enabled Jun 19 17:18:40 volumio volumio[716]: info: Loading plugin "rest_api"... Jun 19 17:18:40 volumio volumio[716]: info: Loading plugin "websocket"... Jun 19 17:18:40 volumio volumio[716]: info: Loading plugin "touch_display"... Jun 19 17:18:40 volumio volumio[716]: Forking 3 albumart workers Jun 19 17:18:41 volumio volumio[716]: info: Applying required configuration parameters for plugin touch_display Jun 19 17:18:42 volumio volumio[716]: info: Loading i18n strings for locale de Jun 19 17:18:42 volumio volumio[716]: Updating browse sources language Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::initPlayerControls Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 17:18:42 volumio volumio[716]: Express server listening on port 3000 Jun 19 17:18:42 volumio volumio[716]: [Metrics] WebUI: 23s 951.92ms Jun 19 17:18:42 volumio mpd[776]: Jun 19 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 19 17:18:42 volumio mpd[776]: Jun 19 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 19 17:18:42 volumio volumio[716]: info: CoreStateMachine::resetVolumioState Jun 19 17:18:42 volumio volumio[716]: info: CoreStateMachine::getcurrentVolume Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 17:18:42 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: Cannot mount NAS DiskStation at system boot, trial number 1 ,retrying in 5 seconds Jun 19 17:18:43 volumio volumio[716]: info: MPD Permissions set Jun 19 17:18:43 volumio systemd[1]: Started Music Player Daemon. Jun 19 17:18:43 volumio sudo[755]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:43 volumio volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:43 volumio volumio[716]: Starting albumart workers Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:44 volumio volumio[716]: info: Reloading queue from file Jun 19 17:18:44 volumio volumio[716]: info: CoreStateMachine::setRepeat true single undefined Jun 19 17:18:44 volumio volumio[716]: info: CoreStateMachine::pushState Jun 19 17:18:44 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::volumioPushState Jun 19 17:18:44 volumio volumio[716]: info: CoreStateMachine::setRandom false Jun 19 17:18:44 volumio volumio[716]: info: CoreStateMachine::pushState Jun 19 17:18:44 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::volumioPushState Jun 19 17:18:44 volumio volumio[716]: info: Setting Device type: Raspberry PI Jun 19 17:18:44 volumio volumio[716]: Starting albumart workers Jun 19 17:18:44 volumio volumio[716]: info: VolumeController:: Volume=100 Mute =false Jun 19 17:18:44 volumio volumio[716]: info: CoreStateMachine::pushState Jun 19 17:18:44 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::volumioPushState Jun 19 17:18:44 volumio volumio[716]: info: CoreStateMachine::updateTrackBlock Jun 19 17:18:44 volumio volumio[716]: info: CorePlayQueue::getTrackBlock Jun 19 17:18:44 volumio volumio[716]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 17:18:44 volumio volumio[716]: Starting albumart workers Jun 19 17:18:44 volumio volumio[716]: info: MPD running with PID776 Jun 19 17:18:44 volumio volumio[716]: ,establishing connection Jun 19 17:18:44 volumio volumio[716]: error: updateQueue error: null Jun 19 17:18:44 volumio volumio[716]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 19 17:18:44 volumio volumio[716]: info: Completed loading Core Plugins Jun 19 17:18:44 volumio volumio[716]: info: Preparing to generate the ALSA configuration file Jun 19 17:18:44 volumio volumio[716]: info: Asound.conf file unchanged, so no further update is needed Jun 19 17:18:44 volumio volumio[716]: info: Output device has changed, restarting MPD Jun 19 17:18:44 volumio volumio[716]: info: ___________ START PLUGINS ___________ Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 17:18:45 volumio volumio[716]: info: [1718817525009] CoreMusicLibrary::Adding element Last_100 Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 17:18:45 volumio volumio[716]: info: [1718817525052] CoreMusicLibrary::Adding element Webradio Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 17:18:45 volumio volumio[716]: info: [now-playing-config] Config is up to date. Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 17:18:45 volumio volumio[716]: info: Discovery: Getting this device information Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:18:45 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 17:18:45 volumio volumio[716]: info: Loading i18n strings for locale de Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 17:18:45 volumio volumio[716]: info: Volumio Calling Home Jun 19 17:18:45 volumio volumio[716]: info: [now-playing-app] App is listening on port 4004. Jun 19 17:18:45 volumio volumio[716]: info: VolumeController:: Volume=100 Mute =false Jun 19 17:18:45 volumio volumio[716]: info: CoreStateMachine::pushState Jun 19 17:18:45 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:45 volumio volumio[716]: info: CoreCommandRouter::volumioPushState Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:18:46 volumio volumio[716]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jun 19 17:18:46 volumio volumio[716]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Jun 19 17:18:46 volumio sudo[834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 19 17:18:46 volumio sudo[834]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:46 volumio sudo[827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 17:18:46 volumio sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 17:18:46 volumio sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:46 volumio sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:46 volumio sudo[827]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:46 volumio systemd[1]: Reloading. Jun 19 17:18:46 volumio volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jun 19 17:18:46 volumio volumio[716]: info: MPD Permissions set Jun 19 17:18:47 volumio sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Jun 19 17:18:47 volumio sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:47 volumio sudo[880]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:47 volumio volumio[716]: info: touch_display: File permissions for backlight brightness control set. Jun 19 17:18:47 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:18:47 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:47 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 19 17:18:47 volumio systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 19 17:18:47 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 19 17:18:48 volumio sudo[834]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:48 volumio volumio[716]: info: touch_display: systemctl daemon-reload succeeded. Jun 19 17:18:48 volumio kernel: Voltage normalised (0x00000000) Jun 19 17:18:48 volumio systemd[1]: Stopping Music Player Daemon... Jun 19 17:18:48 volumio sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 19 17:18:48 volumio sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:48 volumio sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jhoeft,password=8ztsGP3o,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.25/home/music /mnt/NAS/DiskStation Jun 19 17:18:48 volumio sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:48 volumio sudo[886]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:48 volumio kernel: CIFS: Attempting to mount //192.168.0.25/home/music Jun 19 17:18:48 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 19 17:18:48 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 19 17:18:48 volumio volumio[716]: info: Cannot mount NAS DiskStation at system boot, trial number 2 ,retrying in 5 seconds Jun 19 17:18:48 volumio systemd[1]: Started Volumio Kiosk. Jun 19 17:18:48 volumio sudo[884]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:48 volumio volumio[716]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 19 17:18:48 volumio volumio[716]: info: touch_display: Volumio Kiosk started Jun 19 17:18:48 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:18:48 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:48 volumio volumio[716]: info: Completed starting Core Plugins Jun 19 17:18:48 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:48 volumio volumio[716]: info: ----- MyVolumio plugins startup ---- Jun 19 17:18:48 volumio volumio[716]: info: ------------------------------------------- Jun 19 17:18:48 volumio volumio[716]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 19 17:18:49 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:18:49 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:49 volumio volumio[716]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jun 19 17:18:49 volumio volumio[716]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jun 19 17:18:49 volumio volumio[716]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 19 17:18:49 volumio startx[891]: X.Org X Server 1.20.4 Jun 19 17:18:49 volumio startx[891]: X Protocol Version 11, Revision 0 Jun 19 17:18:49 volumio startx[891]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jun 19 17:18:49 volumio startx[891]: Current Operating System: Linux volumio 5.10.92-v7+ #1514 SMP Mon Jan 17 17:36:39 GMT 2022 armv7l Jun 19 17:18:49 volumio startx[891]: Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 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=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jun 19 17:18:49 volumio startx[891]: Build Date: 04 August 2022 12:56:42PM Jun 19 17:18:49 volumio startx[891]: xorg-server 2:1.20.4-1+rpt4+deb10u5 (https://www.debian.org/support) Jun 19 17:18:49 volumio startx[891]: Current version of pixman: 0.36.0 Jun 19 17:18:49 volumio startx[891]: Before reporting problems, check http://wiki.x.org Jun 19 17:18:49 volumio startx[891]: to make sure that you have the latest version. Jun 19 17:18:49 volumio startx[891]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 19 17:18:49 volumio startx[891]: (++) from command line, (!!) notice, (II) informational, Jun 19 17:18:49 volumio startx[891]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 19 17:18:49 volumio startx[891]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jun 19 17:18:49 2024 Jun 19 17:18:49 volumio volumio[716]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 19 17:18:49 volumio startx[891]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 19 17:18:49 volumio startx[891]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 19 17:18:49 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:18:49 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:18:49 volumio volumio[716]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 19 17:18:51 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="The Internet" Jun 19 17:18:51 volumio wpa_supplicant[643]: wlan0: SME: Trying to authenticate with e8:48:b8:14:73:d0 (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:51 volumio kernel: wlan0: authenticate with e8:48:b8:14:73:d0 Jun 19 17:18:51 volumio kernel: wlan0: send auth to e8:48:b8:14:73:d0 (try 1/3) Jun 19 17:18:51 volumio volumio-remote-updater[402]: [2024-06-19 17:18:51] [connect] Successful connection Jun 19 17:18:51 volumio volumio-remote-updater[402]: [2024-06-19 17:18:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1718817531 101 Jun 19 17:18:51 volumio wpa_supplicant[643]: wlan0: Trying to associate with e8:48:b8:14:73:d0 (SSID='The Internet' freq=2412 MHz) Jun 19 17:18:51 volumio kernel: wlan0: authenticated Jun 19 17:18:51 volumio kernel: wlan0: associate with e8:48:b8:14:73:d0 (try 1/3) Jun 19 17:18:51 volumio volumio[716]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jun 19 17:18:51 volumio kernel: wlan0: associate with e8:48:b8:14:73:d0 (try 2/3) Jun 19 17:18:51 volumio kernel: wlan0: RX AssocResp from e8:48:b8:14:73:d0 (capab=0x1431 status=0 aid=2) Jun 19 17:18:51 volumio kernel: wlan0: associated Jun 19 17:18:51 volumio wpa_supplicant[643]: wlan0: Associated with e8:48:b8:14:73:d0 Jun 19 17:18:51 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 19 17:18:53 volumio volumio[716]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 19 17:18:53 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jun 19 17:18:53 volumio volumio[716]: error: MPD error: Error: write EPIPE Jun 19 17:18:54 volumio wpa_supplicant[643]: wlan0: WPA: Key negotiation completed with e8:48:b8:14:73:d0 [PTK=CCMP GTK=CCMP] Jun 19 17:18:54 volumio wpa_supplicant[643]: wlan0: CTRL-EVENT-CONNECTED - Connection to e8:48:b8:14:73:d0 completed [id=0 id_str=] Jun 19 17:18:54 volumio dhcpcd[658]: wlan0: carrier acquired Jun 19 17:18:54 volumio dhcpcd[658]: wlan0: connected to Access Point `The Internet' Jun 19 17:18:54 volumio sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jhoeft,password=8ztsGP3o,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.25/home/music /mnt/NAS/DiskStation Jun 19 17:18:54 volumio dhcpcd[658]: DUID 00:01:00:01:2a:5e:eb:70:b8:27:eb:cd:4a:87 Jun 19 17:18:54 volumio dhcpcd[658]: wlan0: IAID 38:33:95:23 Jun 19 17:18:54 volumio sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:54 volumio dhcpcd[658]: wlan0: adding address fe80::5cce:e7f8:fac6:6b1f Jun 19 17:18:54 volumio dhcpcd[658]: ipv6_addaddr1: Permission denied Jun 19 17:18:54 volumio sudo[961]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:54 volumio kernel: CIFS: Attempting to mount //192.168.0.25/home/music Jun 19 17:18:54 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 19 17:18:54 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 19 17:18:54 volumio volumio[716]: info: Cannot mount NAS DiskStation at system boot, trial number 3 ,retrying in 5 seconds Jun 19 17:18:54 volumio dhcpcd[658]: wlan0: soliciting an IPv6 router Jun 19 17:18:54 volumio systemd[1]: mpd.service: Succeeded. Jun 19 17:18:54 volumio dhcpcd[658]: wlan0: rebinding lease of 192.168.0.19 Jun 19 17:18:54 volumio systemd[1]: Stopped Music Player Daemon. Jun 19 17:18:54 volumio systemd[1]: Starting Music Player Daemon... Jun 19 17:18:56 volumio volumio[716]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.324&uuid=e939998c0af19131b8996c53021d84e7" http://updates.volumio.org/downloader-v1/track-device Jun 19 17:18:56 volumio volumio[716]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 19 17:18:56 volumio volumio[716]: Dload Upload Total Spent Left Speed Jun 19 17:18:56 volumio volumio[716]: [132B blob data] Jun 19 17:18:56 volumio volumio[716]: retrying in 5 seconds, trial 0 Jun 19 17:18:56 volumio volumio[716]: info: Volumio Calling Home Jun 19 17:18:58 volumio systemd[1]: nmbd.service: Start operation timed out. Terminating. Jun 19 17:18:58 volumio systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Jun 19 17:18:58 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Jun 19 17:18:58 volumio systemd[1]: Failed to start Samba NMB Daemon. Jun 19 17:18:58 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 19 17:18:58 volumio mpd[1002]: Jun 19 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 17:18:58 volumio winbindd[1036]: [2024/06/19 17:18:58.958118, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 19 17:18:58 volumio winbindd[1036]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 19 17:18:59 volumio systemd[1]: Started Samba Winbind Daemon. Jun 19 17:18:59 volumio winbindd[1036]: [2024/06/19 17:18:59.076595, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 19 17:18:59 volumio winbindd[1036]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 19 17:18:59 volumio systemd[1]: Starting Samba SMB Daemon... Jun 19 17:18:59 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jhoeft,password=8ztsGP3o,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.25/home/music /mnt/NAS/DiskStation Jun 19 17:18:59 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:18:59 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Jun 19 17:18:59 volumio kernel: CIFS: Attempting to mount //192.168.0.25/home/music Jun 19 17:18:59 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 19 17:18:59 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 19 17:18:59 volumio volumio[716]: info: Cannot mount NAS DiskStation at system boot, trial number 4 ,retrying in 5 seconds Jun 19 17:18:59 volumio dhcpcd[658]: wlan0: probing for an IPv4LL address Jun 19 17:18:59 volumio dhcpcd[658]: wlan0: DHCP lease expired Jun 19 17:18:59 volumio dhcpcd[658]: wlan0: soliciting a DHCP lease Jun 19 17:19:02 volumio systemd[1]: Started Samba SMB Daemon. Jun 19 17:19:02 volumio smbd[1044]: [2024/06/19 17:19:02.622821, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 19 17:19:02 volumio smbd[1044]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 19 17:19:04 volumio dhcpcd[658]: wlan0: using IPv4LL address 169.254.37.127 Jun 19 17:19:04 volumio dhcpcd[658]: wlan0: adding route to 169.254.0.0/16 Jun 19 17:19:04 volumio dhcpcd[658]: wlan0: adding default route Jun 19 17:19:04 volumio avahi-daemon[413]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.37.127. Jun 19 17:19:04 volumio avahi-daemon[413]: New relevant interface wlan0.IPv4 for mDNS. Jun 19 17:19:04 volumio avahi-daemon[413]: Registering new address record for 169.254.37.127 on wlan0.IPv4. Jun 19 17:19:04 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jhoeft,password=8ztsGP3o,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.25/home/music /mnt/NAS/DiskStation Jun 19 17:19:04 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:19:04 volumio mpd[1002]: Jun 19 17:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 19 17:19:04 volumio mpd[1002]: Jun 19 17:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 19 17:19:04 volumio systemd[1]: Started Music Player Daemon. Jun 19 17:19:04 volumio sudo[829]: pam_unix(sudo:session): session closed for user root Jun 19 17:19:04 volumio kernel: CIFS: Attempting to mount //192.168.0.25/home/music Jun 19 17:19:04 volumio systemd[1]: Reached target Multi-User System. Jun 19 17:19:04 volumio systemd[1]: Reached target Graphical Interface. Jun 19 17:19:04 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 19 17:19:04 volumio volumio[716]: error: updateQueue error: null Jun 19 17:19:04 volumio volumio[716]: error: updateQueue error: null Jun 19 17:19:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 19 17:19:04 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 19 17:19:04 volumio systemd[1]: Startup finished in 24.913s (kernel) + 1min 52.307s (userspace) = 2min 17.220s. Jun 19 17:19:05 volumio dhcpcd[658]: wlan0: offered 192.168.0.19 from 192.168.0.1 Jun 19 17:19:06 volumio volumio[716]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.324&uuid=e939998c0af19131b8996c53021d84e7" http://updates.volumio.org/downloader-v1/track-device Jun 19 17:19:06 volumio volumio[716]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 19 17:19:06 volumio volumio[716]: Dload Upload Total Spent Left Speed Jun 19 17:19:06 volumio volumio[716]: [132B blob data] Jun 19 17:19:06 volumio volumio[716]: retrying in 5 seconds, trial 1 Jun 19 17:19:06 volumio volumio[716]: info: Volumio Calling Home Jun 19 17:19:06 volumio ntpd[573]: Listen normally on 3 wlan0 169.254.37.127:123 Jun 19 17:19:06 volumio ntpd[573]: new interface(s) found: waking up resolver Jun 19 17:19:08 volumio dhcpcd[658]: wlan0: ignoring offer of 192.168.0.19 from 192.168.0.1 Jun 19 17:19:08 volumio dhcpcd[658]: wlan0: probing address 192.168.0.19/24 Jun 19 17:19:09 volumio volumio[716]: error: MyVolumio Plugin failed to start in a timely fashion Jun 19 17:19:09 volumio volumio[716]: info: BOOT COMPLETED Jun 19 17:19:09 volumio volumio[716]: [Metrics] CommandRouter: 47s 694.45ms Jun 19 17:19:09 volumio volumio[716]: info: CoreCommandRouter::volumiosetStartupVolume Jun 19 17:19:09 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:19:09 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:19:09 volumio volumio[716]: info: CoreCommandRouter::Close All Modals sent Jun 19 17:19:09 volumio volumio[716]: info: CoreCommandRouter::Close All Modals sent Jun 19 17:19:09 volumio dbus-daemon[408]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=1000 pid=1005 comm="/usr/lib/chromium-browser/chromium-browser-v7 --en") Jun 19 17:19:09 volumio systemd[1]: Starting Bluetooth service... Jun 19 17:19:09 volumio bluetoothd[1123]: Bluetooth daemon 5.50 Jun 19 17:19:09 volumio kernel: Bluetooth: Core ver 2.22 Jun 19 17:19:09 volumio kernel: NET: Registered protocol family 31 Jun 19 17:19:09 volumio kernel: Bluetooth: HCI device and connection manager initialized Jun 19 17:19:09 volumio kernel: Bluetooth: HCI socket layer initialized Jun 19 17:19:09 volumio kernel: Bluetooth: L2CAP socket layer initialized Jun 19 17:19:09 volumio kernel: Bluetooth: SCO socket layer initialized Jun 19 17:19:09 volumio bluetoothd[1123]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 19 17:19:09 volumio dbus-daemon[408]: [system] Successfully activated service 'org.bluez' Jun 19 17:19:09 volumio systemd[1]: Started Bluetooth service. Jun 19 17:19:09 volumio bluetoothd[1123]: Starting SDP server Jun 19 17:19:09 volumio bluetoothd[1123]: Excluding (cli) sap Jun 19 17:19:09 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 19 17:19:09 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 19 17:19:09 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 19 17:19:09 volumio bluetoothd[1123]: Bluetooth management interface 1.18 initialized Jun 19 17:19:09 volumio dbus-daemon[408]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1123 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 19 17:19:09 volumio systemd[1]: Starting Hostname Service... Jun 19 17:19:10 volumio dbus-daemon[408]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 19 17:19:10 volumio systemd[1]: Started Hostname Service. Jun 19 17:19:10 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 19 17:19:10 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 17:19:10 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 19 17:19:12 volumio sudo[1090]: pam_unix(sudo:session): session closed for user root Jun 19 17:19:12 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 19 17:19:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jun 19 17:19:12 volumio volumio[716]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 19 17:19:13 volumio dhcpcd[658]: wlan0: leased 192.168.0.19 for infinity Jun 19 17:19:13 volumio dhcpcd[658]: wlan0: adding route to 192.168.0.0/24 Jun 19 17:19:13 volumio avahi-daemon[413]: Registering new address record for 192.168.0.19 on wlan0.IPv4. Jun 19 17:19:13 volumio dhcpcd[658]: wlan0: changing default route via 192.168.0.1 Jun 19 17:19:14 volumio avahi-daemon[413]: Withdrawing address record for 169.254.37.127 on wlan0. Jun 19 17:19:14 volumio avahi-daemon[413]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.37.127. Jun 19 17:19:14 volumio dhcpcd[658]: wlan0: deleting route to 169.254.0.0/16 Jun 19 17:19:14 volumio avahi-daemon[413]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.19. Jun 19 17:19:14 volumio dhcpcd[658]: wlan0: pid 658 deleted default route via 192.168.0.1 Jun 19 17:19:15 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 17:19:15 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:19:15 volumio volumio[716]: info: Discovery: adding 12265c9d-d08e-4b61-9658-7f89bd4d4f91 Jun 19 17:19:15 volumio volumio[716]: info: mDNS: Found device Volumio Jun 19 17:19:15 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:19:15 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:19:16 volumio ntpd[573]: Listen normally on 4 wlan0 192.168.0.19:123 Jun 19 17:19:16 volumio ntpd[573]: Deleting interface #3 wlan0, 169.254.37.127#123, interface stats: received=0, sent=0, dropped=0, active_time=10 secs Jun 19 17:19:16 volumio ntpd[573]: new interface(s) found: waking up resolver Jun 19 17:19:16 volumio volumio[716]: info: Volumio called home Jun 19 17:19:20 volumio volumio[716]: 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 Total Clients: 4 Jun 19 17:19:22 volumio volumio[716]: error: [now-playing-app] App error: Jun 19 17:19:22 volumio volumio[716]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jun 19 17:19:22 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jun 19 17:19:23 volumio volumio[716]: info: Listing playlists Jun 19 17:19:23 volumio volumio[716]: info: CoreCommandRouter::volumioGetQueue Jun 19 17:19:23 volumio volumio[716]: info: CoreStateMachine::getQueue Jun 19 17:19:23 volumio volumio[716]: info: CorePlayQueue::getQueue Jun 19 17:19:23 volumio volumio[716]: info: CoreCommandRouter::volumioGetVisibleSources Jun 19 17:19:23 volumio volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:19:23 volumio volumio[716]: info: CoreCommandRouter::volumioGetState Jun 19 17:19:23 volumio volumio[716]: info: CorePlayQueue::getTrack 0 Jun 19 17:19:26 volumio volumio[716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 17:19:26 volumio volumio[716]: Error: certificate is not yet valid Jun 19 17:19:26 volumio volumio[716]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jun 19 17:19:26 volumio volumio[716]: at TLSSocket.emit (events.js:315:20) Jun 19 17:19:26 volumio volumio[716]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jun 19 17:19:26 volumio volumio[716]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jun 19 17:19:26 volumio volumio[716]: code: 'CERT_NOT_YET_VALID' Jun 19 17:19:26 volumio volumio[716]: } Jun 19 17:19:26 volumio volumio[716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 17:19:28 volumio sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-19 17:18 Jun 19 17:19:28 volumio sudo[1251]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"