-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2022-10-17 14:34:02 UTC. -- Oct 17 14:33:00 volumiogranaten3 kernel: cam1-reg: disabling Oct 17 14:33:00 volumiogranaten3 kernel: cam-dummy-reg: disabling Oct 17 14:33:00 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:00 volumiogranaten3 sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:00 volumiogranaten3 sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:00 volumiogranaten3 sudo[851]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:01 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:01] [info] asio async_connect error: system:111 (Connection refused) Oct 17 14:33:01 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 17 14:33:01 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:01] [error] handle_connect error: Underlying Transport Error Oct 17 14:33:01 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:01 volumiogranaten3 sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:01 volumiogranaten3 sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:01 volumiogranaten3 sudo[854]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:02 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:02 volumiogranaten3 sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:02 volumiogranaten3 sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:02 volumiogranaten3 sudo[857]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:03 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:03 volumiogranaten3 sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:03 volumiogranaten3 sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:03 volumiogranaten3 sudo[860]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:04 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:04 volumiogranaten3 sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:04 volumiogranaten3 sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:05 volumiogranaten3 sudo[863]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:05 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:05 volumiogranaten3 sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:05 volumiogranaten3 sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:06 volumiogranaten3 sudo[866]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:06 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:06 volumiogranaten3 sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:06 volumiogranaten3 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:07 volumiogranaten3 sudo[869]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:07 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:07 volumiogranaten3 sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:07 volumiogranaten3 sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:08 volumiogranaten3 sudo[872]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:08 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:08 volumiogranaten3 sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:08 volumiogranaten3 sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:09 volumiogranaten3 sudo[875]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:09 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:10 volumiogranaten3 sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:10 volumiogranaten3 sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:10 volumiogranaten3 sudo[878]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:10 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:11 volumiogranaten3 sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:11 volumiogranaten3 sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:11 volumiogranaten3 sudo[881]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:11 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:11] [info] asio async_connect error: system:111 (Connection refused) Oct 17 14:33:11 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 17 14:33:11 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:11] [error] handle_connect error: Underlying Transport Error Oct 17 14:33:11 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:12 volumiogranaten3 sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:12 volumiogranaten3 sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:12 volumiogranaten3 sudo[884]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:12 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:13 volumiogranaten3 sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:13 volumiogranaten3 sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:13 volumiogranaten3 sudo[887]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:13 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:33:13 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:14 volumiogranaten3 sudo[890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:14 volumiogranaten3 sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:14 volumiogranaten3 sudo[890]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:14 volumiogranaten3 systemd[1]: systemd-fsckd.service: Succeeded. Oct 17 14:33:14 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:15 volumiogranaten3 sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:15 volumiogranaten3 sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:15 volumiogranaten3 sudo[893]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:15 volumiogranaten3 wireless.js[470]: trying... Oct 17 14:33:16 volumiogranaten3 sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 14:33:16 volumiogranaten3 sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:16 volumiogranaten3 sudo[896]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:16 volumiogranaten3 wireless.js[470]: Overtime, starting plan B Oct 17 14:33:17 volumiogranaten3 systemd[1]: Started Wireless Services. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Started Volumio Backend Module. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Started Volumio Streaming Daemon. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Reached target Multi-User System. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Reached target Graphical Interface. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 17 14:33:17 volumiogranaten3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 17 14:33:17 volumiogranaten3 systemd[1]: Startup finished in 11.535s (kernel) + 39.018s (userspace) = 50.553s. Oct 17 14:33:17 volumiogranaten3 volumio-streaming-daemon[901]: ############################ Oct 17 14:33:17 volumiogranaten3 volumio-streaming-daemon[901]: # Volumio Streaming Daemon # Oct 17 14:33:17 volumiogranaten3 volumio-streaming-daemon[901]: # Running on port 7777 # Oct 17 14:33:17 volumiogranaten3 volumio-streaming-daemon[901]: ############################ Oct 17 14:33:17 volumiogranaten3 volumio-streaming-daemon[901]: Environment: production Oct 17 14:33:19 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:19 volumiogranaten3 volumio[900]: info: ----- Volumio3 ---- Oct 17 14:33:19 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:19 volumiogranaten3 volumio[900]: info: ----- System startup ---- Oct 17 14:33:19 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:20 volumiogranaten3 volumio[900]: info: MYVOLUMIO Environment detected Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Plugin folders cleanup Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning into folder /volumio/app/plugins/ Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning category audio_interface Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning category miscellanea Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning category music_service Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning category plugins.json Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning category system_controller Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning category user_interface Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Scanning into folder /data/plugins/ Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Plugin folders cleanup completed Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: ----- Core plugins startup ---- Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Adding plugin upnp to MyMusic Plugins Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Loading plugins from folder /data/plugins/ Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Loading plugin "system"... Oct 17 14:33:21 volumiogranaten3 volumio[900]: info: Loading plugin "appearance"... Oct 17 14:33:21 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:33:21 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:21] [connect] Successful connection Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "network"... Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Refreshing Cached IP Addresses Oct 17 14:33:22 volumiogranaten3 sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 14:33:22 volumiogranaten3 sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:22 volumiogranaten3 sudo[925]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "services"... Oct 17 14:33:22 volumiogranaten3 sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 14:33:22 volumiogranaten3 sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "alsa_controller"... Oct 17 14:33:22 volumiogranaten3 sudo[927]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:22 volumiogranaten3 sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 14:33:22 volumiogranaten3 sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "wizard"... Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "volumio_command_line_client"... Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "upnp"... Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: [1666017202678] Starting Upmpd Daemon Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "my_music"... Oct 17 14:33:22 volumiogranaten3 volumio[900]: info: Loading plugin "mpd"... Oct 17 14:33:23 volumiogranaten3 volumio[900]: info: Creating MPD Configuration file Oct 17 14:33:23 volumiogranaten3 volumio[900]: info: Loading plugin "upnp_browser"... Oct 17 14:33:23 volumiogranaten3 sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 14:33:23 volumiogranaten3 sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:23 volumiogranaten3 sudo[936]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:23 volumiogranaten3 sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 14:33:23 volumiogranaten3 sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:23 volumiogranaten3 systemd[1]: Stopping Music Player Daemon... Oct 17 14:33:23 volumiogranaten3 systemd[1]: mpd.service: Succeeded. Oct 17 14:33:23 volumiogranaten3 systemd[1]: Stopped Music Player Daemon. Oct 17 14:33:23 volumiogranaten3 systemd[1]: Starting Music Player Daemon... Oct 17 14:33:23 volumiogranaten3 volumio[900]: info: Loading plugin "networkfs"... Oct 17 14:33:23 volumiogranaten3 systemd[1]: systemd-hostnamed.service: Succeeded. Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Starting Udev Watcher for removable devices Oct 17 14:33:24 volumiogranaten3 sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Granaten3,password=Bertil0691wallin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.234/Musik /mnt/NAS/Remonten3 Oct 17 14:33:24 volumiogranaten3 sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:24 volumiogranaten3 kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 17 14:33:24 volumiogranaten3 kernel: Key type cifs.spnego registered Oct 17 14:33:24 volumiogranaten3 kernel: Key type cifs.idmap registered Oct 17 14:33:24 volumiogranaten3 kernel: CIFS: Attempting to mount //192.168.0.234/Musik Oct 17 14:33:24 volumiogranaten3 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. Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Ignoring mount for partition: boot Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Ignoring mount for partition: volumio Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Ignoring mount for partition: volumio_data Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Loading plugin "alarm-clock"... Oct 17 14:33:24 volumiogranaten3 mpd[943]: Oct 17 14:33 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Loading plugin "airplay_emulation"... Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Starting Shairport Sync Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Loading plugin "last_100"... Oct 17 14:33:24 volumiogranaten3 volumio[900]: info: Loading plugin "webradio"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "i2s_dacs"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "volumiodiscovery"... Oct 17 14:33:25 volumiogranaten3 mpd[943]: Oct 17 14:33 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 17 14:33:25 volumiogranaten3 mpd[943]: Oct 17 14:33 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 17 14:33:25 volumiogranaten3 systemd[1]: Started Music Player Daemon. Oct 17 14:33:25 volumiogranaten3 sudo[938]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:25 volumiogranaten3 sudo[930]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:25 volumiogranaten3 volumio[900]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 14:33:25 volumiogranaten3 volumio[900]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 14:33:25 volumiogranaten3 volumio[900]: *** WARNING *** For more information see Oct 17 14:33:25 volumiogranaten3 node[900]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 14:33:25 volumiogranaten3 volumio[900]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 14:33:25 volumiogranaten3 volumio[900]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 14:33:25 volumiogranaten3 volumio[900]: *** WARNING *** For more information see Oct 17 14:33:25 volumiogranaten3 node[900]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 14:33:25 volumiogranaten3 node[900]: *** WARNING *** For more information see Oct 17 14:33:25 volumiogranaten3 node[900]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 14:33:25 volumiogranaten3 node[900]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 14:33:25 volumiogranaten3 node[900]: *** WARNING *** For more information see Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Discovery: Started advertising with name: Volumiogranaten3 Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "outputs"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "albumart"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Plugin example_plugin is not enabled Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "inputs"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "updater_comm"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Plugin mpdemulation is not enabled Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "rest_api"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading plugin "websocket"... Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: Loading i18n strings for locale sv Oct 17 14:33:25 volumiogranaten3 volumio[900]: Updating browse sources language Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::initPlayerControls Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 14:33:25 volumiogranaten3 volumio[900]: Express server listening on port 3000 Oct 17 14:33:25 volumiogranaten3 volumio[900]: [Metrics] WebUI: 6s 796.32ms Oct 17 14:33:25 volumiogranaten3 volumio[900]: Forking 3 albumart workers Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreStateMachine::resetVolumioState Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreStateMachine::getcurrentVolume Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreStateMachine::pushState Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioPushState Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: MPD Permissions set Oct 17 14:33:25 volumiogranaten3 volumio[900]: verbose: New Socket.io Connection to 192.168.50.216 from 192.168.50.35 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 1 Oct 17 14:33:25 volumiogranaten3 volumio-remote-updater[477]: [2022-10-17 14:33:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1666017201 101 Oct 17 14:33:25 volumiogranaten3 volumio[900]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Oct 17 14:33:25 volumiogranaten3 volumio[900]: info: MPD running with PID943 Oct 17 14:33:25 volumiogranaten3 volumio[900]: ,establishing connection Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:26 volumiogranaten3 volumio[900]: error: updateQueue error: null Oct 17 14:33:26 volumiogranaten3 volumio[900]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreStateMachine::setRepeat null single undefined Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreStateMachine::pushState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioPushState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreStateMachine::setRandom true Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreStateMachine::pushState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioPushState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Setting Device type: Raspberry PI Oct 17 14:33:26 volumiogranaten3 volumio[900]: verbose: New Socket.io Connection to 192.168.50.216 from 192.168.50.35 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 3 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: adding 8fd4021d-a8df-48f3-a348-eaca3cba31da Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: Found device Kök Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: Connecting to remote: 192.168.50.179 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Received Get System Info Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: Getting this device information Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Listing playlists Oct 17 14:33:26 volumiogranaten3 volumio[900]: verbose: New Socket.io Connection to 192.168.50.216:3000 from 192.168.50.179 UA: node-XMLHttpRequest Total Clients: 4 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: adding 284eb5e2-ab27-4158-8053-a978efe40842 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: Found device Volumiogranaten3 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: Getting this device information Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 14:33:26 volumiogranaten3 volumio[900]: info: Discovery: Connected to remote: 192.168.50.179 Oct 17 14:33:26 volumiogranaten3 volumio[900]: Starting albumart workers Oct 17 14:33:26 volumiogranaten3 volumio[900]: Starting albumart workers Oct 17 14:33:27 volumiogranaten3 volumio[900]: Starting albumart workers Oct 17 14:33:27 volumiogranaten3 volumio[900]: verbose: New Socket.io Connection to 192.168.50.216:3000 from 192.168.50.191 UA: Dart/3.5 (dart:io) Total Clients: 5 Oct 17 14:33:29 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:33:29 volumiogranaten3 volumio[900]: error: updateQueue error: null Oct 17 14:33:29 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: Reloading queue from file Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 17 14:33:30 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 17 14:33:31 volumiogranaten3 volumio[900]: info: Discovery: Connected to remote: 192.168.50.179 Oct 17 14:33:31 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:31 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:32 volumiogranaten3 volumio[900]: info: Discovery: Connected to remote: 192.168.50.179 Oct 17 14:33:32 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:32 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:32 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:32 volumiogranaten3 sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 14:33:32 volumiogranaten3 sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:32 volumiogranaten3 sudo[1032]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:32 volumiogranaten3 sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 14:33:32 volumiogranaten3 sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:32 volumiogranaten3 sudo[1035]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:32 volumiogranaten3 sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 14:33:32 volumiogranaten3 sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:32 volumiogranaten3 systemd[1]: Started UPnP Renderer front-end to MPD. Oct 17 14:33:32 volumiogranaten3 sudo[1038]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:32 volumiogranaten3 volumio[900]: info: Upmpdcli Daemon Started Oct 17 14:33:32 volumiogranaten3 volumio[1040]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 17 14:33:33 volumiogranaten3 volumio[900]: info: Discovery: Connected to remote: 192.168.50.179 Oct 17 14:33:33 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:33 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:33 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:33 volumiogranaten3 volumio[900]: info: Discovery: Disconnected from remote: 192.168.50.179 Oct 17 14:33:34 volumiogranaten3 sudo[948]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Cannot mount NAS Remonten3 at system boot, trial number 1 ,retrying in 5 seconds Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Completed loading Core Plugins Oct 17 14:33:34 volumiogranaten3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 17 14:33:34 volumiogranaten3 kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Preparing to generate the ALSA configuration file Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Reading ALSA contributions from plugins. Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Asound.conf file unchanged, so no further update is needed Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Output device has changed, restarting MPD Oct 17 14:33:34 volumiogranaten3 sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Output device has changed, restarting Shairport Sync Oct 17 14:33:34 volumiogranaten3 sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:34 volumiogranaten3 sudo[1057]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:34 volumiogranaten3 sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 14:33:34 volumiogranaten3 sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:34 volumiogranaten3 systemd[1]: Stopping Music Player Daemon... Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: ___________ START PLUGINS ___________ Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: [1666017214705] CoreMusicLibrary::Adding element Media Servers Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 14:33:34 volumiogranaten3 systemd[1]: mpd.service: Succeeded. Oct 17 14:33:34 volumiogranaten3 systemd[1]: Stopped Music Player Daemon. Oct 17 14:33:34 volumiogranaten3 volumio[900]: Cannot find translation for sourceMedia Servers Oct 17 14:33:34 volumiogranaten3 systemd[1]: Starting Music Player Daemon... Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: [1666017214894] CoreMusicLibrary::Adding element Last_100 Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 14:33:34 volumiogranaten3 volumio[900]: Cannot find translation for sourceMedia Servers Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: [1666017214900] CoreMusicLibrary::Adding element Webradio Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 14:33:34 volumiogranaten3 volumio[900]: Cannot find translation for sourceMedia Servers Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 14:33:34 volumiogranaten3 volumio[900]: info: Volumio Calling Home Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Completed starting Core Plugins Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: ----- MyVolumio plugins startup ---- Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: ------------------------------------------- Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: MPD Permissions set Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Volumio called home Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Discovery: Connected to remote: 192.168.50.179 Oct 17 14:33:35 volumiogranaten3 volumio[900]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Starting Shairport Sync Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Starting Shairport Sync Oct 17 14:33:35 volumiogranaten3 sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 14:33:35 volumiogranaten3 sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Starting Shairport Sync Oct 17 14:33:35 volumiogranaten3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 14:33:35 volumiogranaten3 systemd[1]: shairport-sync.service: Succeeded. Oct 17 14:33:35 volumiogranaten3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 14:33:35 volumiogranaten3 sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 14:33:35 volumiogranaten3 sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:35 volumiogranaten3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 14:33:35 volumiogranaten3 sudo[1083]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:35 volumiogranaten3 sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 14:33:35 volumiogranaten3 sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:35 volumiogranaten3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 14:33:35 volumiogranaten3 systemd[1]: shairport-sync.service: Succeeded. Oct 17 14:33:35 volumiogranaten3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 14:33:35 volumiogranaten3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 14:33:35 volumiogranaten3 sudo[1088]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:35 volumiogranaten3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 14:33:35 volumiogranaten3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 17 14:33:35 volumiogranaten3 systemd[1]: shairport-sync.service: Succeeded. Oct 17 14:33:35 volumiogranaten3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 14:33:35 volumiogranaten3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 14:33:35 volumiogranaten3 sudo[1087]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Shairport-Sync Started Oct 17 14:33:35 volumiogranaten3 volumio[900]: Error adding Membership: Error: addMembership EINVAL Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Shairport-Sync Started Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: Shairport-Sync Started Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:35 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:36 volumiogranaten3 mpd[1076]: Oct 17 14:33 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 14:33:36 volumiogranaten3 mpd[1076]: Oct 17 14:33 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 17 14:33:36 volumiogranaten3 mpd[1076]: Oct 17 14:33 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 17 14:33:36 volumiogranaten3 systemd[1]: Started Music Player Daemon. Oct 17 14:33:36 volumiogranaten3 sudo[1059]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:36 volumiogranaten3 volumio[900]: error: updateQueue error: null Oct 17 14:33:36 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:33:37 volumiogranaten3 volumio[1040]: ..................................++++ Oct 17 14:33:39 volumiogranaten3 sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Granaten3,password=Bertil0691wallin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.234/Musik /mnt/NAS/Remonten3 Oct 17 14:33:39 volumiogranaten3 sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:39 volumiogranaten3 kernel: CIFS: Attempting to mount //192.168.0.234/Musik Oct 17 14:33:44 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:33:49 volumiogranaten3 sudo[1101]: pam_unix(sudo:session): session closed for user root Oct 17 14:33:49 volumiogranaten3 volumio[900]: info: Cannot mount NAS Remonten3 at system boot, trial number 2 ,retrying in 5 seconds Oct 17 14:33:49 volumiogranaten3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 17 14:33:49 volumiogranaten3 kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 17 14:33:51 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:33:52 volumiogranaten3 volumio[1040]: ....................................................................................................................++++ Oct 17 14:33:52 volumiogranaten3 volumio[1040]: e is 65537 (0x010001) Oct 17 14:33:52 volumiogranaten3 volumio[1040]: writing RSA key Oct 17 14:33:53 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 14:33:53 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 14:33:53 volumiogranaten3 volumio[900]: info: Discovery: Getting this device information Oct 17 14:33:53 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumioGetState Oct 17 14:33:53 volumiogranaten3 volumio[900]: info: CorePlayQueue::getTrack 0 Oct 17 14:33:53 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 14:33:54 volumiogranaten3 sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Granaten3,password=Bertil0691wallin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.234/Musik /mnt/NAS/Remonten3 Oct 17 14:33:54 volumiogranaten3 sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 14:33:54 volumiogranaten3 kernel: CIFS: Attempting to mount //192.168.0.234/Musik Oct 17 14:33:55 volumiogranaten3 volumio[900]: error: MyVolumio Plugin failed to start in a timely fashion Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: BOOT COMPLETED Oct 17 14:33:55 volumiogranaten3 volumio[900]: [Metrics] CommandRouter: 35s 298.95ms Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: CoreCommandRouter::volumiosetStartupVolume Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: CoreCommandRouter::Close All Modals sent Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: CoreCommandRouter::Close All Modals sent Oct 17 14:33:55 volumiogranaten3 volumio[900]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 17 14:33:55 volumiogranaten3 volumio[900]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:1638:(_snd_pcm_volumioswitch_open) Failed to open control fifo Oct 17 14:33:55 volumiogranaten3 volumio[900]: aplay: main:828: audio open error: No such file or directory Oct 17 14:33:55 volumiogranaten3 ntpd[632]: Soliciting pool server 193.182.111.13 Oct 17 14:33:56 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 17 14:33:56 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 14:33:56 volumiogranaten3 volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 17 14:33:56 volumiogranaten3 ntpd[632]: Soliciting pool server 194.58.207.20 Oct 17 14:33:56 volumiogranaten3 ntpd[632]: Soliciting pool server 194.58.205.20 Oct 17 14:33:57 volumiogranaten3 ntpd[632]: Soliciting pool server 176.126.86.247 Oct 17 14:33:57 volumiogranaten3 ntpd[632]: Soliciting pool server 194.58.202.20 Oct 17 14:33:57 volumiogranaten3 ntpd[632]: Soliciting pool server 192.36.143.134 Oct 17 14:33:57 volumiogranaten3 ntpd[632]: Soliciting pool server 192.36.143.130 Oct 17 14:33:58 volumiogranaten3 ntpd[632]: Soliciting pool server 150.241.82.187 Oct 17 14:33:59 volumiogranaten3 wpa_supplicant[780]: wlan0: Failed to initiate sched scan Oct 17 14:34:01 volumiogranaten3 volumio[900]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 14:34:01 volumiogranaten3 volumio[900]: Error: certificate is not yet valid Oct 17 14:34:01 volumiogranaten3 volumio[900]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Oct 17 14:34:01 volumiogranaten3 volumio[900]: at TLSSocket.emit (events.js:315:20) Oct 17 14:34:01 volumiogranaten3 volumio[900]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Oct 17 14:34:01 volumiogranaten3 volumio[900]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Oct 17 14:34:01 volumiogranaten3 volumio[900]: code: 'CERT_NOT_YET_VALID' Oct 17 14:34:01 volumiogranaten3 volumio[900]: } Oct 17 14:34:01 volumiogranaten3 volumio[900]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 14:34:02 volumiogranaten3 sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-10-17 14:33 Oct 17 14:34:02 volumiogranaten3 sudo[1142]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"