-- Logs begin at Sat 2024-04-27 13:21:36 EDT, end at Sat 2024-04-27 13:29:31 EDT. -- Apr 27 13:28:00 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:00 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:00 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:00 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:01 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:01 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:01 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:01 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:02 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:02 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:02 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:02 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:03 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:03 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:03 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:03 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:04 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:04 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:04 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:04 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:05 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:05 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:05 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:05 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:06 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:06 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:06 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:06 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:07 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:07 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:07 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:07 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:08 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:08 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:08 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:08 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:09 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:09 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:09 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:09 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:10 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:10 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:10 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:10 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:11 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:11 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:11 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:11 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:12 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:12 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:12 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:12 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:13 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:13 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:13 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:13 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:14 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:14 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:14 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:14 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:15 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:15 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:15 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:15 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:16 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:16 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:16 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:16 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:17 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:17 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:17 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:17 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:18 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:18 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:18 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:18 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:19 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:19 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:19 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:19 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:20 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:20 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:20 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:20 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:21 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:21 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:21 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:21 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:22 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:22 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:22 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:22 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:23 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:23 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:23 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:23 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:23 volumac volumio-remote-updater[486]: [2024-04-27 13:28:23] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 27 13:28:23 volumac volumio-remote-updater[486]: [2024-04-27 13:28:23] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Apr 27 13:28:23 volumac volumio-remote-updater[486]: [2024-04-27 13:28:23] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Apr 27 13:28:23 volumac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 27 13:28:23 volumac systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 27 13:28:23 volumac systemd[1]: Started dynamicswap service. Apr 27 13:28:23 volumac systemd[1]: dynamicswap.service: Succeeded. Apr 27 13:28:23 volumac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 27 13:28:23 volumac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Apr 27 13:28:23 volumac systemd[1]: Started dynamicswap service. Apr 27 13:28:23 volumac systemd[1]: Stopped Volumio Backend Module. Apr 27 13:28:23 volumac systemd[1]: Started Volumio Backend Module. Apr 27 13:28:23 volumac systemd[1]: dynamicswap.service: Succeeded. Apr 27 13:28:23 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:28:23 volumac volumio[2607]: info: ----- Volumio3 ---- Apr 27 13:28:23 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:28:23 volumac volumio[2607]: info: ----- System startup ---- Apr 27 13:28:23 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:28:24 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:24 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:24 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:24 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:24 volumac volumio[2607]: info: MYVOLUMIO Environment detected Apr 27 13:28:24 volumac volumio[2607]: info: Plugin folders cleanup Apr 27 13:28:24 volumac volumio[2607]: info: Scanning into folder /volumio/app/plugins/ Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category audio_interface Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category miscellanea Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category music_service Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category plugins.json Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category system_controller Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category user_interface Apr 27 13:28:24 volumac volumio[2607]: info: Scanning into folder /data/plugins/ Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category audio_interface Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category miscellanea Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category music_service Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category system_controller Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category system_hardware Apr 27 13:28:24 volumac volumio[2607]: info: Scanning category user_interface Apr 27 13:28:24 volumac volumio[2607]: info: Plugin folders cleanup completed Apr 27 13:28:24 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:28:24 volumac volumio[2607]: info: ----- Core plugins startup ---- Apr 27 13:28:24 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugins from folder /volumio/app/plugins/ Apr 27 13:28:24 volumac volumio[2607]: info: Adding plugin upnp to MyMusic Plugins Apr 27 13:28:24 volumac volumio[2607]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 27 13:28:24 volumac volumio[2607]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugins from folder /data/plugins/ Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "system"... Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "appearance"... Apr 27 13:28:24 volumac go-librespot[2593]: time="2024-04-27T13:28:24-04:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: i/o timeout" Apr 27 13:28:24 volumac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 13:28:24 volumac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "network"... Apr 27 13:28:24 volumac volumio[2607]: info: Refreshing Cached IP Addresses Apr 27 13:28:24 volumac sudo[2634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 13:28:24 volumac sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:24 volumac sudo[2634]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:24 volumac sudo[2636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "services"... Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "alsa_controller"... Apr 27 13:28:24 volumac sudo[2636]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:24 volumac sudo[2636]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:24 volumac sudo[2639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 27 13:28:24 volumac sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:24 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "wizard"... Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "networkfs"... Apr 27 13:28:24 volumac volumio[2607]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Apr 27 13:28:24 volumac volumio[2607]: info: Starting Udev Watcher for removable devices Apr 27 13:28:24 volumac volumio[2607]: info: Ignoring mount for partition: boot Apr 27 13:28:24 volumac volumio[2607]: info: Ignoring mount for partition: volumio Apr 27 13:28:24 volumac volumio[2607]: info: Ignoring mount for partition: volumio_data Apr 27 13:28:24 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "volumio_command_line_client"... Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "upnp"... Apr 27 13:28:24 volumac volumio[2607]: info: [1714238904949] Starting Upmpd Daemon Apr 27 13:28:24 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "my_music"... Apr 27 13:28:24 volumac volumio[2607]: info: Loading plugin "mpd"... Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "upnp_browser"... Apr 27 13:28:25 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:25 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:25 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:25 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "alarm-clock"... Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "airplay_emulation"... Apr 27 13:28:25 volumac volumio[2607]: info: Starting Shairport Sync Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "last_100"... Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "webradio"... Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "i2s_dacs"... Apr 27 13:28:25 volumac volumio[2607]: info: I2S DAC not set, start Auto-detection Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "volumiodiscovery"... Apr 27 13:28:25 volumac volumio[2607]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 13:28:25 volumac node[2607]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 13:28:25 volumac volumio[2607]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 13:28:25 volumac node[2607]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 13:28:25 volumac volumio[2607]: *** WARNING *** For more information see Apr 27 13:28:25 volumac node[2607]: *** WARNING *** For more information see Apr 27 13:28:25 volumac volumio[2607]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 13:28:25 volumac node[2607]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 13:28:25 volumac volumio[2607]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 13:28:25 volumac node[2607]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 13:28:25 volumac volumio[2607]: *** WARNING *** For more information see Apr 27 13:28:25 volumac node[2607]: *** WARNING *** For more information see Apr 27 13:28:25 volumac volumio[2607]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 27 13:28:25 volumac volumio[2607]: info: Discovery: Started advertising with name: Volumac Apr 27 13:28:25 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "calmradio"... Apr 27 13:28:25 volumac volumio[2607]: info: Loading plugin "spop"... Apr 27 13:28:26 volumac volumio[2607]: info: Loading plugin "youtube2"... Apr 27 13:28:26 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:26 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:26 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:26 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:26 volumac volumio[2607]: info: Loading plugin "ytmusic"... Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "autostart"... Apr 27 13:28:27 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:27 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:27 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:27 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:27 volumac volumio[2607]: info: Applying required configuration parameters for plugin autostart Apr 27 13:28:27 volumac volumio[2607]: info: AutoStart - onVolumioStart - read config.json Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "outputs"... Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "albumart"... Apr 27 13:28:27 volumac volumio[2607]: info: Plugin example_plugin is not enabled Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "inputs"... Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "updater_comm"... Apr 27 13:28:27 volumac volumio[2607]: info: Plugin mpdemulation is not enabled Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "rest_api"... Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "websocket"... Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "fusiondsp"... Apr 27 13:28:27 volumac volumio[2607]: Forking 1 albumart workers Apr 27 13:28:27 volumac volumio[2607]: info: Applying required configuration parameters for plugin fusiondsp Apr 27 13:28:27 volumac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 13:28:27 volumac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Apr 27 13:28:27 volumac systemd[1]: Stopped go-librespot Daemon. Apr 27 13:28:27 volumac systemd[1]: Started go-librespot Daemon. Apr 27 13:28:27 volumac go-librespot[2662]: Librespot-go daemon starting... Apr 27 13:28:27 volumac go-librespot[2662]: time="2024-04-27T13:28:27-04:00" level=info msg="generated new device id: 7279894ce1e43d0373cb36c067230c7595c25f56" Apr 27 13:28:27 volumac go-librespot[2662]: time="2024-04-27T13:28:27-04:00" level=debug msg="stored credentials found for 1249171035" Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "m3u_importer"... Apr 27 13:28:27 volumac volumio[2607]: info: Applying required configuration parameters for plugin m3u_importer Apr 27 13:28:27 volumac volumio[2607]: info: Loading plugin "80s80s"... Apr 27 13:28:27 volumac volumio[2607]: Starting albumart workers Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin 80s80s Apr 27 13:28:28 volumac volumio[2607]: info: [1714238908023] [80s80s] API delay: 30 Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "minidlna"... Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin minidlna Apr 27 13:28:28 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:28 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:28 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:28 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:28 volumac volumio-remote-updater[486]: [2024-04-27 13:28:28] [connect] Successful connection Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "nowyswiat"... Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin nowyswiat Apr 27 13:28:28 volumac volumio[2607]: info: [1714238908342] [RadioNowySwiat] API delay: 5 Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "pandora"... Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin pandora Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "rad357pl"... Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin rad357pl Apr 27 13:28:28 volumac volumio[2607]: info: [1714238908795] [rad357pl] API delay: 5 Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "radrocksrvfm"... Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin radrocksrvfm Apr 27 13:28:28 volumac volumio[2607]: info: [1714238908859] [radrocksrvfm] API delay: 5 Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "swarmstation"... Apr 27 13:28:28 volumac volumio[2607]: info: Applying required configuration parameters for plugin swarmstation Apr 27 13:28:28 volumac volumio[2607]: info: [1714238908950] [Swarmstation] API delay: 5 Apr 27 13:28:28 volumac volumio[2607]: info: Loading plugin "tunein_radio"... Apr 27 13:28:29 volumac volumio[2607]: info: Applying required configuration parameters for plugin tunein_radio Apr 27 13:28:29 volumac volumio[2607]: info: Loading plugin "volusonic"... Apr 27 13:28:29 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:29 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:29 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:29 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:29 volumac volumio[2607]: info: Applying required configuration parameters for plugin volusonic Apr 27 13:28:29 volumac volumio[2607]: info: Loading plugin "crossfader_switch"... Apr 27 13:28:29 volumac volumio[2607]: info: Applying required configuration parameters for plugin crossfader_switch Apr 27 13:28:29 volumac volumio[2607]: info: Loading plugin "ir_controller"... Apr 27 13:28:29 volumac volumio[2607]: info: Applying required configuration parameters for plugin ir_controller Apr 27 13:28:29 volumac volumio[2607]: info: Loading plugin "music_services_shield"... Apr 27 13:28:29 volumac volumio[2607]: info: Applying required configuration parameters for plugin music_services_shield Apr 27 13:28:29 volumac volumio[2607]: info: Loading plugin "lastfm"... Apr 27 13:28:29 volumac volumio[2607]: info: Loading plugin "randomizer"... Apr 27 13:28:29 volumac volumio[2607]: info: Applying required configuration parameters for plugin randomizer Apr 27 13:28:29 volumac volumio[2607]: info: Loading i18n strings for locale en Apr 27 13:28:29 volumac volumio[2607]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 27 13:28:29 volumac volumio[2607]: Updating browse sources language Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::initPlayerControls Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: Express server listening on port 3000 Apr 27 13:28:29 volumac volumio[2607]: [Metrics] WebUI: 6s 142.52ms Apr 27 13:28:29 volumac volumio[2607]: info: Setting Device type: x86 Apr 27 13:28:29 volumac volumio[2607]: info: CoreStateMachine::resetVolumioState Apr 27 13:28:29 volumac volumio[2607]: info: CoreStateMachine::getcurrentVolume Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 13:28:29 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:29 volumac volumio[2607]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 27 13:28:29 volumac volumio[2607]: info: Completed loading Core Plugins Apr 27 13:28:29 volumac volumio[2607]: info: Preparing to generate the ALSA configuration file Apr 27 13:28:30 volumac volumio[2607]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 27 13:28:30 volumac volumio[2607]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Apr 27 13:28:30 volumac volumio[2607]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 27 13:28:30 volumac volumio[2607]: info: Reading ALSA contributions from plugins. Apr 27 13:28:30 volumac volumio-remote-updater[486]: [2024-04-27 13:28:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714238908 101 Apr 27 13:28:30 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:30 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:30 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:30 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:30 volumac volumio[2607]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 27 13:28:30 volumac volumio[2607]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 27 13:28:30 volumac volumio[2607]: info: Reloading queue from file Apr 27 13:28:30 volumac volumio[2607]: info: VolumeController:: Volume=30 Mute =false Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:28:30 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::updateTrackBlock Apr 27 13:28:30 volumac volumio[2607]: info: CorePlayQueue::getTrackBlock Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::setRepeat null single undefined Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:28:30 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::setRandom null Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:28:30 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:28:30 volumac volumio[2607]: info: VolumeController:: Volume=30 Mute =false Apr 27 13:28:30 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:28:30 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:28:30 volumac volumio[2607]: info: Asound.conf file unchanged, so no further update is needed Apr 27 13:28:30 volumac volumio[2607]: info: Output device has changed, restarting MPD Apr 27 13:28:30 volumac sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 13:28:30 volumac sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:30 volumac sudo[2685]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:30 volumac volumio[2607]: info: Output device has changed, restarting Shairport Sync Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:30 volumac sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 13:28:30 volumac sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:30 volumac systemd[1]: musicservicesshield.service: Succeeded. Apr 27 13:28:30 volumac systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 27 13:28:30 volumac systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 27 13:28:30 volumac systemd[1]: Stopping Music Player Daemon... Apr 27 13:28:30 volumac volumio[2607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 13:28:30 volumac volumio[2607]: info: ___________ START PLUGINS ___________ Apr 27 13:28:30 volumac volumio[2607]: info: ControllerMpd::onStart: Initializing MPD Apr 27 13:28:30 volumac volumio[2607]: info: Creating MPD Configuration file Apr 27 13:28:30 volumac sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 13:28:30 volumac sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:30 volumac sudo[2694]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910717] CoreMusicLibrary::Adding element Media Servers Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac sudo[2697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:30 volumac sudo[2697]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910816] CoreMusicLibrary::Adding element Last_100 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910818] CoreMusicLibrary::Adding element Webradio Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 13:28:30 volumac volumio[2607]: info: Adding Calm Radio to Browse Sources Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910823] CoreMusicLibrary::Adding element Calm Radio Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: info: Creating Spotify config file Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910862] CoreMusicLibrary::Adding element YouTube2 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910866] CoreMusicLibrary::Adding element YouTube Music Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: info: AutoStart - onStart Apr 27 13:28:30 volumac volumio[2607]: info: Loading i18n strings for locale en Apr 27 13:28:30 volumac volumio[2607]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:30 volumac volumio[2607]: info: FusionDsp - mixtype--------------------- Hardware Apr 27 13:28:30 volumac volumio[2607]: info: Loading i18n strings for locale en Apr 27 13:28:30 volumac volumio[2607]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910885] CoreMusicLibrary::Adding element 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: info: Loading i18n strings for locale en Apr 27 13:28:30 volumac volumio[2607]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910920] CoreMusicLibrary::Adding element Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910923] CoreMusicLibrary::Adding element Pandora Radio Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910929] ControllerPandora::initializeMQTT Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910929] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910931] CoreMusicLibrary::Adding element RADIO 357 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910937] CoreMusicLibrary::Adding element Radio Rockserwis FM Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910948] CoreMusicLibrary::Adding element SWARM Station Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:30 volumac volumio[2607]: info: TuneIn addToBrowseSources Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:30 volumac volumio[2607]: info: [1714238910950] CoreMusicLibrary::Adding element TuneIn Radio Apr 27 13:28:30 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:30 volumac volumio[2607]: Cannot find translation for source TuneIn Radio Apr 27 13:28:30 volumac volumio[2607]: info: [object Object] Apr 27 13:28:30 volumac volumio[2607]: info: Loading i18n strings for locale en Apr 27 13:28:31 volumac volumio[2607]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 27 13:28:31 volumac volumio[2607]: Updating browse sources language Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source TuneIn Radio Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source TuneIn Radio Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911004] CoreMusicLibrary::Adding element Volusonic Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source TuneIn Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Volusonic Apr 27 13:28:31 volumac volumio[2607]: info: Loading i18n strings for locale en Apr 27 13:28:31 volumac volumio[2607]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 13:28:31 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:31 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:31 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:31 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:31 volumac sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Apr 27 13:28:31 volumac sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:31 volumac sudo[2710]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911316] CoreMusicLibrary::Adding element LastFM Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source TuneIn Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Volusonic Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source LastFM Apr 27 13:28:31 volumac volumio[2607]: info: [LastFM] scrobbler initiated! Apr 27 13:28:31 volumac volumio[2607]: info: [LastFM] extended logging: false Apr 27 13:28:31 volumac volumio[2607]: info: [LastFM] try scrobble stream/radio plays: false Apr 27 13:28:31 volumac volumio[2607]: info: [LastFM] Left init routine Apr 27 13:28:31 volumac volumio[2607]: info: [LastFM] Socket already connected: true Apr 27 13:28:31 volumac sudo[2738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Apr 27 13:28:31 volumac sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911349] CoreMusicLibrary::Adding element Randomizer Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Calm Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube2 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source YouTube Music Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source 80s80s Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Nowy Swiat Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Pandora Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source RADIO 357 Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Radio Rockserwis FM Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source SWARM Station Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source TuneIn Radio Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Volusonic Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source LastFM Apr 27 13:28:31 volumac volumio[2607]: Cannot find translation for source Randomizer Apr 27 13:28:31 volumac volumio[2607]: info: Volumio Calling Home Apr 27 13:28:31 volumac systemd[1]: mpd.service: Succeeded. Apr 27 13:28:31 volumac systemd[1]: Stopped Music Player Daemon. Apr 27 13:28:31 volumac systemd[1]: Starting Music Player Daemon... Apr 27 13:28:31 volumac volumio[2607]: info: Preparing to generate the ALSA configuration file Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911614] ControllerPandora::initialSetup Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911629] ControllerPandora::PandoraHandler::init Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911631] ControllerPandora::PandoraHandler::loadErrorCodes Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911650] [Pandora] PandoraHandler::setMaxStationTracks: 16 Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911658] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911659] ControllerPandora::flushPandora Apr 27 13:28:31 volumac volumio[2607]: info: CorePlayQueue::getQueue Apr 27 13:28:31 volumac volumio[2607]: info: CorePlayQueue::clearAddPlayQueue Apr 27 13:28:31 volumac volumio[2607]: info: CorePlayQueue::saveQueue Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::serviceClearAddPlayTracks Apr 27 13:28:31 volumac volumio[2607]: error: WARNING: No clearAddPlayTracks method for service mpd Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::volumioPushQueue Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911676] ControllerPandora::validateAndSetAccountOptions Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911677] ControllerPandora::PandoraHandler::setAccountOptions Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911677] ControllerPandora::flushPandora Apr 27 13:28:31 volumac volumio[2607]: info: CorePlayQueue::getQueue Apr 27 13:28:31 volumac volumio[2607]: info: CorePlayQueue::clearAddPlayQueue Apr 27 13:28:31 volumac volumio[2607]: info: CorePlayQueue::saveQueue Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::serviceClearAddPlayTracks Apr 27 13:28:31 volumac volumio[2607]: error: WARNING: No clearAddPlayTracks method for service mpd Apr 27 13:28:31 volumac volumio[2607]: info: CoreCommandRouter::volumioPushQueue Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911689] [Pandora] PreventAuthTimeout::init: Timer loaded Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911695] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911695] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Apr 27 13:28:31 volumac volumio[2607]: info: [1714238911696] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Apr 27 13:28:31 volumac volumio[2607]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 27 13:28:31 volumac volumio[2607]: info: Reading ALSA contributions from plugins. Apr 27 13:28:31 volumac volumio[2607]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Apr 27 13:28:31 volumac volumio[2607]: info: minidlna: Found miniDLNA version 1.2.1 Apr 27 13:28:31 volumac volumio[2607]: info: minidlna: Starting minidlna.service Apr 27 13:28:31 volumac volumio[2607]: info: MPD Permissions set Apr 27 13:28:31 volumac volumio[2607]: info: MPD Permissions set Apr 27 13:28:31 volumac sudo[2759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Apr 27 13:28:31 volumac sudo[2759]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:32 volumac systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Apr 27 13:28:32 volumac sudo[2759]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:32 volumac mpd[2748]: Apr 27 13:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 13:28:32 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:32 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:32 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:32 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:32 volumac systemd[1]: Started Music Player Daemon. Apr 27 13:28:32 volumac sudo[2688]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:32 volumac sudo[2697]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:32 volumac systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 27 13:28:32 volumac volumio[2607]: info: Spotify config file written Apr 27 13:28:32 volumac sudo[2738]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:32 volumac systemd[1]: minidlna.service: Succeeded. Apr 27 13:28:32 volumac sudo[2781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 27 13:28:32 volumac sudo[2781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:32 volumac systemd[1]: Stopping go-librespot Daemon... Apr 27 13:28:32 volumac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 27 13:28:32 volumac systemd[1]: go-librespot-daemon.service: Succeeded. Apr 27 13:28:32 volumac systemd[1]: Stopped go-librespot Daemon. Apr 27 13:28:32 volumac systemd[1]: Started go-librespot Daemon. Apr 27 13:28:32 volumac go-librespot[2784]: Librespot-go daemon starting... Apr 27 13:28:32 volumac sudo[2781]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:32 volumac volumio[2607]: info: minidlna: systemctl start minidlna.service succeeded. Apr 27 13:28:32 volumac go-librespot[2784]: time="2024-04-27T13:28:32-04:00" level=info msg="generated new device id: 681cdaebc3b5b3457bf4781322d2d7eaa914e81b" Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac go-librespot[2784]: time="2024-04-27T13:28:32-04:00" level=debug msg="stored credentials found for 1249171035" Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 13:28:32 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:32 volumac volumio[2768]: cset: --> shielding system active with Apr 27 13:28:32 volumac volumio[2768]: cset: "system" cpuset of CPUSPEC(1) with 147 tasks running Apr 27 13:28:32 volumac volumio[2768]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Apr 27 13:28:32 volumac sudo[2810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Apr 27 13:28:32 volumac sudo[2810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:32 volumac systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 27 13:28:32 volumac volumio[2607]: error: MPD error: The expression evaluated to a falsy value: Apr 27 13:28:32 volumac volumio[2607]: assert.ok(self.idling) Apr 27 13:28:32 volumac volumio[2607]: error: The expression evaluated to a falsy value: Apr 27 13:28:32 volumac volumio[2607]: assert.ok(self.idling) Apr 27 13:28:32 volumac systemd[1]: Reloading. Apr 27 13:28:32 volumac volumio[2607]: info: MPD running with PID2748 Apr 27 13:28:32 volumac volumio[2607]: ,establishing connection Apr 27 13:28:32 volumac volumio[2607]: error: updateQueue error: null Apr 27 13:28:32 volumac volumio[2607]: info: Starting Shairport Sync Apr 27 13:28:32 volumac volumio[2607]: info: Starting Shairport Sync Apr 27 13:28:32 volumac sudo[2823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 13:28:32 volumac sudo[2823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:32 volumac volumio[2607]: info: Starting Shairport Sync Apr 27 13:28:32 volumac sudo[2834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 13:28:32 volumac sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:33 volumac sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 13:28:33 volumac sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:33 volumac volumio[2607]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 27 13:28:33 volumac volumio[2607]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 27 13:28:33 volumac volumio[2607]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 27 13:28:33 volumac volumio[2607]: info: camilladsp spawned new process with pid 2850, instance 1, run: true Apr 27 13:28:33 volumac volumio[2607]: info: camilladsp service started and running in background, instance 1 Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:33 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:33 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:33 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:33 volumac volumio[2607]: cannot open device 'volumioHw': Device or resource busy Apr 27 13:28:33 volumac volumio[2607]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 27 13:28:33 volumac volumio[2607]: cannot open device 'volumioHw': Device or resource busy Apr 27 13:28:33 volumac volumio[2607]: info: FusionDsp loaded Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 13:28:33 volumac volumio[2607]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 27 13:28:33 volumac 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. Apr 27 13:28:33 volumac sudo[2860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 27 13:28:33 volumac sudo[2860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:33 volumac volumio[2607]: info: Adding Signal Path Element [object Object] Apr 27 13:28:33 volumac volumio[2607]: info: Adding fusiondspeq DSP Signal Path Element Apr 27 13:28:33 volumac volumio[2607]: info: FusionDsp - ---- installed callbackRead Apr 27 13:28:33 volumac volumio[2607]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 13:28:33 volumac volumio[2607]: info: ir_controller: LIRC correctly updated. Apr 27 13:28:33 volumac volumio[2607]: error: updateQueue error: null Apr 27 13:28:33 volumac volumio[2607]: info: Asound.conf file unchanged, so no further update is needed Apr 27 13:28:33 volumac volumio[2607]: info: Output device has changed, restarting MPD Apr 27 13:28:33 volumac sudo[2863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 13:28:33 volumac sudo[2863]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:33 volumac volumio[2607]: info: Output device has changed, restarting Shairport Sync Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:33 volumac sudo[2863]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac sudo[2866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 13:28:33 volumac sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:33 volumac volumio[2607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 13:28:33 volumac volumio[2607]: info: MPD Permissions set Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 13:28:33 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:28:33 volumac volumio[2607]: info: Starting Shairport Sync Apr 27 13:28:33 volumac sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 13:28:33 volumac sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:33 volumac sudo[2810]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac volumio[2607]: info: succeeded Apr 27 13:28:33 volumac systemd[1]: musicservicesshield.service: Succeeded. Apr 27 13:28:33 volumac systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 27 13:28:33 volumac systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 27 13:28:33 volumac systemd[1]: Stopping Music Player Daemon... Apr 27 13:28:33 volumac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 13:28:33 volumac systemd[1]: shairport-sync.service: Succeeded. Apr 27 13:28:33 volumac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 13:28:33 volumac systemd[1]: mpd.service: Succeeded. Apr 27 13:28:33 volumac systemd[1]: Stopped Music Player Daemon. Apr 27 13:28:33 volumac sudo[2860]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac systemd[1]: Starting Music Player Daemon... Apr 27 13:28:33 volumac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 13:28:33 volumac sudo[2834]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac volumio[2607]: info: Shairport-Sync Started Apr 27 13:28:33 volumac volumio[2607]: Error adding Membership: Error: addMembership EINVAL Apr 27 13:28:33 volumac sudo[2823]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac sudo[2842]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac sudo[2878]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:33 volumac volumio[2607]: info: Shairport-Sync Started Apr 27 13:28:33 volumac volumio[2607]: info: Shairport-Sync Started Apr 27 13:28:33 volumac volumio[2607]: info: Shairport-Sync Started Apr 27 13:28:33 volumac sudo[2639]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:34 volumac lircd[2410]: lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2410]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:34 volumac lircd[2410]: lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:34 volumac lircd-0.10.1[2410]: default_init(): No such file or directory Apr 27 13:28:34 volumac mpd[2880]: Apr 27 13:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 13:28:34 volumac systemd[1]: Started Music Player Daemon. Apr 27 13:28:34 volumac sudo[2866]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:34 volumac volumio[2607]: error: updateQueue error: null Apr 27 13:28:34 volumac systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 27 13:28:34 volumac sudo[2889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Apr 27 13:28:34 volumac sudo[2889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:34 volumac systemd[1]: Stopping Flexible IR remote input/output application support... Apr 27 13:28:34 volumac lircd[2410]: lircd-0.10.1[2410]: Notice: caught signal Apr 27 13:28:34 volumac lircd-0.10.1[2410]: Notice: caught signal Apr 27 13:28:34 volumac systemd[1]: irexec.service: Succeeded. Apr 27 13:28:34 volumac systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Apr 27 13:28:34 volumac systemd[1]: lircd.service: Succeeded. Apr 27 13:28:34 volumac systemd[1]: Stopped Flexible IR remote input/output application support. Apr 27 13:28:34 volumac systemd[1]: Starting lircd(8) initialization helper tool... Apr 27 13:28:34 volumac lircd-setup[2899]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Apr 27 13:28:34 volumac lircd-setup[2899]: parser = configparser.SafeConfigParser() Apr 27 13:28:34 volumac systemd[1]: lircd-setup.service: Succeeded. Apr 27 13:28:34 volumac systemd[1]: Started lircd(8) initialization helper tool. Apr 27 13:28:34 volumac systemd[1]: Starting Flexible IR remote input/output application support... Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Info: lircd: Opening log, level: Info Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Version: lircd 0.10.1 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: System info: Linux volumac 6.6.25-volumio #2 SMP PREEMPT_DYNAMIC Sun Apr 7 20:18:18 CEST 2024 x86_64 GNU/Linux Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Info: Initial device: /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Warning: Cannot access device: /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Info: Initial device: /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Warning: Cannot access device: /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: driver: default Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: output: /var/run/lirc/lircd Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: nodaemon: 1 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: plugindir: /usr/lib/x86_64-linux-gnu/lirc/plugins Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: logfile: syslog Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: immediate-init: 0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: permission: 666 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: driver-options: Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: listen: 0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: connect: (null) Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: userelease: 0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: effective_user: (null) Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: release_suffix: _EVUP Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: allow_simulate: 0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: repeat_max: 600 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: configfile: /etc/lirc/lircd.conf Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Options: dynamic_codes: (null) Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Current driver: default Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Driver API version: 3 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Driver version: 0.10.0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Info: lircd: Opening log, level: Info Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Info: lircd: Opening log, level: Info Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Notice: Using systemd fd Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: Using systemd fd Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Warning: Running as root Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Warning: Running as root Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Info: Using remote: iremote. Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Info: Using remote: iremote. Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 27 13:28:34 volumac systemd[1]: Started Flexible IR remote input/output application support. Apr 27 13:28:34 volumac sudo[2889]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:34 volumac volumio[2607]: info: ir_controller: systemctl restart lircd.service succeeded. Apr 27 13:28:34 volumac sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Apr 27 13:28:34 volumac sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:34 volumac systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Apr 27 13:28:34 volumac sudo[2909]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Notice: accepted new client on /var/run/lirc/lircd Apr 27 13:28:34 volumac volumio[2607]: info: ir_controller: systemctl restart irexec.service succeeded. Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Notice: accepted new client on /var/run/lirc/lircd Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Info: Cannot configure the rc device for /dev/lirc0 Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:34 volumac lircd[2903]: lircd-0.10.1[2903]: Warning: Failed to initialize hardware Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Info: Cannot configure the rc device for /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:34 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:34 volumac lircd-0.10.1[2903]: Warning: Failed to initialize hardware Apr 27 13:28:34 volumac volumio[2888]: cset: --> shielding system active with Apr 27 13:28:34 volumac volumio[2888]: cset: "system" cpuset of CPUSPEC(1) with 149 tasks running Apr 27 13:28:34 volumac volumio[2888]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Apr 27 13:28:34 volumac systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 27 13:28:34 volumac sudo[2920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 13:28:34 volumac sudo[2920]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:34 volumac sudo[2920]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:35 volumac sudo[2923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 13:28:35 volumac sudo[2923]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:35 volumac sudo[2923]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:35 volumac sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 27 13:28:35 volumac sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 13:28:35 volumac sudo[2926]: pam_unix(sudo:session): session closed for user root Apr 27 13:28:35 volumac volumio[2607]: info: Upmpdcli Daemon Started Apr 27 13:28:35 volumac volumio[2607]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Apr 27 13:28:35 volumac volumio[2607]: info: go-librespot daemon successfully initialized Apr 27 13:28:35 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:35 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:35 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:35 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:36 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:36 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:36 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:36 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:37 volumac volumio[2607]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 27 13:28:37 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:37 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:37 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:37 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:38 volumac volumio[2607]: info: Initializing connection to go-librespot Websocket Apr 27 13:28:38 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:38 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:38 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:38 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:39 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:39 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:39 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:39 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:40 volumac volumio[2607]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds Apr 27 13:28:40 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:40 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:40 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:40 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:41 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:41 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:41 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:41 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:42 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:42 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:42 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:42 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:43 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:43 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:43 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:43 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:44 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:44 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:44 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:44 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:45 volumac volumio[2607]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 27 13:28:45 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:45 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:45 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:45 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:46 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:46 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:46 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:46 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:47 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:47 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:47 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:47 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:48 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:48 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:48 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:48 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:49 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:49 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:49 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:49 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:50 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:50 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:50 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:50 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:50 volumac volumio[2607]: info: AutoStart - Plugin is starting Apr 27 13:28:50 volumac volumio[2607]: info: CoreCommandRouter::volumioGetQueue Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::getQueue Apr 27 13:28:50 volumac volumio[2607]: info: CorePlayQueue::getQueue Apr 27 13:28:50 volumac volumio[2607]: info: AutoStart - start playing Apr 27 13:28:50 volumac volumio[2607]: info: AutoStart - start playing with no specific position Apr 27 13:28:50 volumac volumio[2607]: info: CoreCommandRouter::volumioPlay Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::play index 0 Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::stop Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::play index undefined Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 13:28:50 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:50 volumac volumio[2607]: info: CoreStateMachine::startPlaybackTimer Apr 27 13:28:50 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:50 volumac volumio[2607]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Dio/The Very Beast Of/01. Stand Up And Shout.mp3 Apr 27 13:28:50 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 13:28:50 volumac volumio[2607]: info: sendMpdCommand stop took 1 milliseconds Apr 27 13:28:50 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 13:28:50 volumac volumio[2607]: info: Apr 27 13:28:50 volumac volumio[2607]: ---------------------------- MPD announces system playlist update Apr 27 13:28:50 volumac volumio[2607]: info: Ignoring MPD Status Update Apr 27 13:28:50 volumac volumio[2607]: info: sendMpdCommand clear took 1 milliseconds Apr 27 13:28:50 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Dio/The Very Beast Of/01. Stand Up And Shout.mp3" Apr 27 13:28:50 volumac volumio[2607]: error: updateQueue error: null Apr 27 13:28:50 volumac volumio[2607]: info: Apr 27 13:28:50 volumac volumio[2607]: ---------------------------- MPD announces system playlist update Apr 27 13:28:50 volumac volumio[2607]: info: Ignoring MPD Status Update Apr 27 13:28:50 volumac volumio[2607]: info: ------------------------------ 3ms Apr 27 13:28:50 volumac volumio[2607]: info: sendMpdCommand add "NAS/Music/Dio/The Very Beast Of/01. Stand Up And Shout.mp3" took 1 milliseconds Apr 27 13:28:50 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand play Apr 27 13:28:50 volumac volumio[2607]: info: ------------------------------ 2ms Apr 27 13:28:50 volumac volumio[2607]: info: sendMpdCommand play took 1 milliseconds Apr 27 13:28:51 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:51 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:51 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:51 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:52 volumac volumio[2607]: info: Apr 27 13:28:52 volumac volumio[2607]: ---------------------------- MPD announces state update: player Apr 27 13:28:52 volumac volumio[2607]: info: ControllerMpd::getState Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand status Apr 27 13:28:52 volumac volumio[2607]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 27 13:28:52 volumac volumio[2607]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 27 13:28:52 volumac volumio[2607]: info: camilladsp stopping service pid 2850... Apr 27 13:28:52 volumac volumio[2607]: info: camilladsp service terminated, instance 1 Apr 27 13:28:52 volumac volumio[2607]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 13:28:52 volumac volumio[2607]: info: camilladsp service started and running in background, instance 1 Apr 27 13:28:52 volumac volumio[2607]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 27 13:28:52 volumac volumio[2607]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 27 13:28:52 volumac volumio[2607]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 13:28:52 volumac volumio[2607]: info: Apr 27 13:28:52 volumac volumio[2607]: ---------------------------- MPD announces state update: player Apr 27 13:28:52 volumac volumio[2607]: info: sendMpdCommand status took 71 milliseconds Apr 27 13:28:52 volumac volumio[2607]: info: ControllerMpd::getState Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand status Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::parseState Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 13:28:52 volumac volumio[2607]: info: sendMpdCommand status took 3 milliseconds Apr 27 13:28:52 volumac volumio[2607]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::parseState Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::parseTrackInfo Apr 27 13:28:52 volumac volumio[2607]: info: ControllerMpd::pushState Apr 27 13:28:52 volumac volumio[2607]: info: CoreCommandRouter::servicePushState Apr 27 13:28:52 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:52 volumac volumio[2607]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stand Up And Shout","artist":"Dio","album":"The Very Beast Of Dio","uri":"NAS/Music/Dio/The Very Beast Of/01. Stand Up And Shout.mp3","trackType":"mp3"} Apr 27 13:28:52 volumac volumio[2607]: verbose: CURRENT POSITION 0 Apr 27 13:28:52 volumac volumio[2607]: info: CoreStateMachine::syncState stateService play Apr 27 13:28:52 volumac volumio[2607]: info: CoreStateMachine::syncState currentStatus stop Apr 27 13:28:52 volumac volumio[2607]: info: ------------------------------ 81ms Apr 27 13:28:52 volumac volumio[2607]: info: camilladsp spawned new process with pid 2981, instance 1, run: true Apr 27 13:28:52 volumac volumio[2607]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 27 13:28:52 volumac volumio[2607]: verbose: ControllerMpd::parseTrackInfo Apr 27 13:28:52 volumac volumio[2607]: info: ControllerMpd::pushState Apr 27 13:28:52 volumac volumio[2607]: info: CoreCommandRouter::servicePushState Apr 27 13:28:52 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:52 volumac volumio[2607]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stand Up And Shout","artist":"Dio","album":"The Very Beast Of Dio","uri":"NAS/Music/Dio/The Very Beast Of/01. Stand Up And Shout.mp3","trackType":"mp3"} Apr 27 13:28:52 volumac volumio[2607]: verbose: CURRENT POSITION 0 Apr 27 13:28:52 volumac volumio[2607]: info: CoreStateMachine::syncState stateService play Apr 27 13:28:52 volumac volumio[2607]: info: CoreStateMachine::syncState currentStatus play Apr 27 13:28:52 volumac volumio[2607]: info: Received an update from plugin. extracting info from payload Apr 27 13:28:52 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:28:52 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:52 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 13:28:52 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:28:52 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:28:52 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:28:52 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:28:52 volumac volumio[2607]: info: ------------------------------ 46ms Apr 27 13:28:52 volumac volumio[2607]: info: [LastFM] Current track has sufficient metadata: title (Stand Up And Shout) and artist (Dio) passed on explicitly Apr 27 13:28:52 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:52 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:52 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:52 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:53 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:53 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:53 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:53 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:54 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:54 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:54 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:54 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:55 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:55 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:55 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:55 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:56 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:56 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:56 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:56 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:57 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:57 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:57 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:57 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:58 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:58 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:58 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:58 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:59 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:59 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:28:59 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:28:59 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:00 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:00 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:00 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:00 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:00 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 13:29:00 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:29:00 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 13:29:01 volumac volumio[2607]: error: Plugin music_service pandora failed to complete 'onStart' in a timely fashion Apr 27 13:29:01 volumac volumio[2607]: error: Plugin music_service tunein_radio failed to complete 'onStart' in a timely fashion Apr 27 13:29:01 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:29:01 volumac volumio[2607]: info: ----- MyVolumio plugins startup ---- Apr 27 13:29:01 volumac volumio[2607]: info: ------------------------------------------- Apr 27 13:29:01 volumac volumio[2607]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 27 13:29:01 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:01 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:01 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:01 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:02 volumac go-librespot[2784]: time="2024-04-27T13:29:02-04:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: i/o timeout" Apr 27 13:29:02 volumac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 13:29:02 volumac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 13:29:02 volumac volumio[2607]: info: Discovery: adding 01adf3d4-6813-42d7-8b29-ad3e4c408fe9 Apr 27 13:29:02 volumac volumio[2607]: info: Discovery: Found device Volumac Apr 27 13:29:02 volumac volumio[2607]: info: CoreCommandRouter::volumioGetState Apr 27 13:29:02 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:29:02 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:02 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:02 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:02 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:02 volumac volumio[2607]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.dfs.volumio.org Apr 27 13:29:02 volumac volumio[2607]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Apr 27 13:29:02 volumac volumio[2607]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 27 13:29:03 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:03 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:03 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:03 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:04 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:04 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:04 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:04 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:05 volumac volumio[2607]: verbose: New Socket.io Connection to 172.16.22.103 from 172.16.22.241 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 5 Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 13:29:05 volumac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 13:29:05 volumac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 27 13:29:05 volumac systemd[1]: Stopped go-librespot Daemon. Apr 27 13:29:05 volumac systemd[1]: Started go-librespot Daemon. Apr 27 13:29:05 volumac go-librespot[2990]: Librespot-go daemon starting... Apr 27 13:29:05 volumac go-librespot[2990]: time="2024-04-27T13:29:05-04:00" level=info msg="generated new device id: f60e755acfabc7da1926503e782e4ca6df3146fe" Apr 27 13:29:05 volumac go-librespot[2990]: time="2024-04-27T13:29:05-04:00" level=debug msg="stored credentials found for 1249171035" Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::volumioGetState Apr 27 13:29:05 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::volumioGetState Apr 27 13:29:05 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 13:29:05 volumac volumio[2607]: info: Received Get System Info Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 13:29:05 volumac volumio[2607]: info: Discovery: Getting this device information Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::volumioGetState Apr 27 13:29:05 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::volumioGetState Apr 27 13:29:05 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:29:05 volumac volumio[2607]: info: Listing playlists Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 13:29:05 volumac volumio[2607]: info: FusionDsp - IP adress is ---------------------------172.16.22.103 Apr 27 13:29:05 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:05 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:05 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:05 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:05 volumac volumio[2607]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 13:29:05 volumac volumio[2607]: info: FusionDsp - IP adress is ---------------------------172.16.22.103 Apr 27 13:29:06 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 13:29:06 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:06 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:06 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:06 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:07 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:07 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:07 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:07 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:08 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:08 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:08 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:08 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:09 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:09 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:09 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:09 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:10 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:10 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:10 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:10 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:11 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:11 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:11 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:11 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:12 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:12 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:12 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:12 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:13 volumac volumio[2607]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.661&uuid=47e990b15b658f061d824f174dd6966e" http://updates.volumio.org/downloader-v1/track-device Apr 27 13:29:13 volumac volumio[2607]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 27 13:29:13 volumac volumio[2607]: Dload Upload Total Spent Left Speed Apr 27 13:29:13 volumac volumio[2607]: [2.5K blob data] Apr 27 13:29:13 volumac volumio[2607]: retrying in 5 seconds, trial 0 Apr 27 13:29:13 volumac volumio[2607]: info: Volumio Calling Home Apr 27 13:29:13 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:13 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:13 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:13 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:14 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:14 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:14 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:14 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:15 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:15 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:15 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:15 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:16 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:16 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:16 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:16 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:17 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:17 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:17 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:17 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:18 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:18 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:18 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:18 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:19 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:19 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:19 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:19 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:20 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:20 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:20 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:20 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:21 volumac volumio[2607]: error: MyVolumio Plugin failed to start in a timely fashion Apr 27 13:29:21 volumac volumio[2607]: info: BOOT COMPLETED Apr 27 13:29:21 volumac volumio[2607]: [Metrics] CommandRouter: 57s 383.25ms Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::volumiosetStartupVolume Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 13:29:21 volumac volumio[2607]: info: VolumeController:: Setting startup Volume 30 Apr 27 13:29:21 volumac volumio[2607]: info: VolumeController::SetAlsaVolume30 Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::Close All Modals sent Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::Close All Modals sent Apr 27 13:29:21 volumac volumio[2607]: info: CoreStateMachine::pushState Apr 27 13:29:21 volumac volumio[2607]: info: CorePlayQueue::getTrack 0 Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 13:29:21 volumac volumio[2607]: info: CoreCommandRouter::volumioPushState Apr 27 13:29:21 volumac volumio[2607]: info: FusionDsp - ---- read samplerate, raw: Apr 27 13:29:21 volumac volumio[2607]: error: FusionDsp - invalid sample rate Apr 27 13:29:21 volumac volumio[2607]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 27 13:29:21 volumac volumio[2607]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 27 13:29:21 volumac volumio[2607]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 13:29:21 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:21 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:21 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:21 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:22 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 27 13:29:22 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 13:29:22 volumac volumio[2607]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 27 13:29:22 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:22 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:22 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:22 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:23 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:23 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:23 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:23 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:24 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:24 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:24 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:24 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:25 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:25 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:25 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:25 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:26 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:26 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:26 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:26 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:27 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:27 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:27 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:27 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:28 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:28 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:28 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:28 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:29 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:29 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:29 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:29 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:30 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:30 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:30 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:30 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:31 volumac volumio[2607]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 13:29:31 volumac volumio[2607]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Apr 27 13:29:31 volumac volumio[2607]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Apr 27 13:29:31 volumac volumio[2607]: errno: -3007, Apr 27 13:29:31 volumac volumio[2607]: code: 'ENOTFOUND', Apr 27 13:29:31 volumac volumio[2607]: syscall: 'getaddrinfo', Apr 27 13:29:31 volumac volumio[2607]: hostname: 'ws.audioscrobbler.com' Apr 27 13:29:31 volumac volumio[2607]: } Apr 27 13:29:31 volumac volumio[2607]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 13:29:31 volumac lircd[2903]: lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:31 volumac lircd-0.10.1[2903]: Error: could not get file information for /dev/lirc0 Apr 27 13:29:31 volumac lircd[2903]: lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:31 volumac lircd-0.10.1[2903]: default_init(): No such file or directory Apr 27 13:29:31 volumac sudo[3016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 13:28 Apr 27 13:29:31 volumac sudo[3016]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"