-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Fri 2024-09-06 08:56:43 CST. -- Sep 06 08:56:00 volumio systemd-timesyncd[2126]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Sep 06 08:56:00 volumio systemd[1]: Started SW Update Web Service. Sep 06 08:56:00 volumio systemd[2262]: swupdate_web.service: Failed to execute command: Exec format error Sep 06 08:56:00 volumio systemd[2262]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 06 08:56:00 volumio systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Sep 06 08:56:00 volumio systemd[1]: Starting Permit User Sessions... Sep 06 08:56:00 volumio systemd[1]: Starting Disk Manager (legacy version)... Sep 06 08:56:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 06 08:56:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 06 08:56:00 volumio systemd[1]: motd-news.service: Succeeded. Sep 06 08:56:00 volumio systemd[1]: Finished Message of the Day. Sep 06 08:56:00 volumio systemd[1]: Started /etc/rc.local Compatibility. Sep 06 08:56:00 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 06 08:56:00 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 06 08:56:00 volumio dbus-daemon[2133]: [system] Successfully activated service 'org.freedesktop.UDisks' Sep 06 08:56:00 volumio systemd[1]: Finished Permit User Sessions. Sep 06 08:56:00 volumio systemd[1]: Started Disk Manager (legacy version). Sep 06 08:56:00 volumio avahi-daemon[2132]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::8864:aff:febd:414. Sep 06 08:56:00 volumio systemd-networkd[2027]: eth0: Gained IPv6LL Sep 06 08:56:00 volumio avahi-daemon[2132]: New relevant interface eth0.IPv6 for mDNS. Sep 06 08:56:00 volumio avahi-daemon[2132]: Registering new address record for fe80::8864:aff:febd:414 on eth0.*. Sep 06 08:56:00 volumio dbus-daemon[2133]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.9' (uid=0 pid=2270 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Sep 06 08:56:00 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 2. Sep 06 08:56:00 volumio systemd[1]: Starting Daily apt download activities... Sep 06 08:56:00 volumio systemd[1]: Started Getty on tty1. Sep 06 08:56:00 volumio systemd[1]: Starting Message of the Day... Sep 06 08:56:00 volumio systemd[1]: Starting Authorization Manager... Sep 06 08:56:00 volumio systemd[1]: Started Serial Getty on ttyAS0. Sep 06 08:56:00 volumio systemd[1]: Reached target Login Prompts. Sep 06 08:56:00 volumio systemd[1]: Stopped SW Update Web Service. Sep 06 08:56:01 volumio systemd[1]: Started SW Update Web Service. Sep 06 08:56:01 volumio systemd[2284]: swupdate_web.service: Failed to execute command: Exec format error Sep 06 08:56:01 volumio systemd[2284]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 06 08:56:01 volumio systemd[1]: Starting Rotate log files... Sep 06 08:56:01 volumio polkitd[2279]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 06 08:56:01 volumio dbus-daemon[2133]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 06 08:56:01 volumio systemd[1]: motd-news.service: Succeeded. Sep 06 08:56:01 volumio systemd[1]: Finished Message of the Day. Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 06 08:56:01 volumio systemd[1]: Started Authorization Manager. Sep 06 08:56:01 volumio nmbd[2250]: [2024/09/06 08:56:01.310881, 0] ../../source3/nmbd/nmbd.c:901(main) Sep 06 08:56:01 volumio nmbd[2250]: nmbd version 4.15.13-Ubuntu started. Sep 06 08:56:01 volumio nmbd[2250]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 3. Sep 06 08:56:01 volumio nmbd[2250]: [2024/09/06 08:56:01.428561, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 06 08:56:01 volumio nmbd[2250]: started asyncdns process 2289 Sep 06 08:56:01 volumio systemd[1]: Stopped SW Update Web Service. Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0p6 inserted Sep 06 08:56:01 volumio systemd[1]: Started SW Update Web Service. Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0 inserted Sep 06 08:56:01 volumio systemd[2291]: swupdate_web.service: Failed to execute command: Exec format error Sep 06 08:56:01 volumio systemd[2291]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 06 08:56:01 volumio systemd[1]: Started Samba NMB Daemon. Sep 06 08:56:01 volumio nmbd[2250]: [2024/09/06 08:56:01.519208, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 06 08:56:01 volumio nmbd[2250]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.123 for name WORKGROUP<1d>. Sep 06 08:56:01 volumio nmbd[2250]: This response was from IP 192.168.23.81, reporting an IP address of 192.168.23.81. Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0p4 inserted Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0p3 inserted Sep 06 08:56:01 volumio systemd[1]: Starting Samba SMB Daemon... Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 4. Sep 06 08:56:01 volumio systemd[1]: Stopped SW Update Web Service. Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0p2 inserted Sep 06 08:56:01 volumio systemd[1]: Started SW Update Web Service. Sep 06 08:56:01 volumio systemd[2301]: swupdate_web.service: Failed to execute command: Exec format error Sep 06 08:56:01 volumio systemd[2301]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0p8 inserted Sep 06 08:56:01 volumio udisks-glue[2161]: Device file /dev/mmcblk0p1 inserted Sep 06 08:56:01 volumio udisks-glue[2161]: Trying to automount /dev/mmcblk0p1... Sep 06 08:56:01 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 5. Sep 06 08:56:01 volumio systemd[1]: Stopped SW Update Web Service. Sep 06 08:56:02 volumio systemd[1]: swupdate_web.service: Start request repeated too quickly. Sep 06 08:56:02 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 06 08:56:02 volumio systemd[1]: Failed to start SW Update Web Service. Sep 06 08:56:02 volumio nmbd[2250]: [2024/09/06 08:56:02.046958, 0] ../../source3/nmbd/nmbd_workgroupdb.c:279(dump_workgroups) Sep 06 08:56:02 volumio nmbd[2250]: dump_workgroups() Sep 06 08:56:02 volumio nmbd[2250]: dump workgroup on subnet 192.168.23.123: netmask= 255.255.255.0: Sep 06 08:56:02 volumio nmbd[2250]: WORKGROUP(1) current master browser = UNKNOWN Sep 06 08:56:02 volumio nmbd[2250]: VOLUMIO 40809a03 (Volumio Audiophile Music Player) Sep 06 08:56:02 volumio nmbd[2250]: [2024/09/06 08:56:02.047735, 0] ../../source3/nmbd/nmbd_workgroupdb.c:279(dump_workgroups) Sep 06 08:56:02 volumio nmbd[2250]: dump_workgroups() Sep 06 08:56:02 volumio nmbd[2250]: dump workgroup on subnet UNICAST_SUBNET: netmask= 192.168.23.123: Sep 06 08:56:02 volumio nmbd[2250]: WORKGROUP(1) current master browser = UNKNOWN Sep 06 08:56:02 volumio nmbd[2250]: VOLUMIO 40809a03 (Volumio Audiophile Music Player) Sep 06 08:56:02 volumio systemd[1]: Started Dispatcher daemon for systemd-networkd. Sep 06 08:56:02 volumio systemd[1]: logrotate.service: Succeeded. Sep 06 08:56:02 volumio systemd[1]: Finished Rotate log files. Sep 06 08:56:02 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 06 08:56:02 volumio udisks-glue[2161]: Successfully automounted /dev/mmcblk0p1 at /media/Volumn_ Sep 06 08:56:02 volumio udisks-glue[2161]: Device file /dev/mmcblk0p7 inserted Sep 06 08:56:02 volumio udisks-glue[2161]: Device file /dev/mmcblk0p1 mounted at /media/Volumn_ Sep 06 08:56:02 volumio smbd[2302]: [2024/09/06 08:56:02.966536, 0] ../../source3/smbd/server.c:1734(main) Sep 06 08:56:02 volumio smbd[2302]: smbd version 4.15.13-Ubuntu started. Sep 06 08:56:02 volumio smbd[2302]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Sep 06 08:56:03 volumio systemd[1]: Started Samba SMB Daemon. Sep 06 08:56:04 volumio set-cpufreq[2146]: Setting ondemand scheduler for all CPUs Sep 06 08:56:04 volumio systemd[1]: ondemand.service: Succeeded. Sep 06 08:56:05 volumio wireless.js[2272]: WIRELESS: No wireless interface, exiting Sep 06 08:56:05 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Sep 06 08:56:05 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Sep 06 08:56:05 volumio systemd[1]: Failed to start Wireless Services. Sep 06 08:56:05 volumio systemd[1]: Started Volumio Backend Module. Sep 06 08:56:05 volumio systemd[1]: Started SocketIO Bridge Service. Sep 06 08:56:05 volumio systemd[1]: Started Demo Service. Sep 06 08:56:05 volumio socketio[2340]: websocket connect Sep 06 08:56:05 volumio socketio[2340]: Error: No active session Sep 06 08:56:05 volumio socketio[2340]: [2024-09-06 08:56:05] [info] asio async_connect error: asio.system:111 (Connection refused) Sep 06 08:56:05 volumio socketio[2340]: [2024-09-06 08:56:05] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Sep 06 08:56:05 volumio socketio[2340]: [2024-09-06 08:56:05] [error] handle_connect error: Connection refused Sep 06 08:56:05 volumio mpd[2246]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Sep 06 08:56:05 volumio mpd[2246]: config_file: config parameter "buffer_before_play" on line 102 is deprecated Sep 06 08:56:05 volumio mpd[2246]: config_file: config parameter "id3v1_encoding" on line 116 is deprecated Sep 06 08:56:05 volumio demo[2341]: opt = r Sep 06 08:56:05 volumio demo[2341]: optarg = 90 Sep 06 08:56:05 volumio demo[2341]: optind = 3 Sep 06 08:56:05 volumio demo[2341]: argv[optind - 1] = 90 Sep 06 08:56:05 volumio demo[2341]: opt = t Sep 06 08:56:05 volumio demo[2341]: optarg = /dev/ttyAS2 Sep 06 08:56:05 volumio demo[2341]: optind = 5 Sep 06 08:56:05 volumio demo[2341]: argv[optind - 1] = /dev/ttyAS2 Sep 06 08:56:05 volumio demo[2341]: tty driver /dev/ttyAS2 Sep 06 08:56:05 volumio demo[2341]: SCREEN_SIZE_960X376 Sep 06 08:56:05 volumio mpd[2246]: Sep 06 08:56 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 08:56:05 volumio mpd[2246]: Sep 06 08:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 06 08:56:05 volumio mpd[2246]: Sep 06 08:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 06 08:56:05 volumio systemd[1]: Started Music Player Daemon. Sep 06 08:56:05 volumio udisks-glue[2323]: Updating DB (#1) ... Sep 06 08:56:05 volumio udisks-glue[2323]: volume:100% repeat: off random: off single: off consume: off Sep 06 08:56:05 volumio systemd[1]: Reached target Multi-User System. Sep 06 08:56:05 volumio systemd[1]: Reached target Graphical Interface. Sep 06 08:56:05 volumio demo[2360]: cat: /workdir/version.txt: No such file or directory Sep 06 08:56:05 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 06 08:56:05 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 06 08:56:05 volumio systemd[1]: Finished Update UTMP about System Runlevel Changes. Sep 06 08:56:05 volumio demo[2372]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:06 volumio socketio[2340]: IP is 127.0.0.1 Sep 06 08:56:06 volumio socketio[2340]: Port is 33954 Sep 06 08:56:06 volumio socketio[2340]: 8 Sep 06 08:56:06 volumio socketio[2340]: command_len 395 Sep 06 08:56:06 volumio socketio[2340]: pushSrctotal Sep 06 08:56:06 volumio socketio[2340]: { Sep 06 08:56:06 volumio socketio[2340]: "total": 8, Sep 06 08:56:06 volumio socketio[2340]: "cur": 0, Sep 06 08:56:06 volumio socketio[2340]: "source": [{ Sep 06 08:56:06 volumio socketio[2340]: "type": 0, Sep 06 08:56:06 volumio socketio[2340]: "name": "Media Player" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 4, Sep 06 08:56:06 volumio socketio[2340]: "name": "Bluetooth" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 2, Sep 06 08:56:06 volumio socketio[2340]: "name": "DAB" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 3, Sep 06 08:56:06 volumio socketio[2340]: "name": "FM" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 6, Sep 06 08:56:06 volumio socketio[2340]: "name": "AUX 1" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 5, Sep 06 08:56:06 volumio socketio[2340]: "name": "DIGITAL OPT1" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 5, Sep 06 08:56:06 volumio socketio[2340]: "name": "DIGITAL OPT2" Sep 06 08:56:06 volumio socketio[2340]: }, { Sep 06 08:56:06 volumio socketio[2340]: "type": 0, Sep 06 08:56:06 volumio socketio[2340]: "name": "HDMI ARC" Sep 06 08:56:06 volumio socketio[2340]: }] Sep 06 08:56:06 volumio socketio[2340]: } Sep 06 08:56:06 volumio socketio[2340]: command_len 31 Sep 06 08:56:06 volumio socketio[2340]: pushSourceInfo Sep 06 08:56:06 volumio socketio[2340]: { Sep 06 08:56:06 volumio socketio[2340]: "source": 0 Sep 06 08:56:06 volumio socketio[2340]: } Sep 06 08:56:06 volumio demo[2376]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:07 volumio demo[2378]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:07 volumio demo[2400]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:08 volumio systemd[1]: apt-daily.service: Succeeded. Sep 06 08:56:08 volumio systemd[1]: Finished Daily apt download activities. Sep 06 08:56:08 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 06 08:56:08 volumio demo[2419]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:09 volumio demo[2432]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:10 volumio demo[2435]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:10 volumio socketio[2340]: [2024-09-06 08:56:10] [info] asio async_connect error: asio.system:111 (Connection refused) Sep 06 08:56:10 volumio socketio[2340]: [2024-09-06 08:56:10] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Sep 06 08:56:10 volumio socketio[2340]: [2024-09-06 08:56:10] [error] handle_connect error: Connection refused Sep 06 08:56:10 volumio demo[2437]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:11 volumio demo[2456]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:11 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 06 08:56:11 volumio systemd[1]: Finished Daily apt upgrade and clean activities. Sep 06 08:56:11 volumio systemd[1]: Startup finished in 3.886s (kernel) + 19.944s (userspace) = 23.830s. Sep 06 08:56:12 volumio demo[2470]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 06 08:56:13 volumio volumio[2339]: info: ------------------------------------------- Sep 06 08:56:13 volumio volumio[2339]: info: ----- Volumio2 ---- Sep 06 08:56:13 volumio volumio[2339]: info: ------------------------------------------- Sep 06 08:56:13 volumio volumio[2339]: info: ----- System startup ---- Sep 06 08:56:13 volumio volumio[2339]: info: ------------------------------------------- Sep 06 08:56:15 volumio volumio[2339]: info: Found new core plugin music_service/youtube2. Adding it Sep 06 08:56:15 volumio volumio[2339]: info: Configured plugin music_service/youtube2 cannot be loaded. Removing from configuration Sep 06 08:56:15 volumio volumio[2339]: info: Plugin folders cleanup Sep 06 08:56:15 volumio volumio[2339]: info: Scanning into folder /volumio/app/plugins/ Sep 06 08:56:15 volumio volumio[2339]: info: Scanning category audio_interface Sep 06 08:56:15 volumio volumio[2339]: info: Scanning category miscellanea Sep 06 08:56:15 volumio volumio[2339]: info: Scanning category music_service Sep 06 08:56:15 volumio volumio[2339]: info: Scanning category plugins.json Sep 06 08:56:15 volumio volumio[2339]: info: Scanning category system_controller Sep 06 08:56:15 volumio volumio[2339]: info: Scanning category user_interface Sep 06 08:56:15 volumio volumio[2339]: info: Scanning into folder /data/plugins/ Sep 06 08:56:15 volumio volumio[2339]: info: Plugin folders cleanup completed Sep 06 08:56:15 volumio volumio[2339]: info: ------------------------------------------- Sep 06 08:56:15 volumio volumio[2339]: info: ----- Core plugins startup ---- Sep 06 08:56:15 volumio volumio[2339]: info: ------------------------------------------- Sep 06 08:56:15 volumio volumio[2339]: info: Loading plugins from folder /volumio/app/plugins/ Sep 06 08:56:15 volumio volumio[2339]: info: Adding plugin upnp to MyMusic Plugins Sep 06 08:56:15 volumio volumio[2339]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 06 08:56:15 volumio volumio[2339]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 06 08:56:15 volumio volumio[2339]: info: Loading plugins from folder /data/plugins/ Sep 06 08:56:15 volumio volumio[2339]: info: Loading plugin "system"... Sep 06 08:56:15 volumio volumio[2339]: info: Loading plugin "appearance"... Sep 06 08:56:17 volumio socketio[2340]: [2024-09-06 08:56:17] [connect] Successful connection Sep 06 08:56:21 volumio kernel: usb0-vbus: disabling Sep 06 08:56:22 volumio volumio[2339]: info: Loading plugin "network"... Sep 06 08:56:22 volumio volumio[2339]: info: Refreshing Cached IP Addresses Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "services"... Sep 06 08:56:23 volumio volumio[2339]: info: Plugin sources is not enabled Sep 06 08:56:23 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "alsa_controller"... Sep 06 08:56:23 volumio sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 08:56:23 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:23 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:23 volumio sudo[2490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Sep 06 08:56:23 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:23 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:23 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:23 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "wizard"... Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "volumio_command_line_client"... Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "upnp"... Sep 06 08:56:23 volumio volumio[2339]: info: [1725584183511] Starting Upmpd Daemon Sep 06 08:56:23 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "my_music"... Sep 06 08:56:23 volumio volumio[2339]: info: Loading plugin "mpd"... Sep 06 08:56:24 volumio volumio[2339]: info: Creating MPD Configuration file Sep 06 08:56:24 volumio sudo[2522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 08:56:24 volumio volumio[2339]: info: Loading plugin "upnp_browser"... Sep 06 08:56:24 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:24 volumio sudo[2526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 08:56:24 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:24 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:24 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:24 volumio volumio[2339]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: error: Stack trace: Error: Could not locate the bindings file. Tried: Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 06 08:56:24 volumio volumio[2339]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Sep 06 08:56:24 volumio volumio[2339]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Sep 06 08:56:24 volumio volumio[2339]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 06 08:56:24 volumio volumio[2339]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 06 08:56:24 volumio volumio[2339]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 06 08:56:24 volumio volumio[2339]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 06 08:56:24 volumio volumio[2339]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 06 08:56:24 volumio volumio[2339]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 06 08:56:24 volumio volumio[2339]: at require (internal/modules/cjs/helpers.js:25:18) Sep 06 08:56:24 volumio volumio[2339]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Sep 06 08:56:24 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:24 volumio volumio[2339]: info: Loading plugin "networkfs"... Sep 06 08:56:24 volumio systemd[1]: Stopping Music Player Daemon... Sep 06 08:56:25 volumio systemd[1]: mpd.service: Succeeded. Sep 06 08:56:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 06 08:56:25 volumio systemd[1]: Starting Music Player Daemon... Sep 06 08:56:26 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:26 volumio volumio[2339]: error: The plugin system_controller/networkfs failed to load, setting it to stopped. Error: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Sep 06 08:56:26 volumio volumio[2339]: error: Stack trace: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Sep 06 08:56:26 volumio volumio[2339]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Sep 06 08:56:26 volumio volumio[2339]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Sep 06 08:56:26 volumio volumio[2339]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 06 08:56:26 volumio volumio[2339]: at require (internal/modules/cjs/helpers.js:25:18) Sep 06 08:56:26 volumio volumio[2339]: at Object. (/volumio/node_modules/udev/udev.js:4:12) Sep 06 08:56:26 volumio volumio[2339]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 06 08:56:26 volumio volumio[2339]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 06 08:56:26 volumio volumio[2339]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 06 08:56:26 volumio volumio[2339]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 06 08:56:26 volumio volumio[2339]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 06 08:56:26 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:26 volumio volumio[2339]: info: Loading plugin "alarm-clock"... Sep 06 08:56:26 volumio mpd[2538]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Sep 06 08:56:26 volumio mpd[2538]: config_file: config parameter "buffer_before_play" on line 102 is deprecated Sep 06 08:56:26 volumio mpd[2538]: config_file: config parameter "id3v1_encoding" on line 116 is deprecated Sep 06 08:56:26 volumio mpd[2538]: Sep 06 08:56 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 08:56:26 volumio mpd[2538]: Sep 06 08:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 06 08:56:26 volumio mpd[2538]: Sep 06 08:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 06 08:56:26 volumio systemd[1]: Started Music Player Daemon. Sep 06 08:56:26 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "airplay_emulation"... Sep 06 08:56:27 volumio volumio[2339]: info: Starting Shairport Sync Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "last_100"... Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "webradio"... Sep 06 08:56:27 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:27 volumio volumio[2339]: error: The plugin music_service/webradio failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: error: Stack trace: Error: Could not locate the bindings file. Tried: Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 06 08:56:27 volumio volumio[2339]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Sep 06 08:56:27 volumio volumio[2339]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Sep 06 08:56:27 volumio volumio[2339]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 06 08:56:27 volumio volumio[2339]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 06 08:56:27 volumio volumio[2339]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 06 08:56:27 volumio volumio[2339]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 06 08:56:27 volumio volumio[2339]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 06 08:56:27 volumio volumio[2339]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 06 08:56:27 volumio volumio[2339]: at require (internal/modules/cjs/helpers.js:25:18) Sep 06 08:56:27 volumio volumio[2339]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Sep 06 08:56:27 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "i2s_dacs"... Sep 06 08:56:27 volumio volumio[2339]: info: I2S DAC not set, start Auto-detection Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "volumiodiscovery"... Sep 06 08:56:27 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:27 volumio volumio[2339]: error: The plugin system_controller/volumiodiscovery failed to load, setting it to stopped. Error: Error: Cannot find module '../build/Release/dns_sd_bindings' Sep 06 08:56:27 volumio volumio[2339]: error: Stack trace: Error: Cannot find module '../build/Release/dns_sd_bindings' Sep 06 08:56:27 volumio volumio[2339]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Sep 06 08:56:27 volumio volumio[2339]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Sep 06 08:56:27 volumio volumio[2339]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 06 08:56:27 volumio volumio[2339]: at require (internal/modules/cjs/helpers.js:25:18) Sep 06 08:56:27 volumio volumio[2339]: at Object. (/volumio/node_modules/mdns/lib/dns_sd.js:32:22) Sep 06 08:56:27 volumio volumio[2339]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 06 08:56:27 volumio volumio[2339]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 06 08:56:27 volumio volumio[2339]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 06 08:56:27 volumio volumio[2339]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 06 08:56:27 volumio volumio[2339]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 06 08:56:27 volumio volumio[2339]: error: !!!! WARNING !!!! Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "outputs"... Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "albumart"... Sep 06 08:56:27 volumio volumio[2339]: info: Plugin chromecast is not enabled Sep 06 08:56:27 volumio volumio[2339]: info: Plugin example_plugin is not enabled Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "inputs"... Sep 06 08:56:27 volumio volumio[2339]: info: Loading plugin "pandora"... Sep 06 08:56:28 volumio volumio[2339]: info: Applying required configuration parameters for plugin pandora Sep 06 08:56:28 volumio volumio[2339]: info: Loading plugin "podcast"... Sep 06 08:56:29 volumio volumio[2339]: info: ControllerPodcast::constructor Sep 06 08:56:29 volumio volumio[2339]: info: Loading plugin "qobuz"... Sep 06 08:56:29 volumio volumio[2339]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Sep 06 08:56:29 volumio volumio[2339]: info: Loading plugin "tidal"... Sep 06 08:56:30 volumio volumio[2339]: info: [1725584190595] ControllerTidalPlugin::constructor Sep 06 08:56:30 volumio volumio[2339]: info: [1725584190597] ControllerTidalPlugin::onVolumioStart Sep 06 08:56:30 volumio volumio[2339]: info: Loading plugin "volspotconnect2"... Sep 06 08:56:32 volumio volumio[2339]: [SpotifyConnect2] Creating VLS config file Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:32 volumio volumio[2339]: [SpotifyConnect2] <> or Mixer found, using softvol Sep 06 08:56:32 volumio volumio[2339]: info: Loading plugin "updater_comm"... Sep 06 08:56:32 volumio volumio[2339]: info: Plugin mpdemulation is not enabled Sep 06 08:56:32 volumio volumio[2339]: info: Loading plugin "rest_api"... Sep 06 08:56:32 volumio volumio[2339]: info: Loading plugin "websocket"... Sep 06 08:56:32 volumio volumio[2339]: info: ___________ START PLUGINS ___________ Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:32 volumio volumio[2339]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192910] CoreMusicLibrary::Adding element Last_100 Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192937] CoreMusicLibrary::Adding element Pandora Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192944] ControllerPandora::initializeMQTT Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192946] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192974] CoreMusicLibrary::Adding element Podcast Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192980] CoreMusicLibrary::Adding element Qobuz Sep 06 08:56:32 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 08:56:32 volumio volumio[2339]: info: [1725584192995] ControllerTidalPlugin::onStart Sep 06 08:56:33 volumio volumio[2339]: info: [1725584193007] ControllerTidalPlugin::addToBrowseSources Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 08:56:33 volumio volumio[2339]: info: [1725584193009] CoreMusicLibrary::Adding element Tidal Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 08:56:33 volumio volumio[2339]: Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Sep 06 08:56:33 volumio volumio[2339]: Could not load session from /data/configuration/music_service/tidal/tidal-session-oauth.json: Error: ENOENT: no such file or directory, open '/data/configuration/music_service/tidal/tidal-session-oauth.json' Sep 06 08:56:33 volumio volumio[2339]: [SpotifyConnect2] Creating VLS config file Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: [SpotifyConnect2] <> or Mixer found, using softvol Sep 06 08:56:33 volumio volumio[2339]: [SpotifyConnect2] Starting metadata listener Sep 06 08:56:33 volumio volumio[2339]: info: Loading i18n strings for locale en Sep 06 08:56:33 volumio sudo[2554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 06 08:56:33 volumio volumio[2339]: Updating browse sources language Sep 06 08:56:33 volumio volumio[2339]: Cannot find translation for sourcePodcast Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 08:56:33 volumio sudo[2554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::initPlayerControls Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:33 volumio systemd[1]: Configuration file /etc/systemd/system/volspotconnect2.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 08:56:33 volumio systemd[1]: Started Volspotconnect2 Daemon. Sep 06 08:56:33 volumio volumio[2339]: info: BOOT COMPLETED Sep 06 08:56:33 volumio volumio[2339]: [Metrics] CommandRouter: 19s 686.11ms Sep 06 08:56:33 volumio sudo[2554]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 08:56:33 volumio volumio[2339]: ALSA lib pcm.c:2642:(snd_pcm_open_noupdate) Unknown PCM softvolume Sep 06 08:56:33 volumio volumio[2339]: aplay: main:852: audio open error: No such file or directory Sep 06 08:56:33 volumio volumio[2339]: Cannot play startup sound Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::Close All Modals sent Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::Close All Modals sent Sep 06 08:56:33 volumio volumio[2339]: Express server listening on port 3000 Sep 06 08:56:33 volumio volumio[2339]: [Metrics] WebUI: 21s 370.61ms Sep 06 08:56:33 volumio volumio[2339]: info: [1725584193492] ControllerPandora::validateAndSetAccountOptions Sep 06 08:56:33 volumio volumio[2339]: error: [1725584193496] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Sep 06 08:56:33 volumio volumio[2339]: info: CoreStateMachine::resetVolumioState Sep 06 08:56:33 volumio volumio[2339]: info: CoreStateMachine::getcurrentVolume Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 08:56:33 volumio volumio[2566]: vollibrespot v0.2.4 61563b7 2021-07-22 (librespot 08d8bcc 2020-10-07) -- Built On 2021-07-22 Sep 06 08:56:33 volumio volumio[2566]: Reading Config from "volspotify.toml" Sep 06 08:56:33 volumio volumio[2566]: [Vollibrespot] : Using Alsa backend with device: hw:1 Sep 06 08:56:33 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 08:56:33 volumio sudo[2579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 08:56:33 volumio sudo[2579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:33 volumio sudo[2579]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:33 volumio sudo[2588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 08:56:33 volumio sudo[2588]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:33 volumio volumio[2339]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 06 08:56:33 volumio sudo[2588]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:34 volumio volumio[2339]: info: Cannot read play queue from file Sep 06 08:56:34 volumio socketio[2340]: [2024-09-06 08:56:34] [connect] Successful connection Sep 06 08:56:34 volumio volumio[2339]: info: CoreStateMachine::pushState Sep 06 08:56:34 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 08:56:34 volumio volumio[2339]: info: CoreStateMachine::updateTrackBlock Sep 06 08:56:34 volumio volumio[2339]: info: CorePlayQueue::getTrackBlock Sep 06 08:56:34 volumio volumio[2339]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 08:56:34 volumio volumio[2339]: [SpotifyConnect2] Vollibrespot Daemon service started! Sep 06 08:56:34 volumio volumio[2339]: [Metrics] SpotifyConnect: 1s 258.99ms Sep 06 08:56:34 volumio volumio[2339]: info: MPD Permissions set Sep 06 08:56:34 volumio volumio[2339]: info: Permissions for /etc/hostapd/hostapd.conf Sep 06 08:56:34 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 06 08:56:34 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 08:56:34 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 06 08:56:34 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 06 08:56:34 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:34 volumio systemd[1]: Starting Wireless Services... Sep 06 08:56:35 volumio sudo[2628]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 06 08:56:35 volumio sudo[2628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:35 volumio wifistart.sh[2633]: command failed: No such device (-19) Sep 06 08:56:35 volumio sudo[2628]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:35 volumio volumio[2339]: info: MPD running with PID2538 Sep 06 08:56:35 volumio volumio[2339]: ,establishing connection Sep 06 08:56:35 volumio volumio[2339]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Sep 06 08:56:35 volumio volumio[2339]: sudo: /usr/sbin/i2cdetect: command not found Sep 06 08:56:35 volumio volumio[2339]: info: CoreStateMachine::pushState Sep 06 08:56:35 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 08:56:35 volumio volumio[2607]: Forking 1 albumart workers Sep 06 08:56:35 volumio socketio[2340]: command_len 9 Sep 06 08:56:35 volumio socketio[2340]: getQueue Sep 06 08:56:35 volumio socketio[2340]: Sep 06 08:56:35 volumio volumio[2339]: info: Sep 06 08:56:35 volumio volumio[2339]: ---------------------------- Client requests Volume 100 Sep 06 08:56:35 volumio volumio[2339]: info: VolumeController::SetAlsaVolume100 Sep 06 08:56:36 volumio volumio[2339]: info: CoreStateMachine::pushState Sep 06 08:56:36 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 08:56:36 volumio volumio[2339]: sendCommand: Command { name: 'playlistinfo', args: [] } Sep 06 08:56:36 volumio volumio[2339]: error: MPD error: The expression evaluated to a falsy value: Sep 06 08:56:36 volumio volumio[2339]: assert.ok(self.idling) Sep 06 08:56:36 volumio volumio[2339]: sendCommand: Command { name: 'search album ""', args: [] } Sep 06 08:56:36 volumio volumio[2339]: error: MPD error: The expression evaluated to a falsy value: Sep 06 08:56:36 volumio volumio[2339]: assert.ok(self.idling) Sep 06 08:56:36 volumio socketio[2340]: [2024-09-06 08:56:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1725584194 101 Sep 06 08:56:36 volumio socketio[2340]: sio connected Sep 06 08:56:36 volumio socketio[2340]: socket send Sep 06 08:56:36 volumio socketio[2340]: command_len 395 Sep 06 08:56:36 volumio socketio[2340]: pushSrctotal Sep 06 08:56:36 volumio socketio[2340]: { Sep 06 08:56:36 volumio socketio[2340]: "total": 8, Sep 06 08:56:36 volumio socketio[2340]: "cur": 0, Sep 06 08:56:36 volumio socketio[2340]: "source": [{ Sep 06 08:56:36 volumio socketio[2340]: "type": 0, Sep 06 08:56:36 volumio socketio[2340]: "name": "Media Player" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 4, Sep 06 08:56:36 volumio socketio[2340]: "name": "Bluetooth" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 2, Sep 06 08:56:36 volumio socketio[2340]: "name": "DAB" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 3, Sep 06 08:56:36 volumio socketio[2340]: "name": "FM" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 6, Sep 06 08:56:36 volumio socketio[2340]: "name": "AUX 1" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 5, Sep 06 08:56:36 volumio socketio[2340]: "name": "DIGITAL OPT1" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 5, Sep 06 08:56:36 volumio socketio[2340]: "name": "DIGITAL OPT2" Sep 06 08:56:36 volumio socketio[2340]: }, { Sep 06 08:56:36 volumio socketio[2340]: "type": 0, Sep 06 08:56:36 volumio socketio[2340]: "name": "HDMI ARC" Sep 06 08:56:36 volumio socketio[2340]: }] Sep 06 08:56:36 volumio socketio[2340]: } Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::volumioGetQueue Sep 06 08:56:37 volumio volumio[2339]: info: CoreStateMachine::getQueue Sep 06 08:56:37 volumio volumio[2339]: info: CorePlayQueue::getQueue Sep 06 08:56:37 volumio socketio[2340]: socket send Sep 06 08:56:37 volumio volumio[2339]: sendCommand: Command { name: 'playlistinfo', args: [] } Sep 06 08:56:37 volumio volumio[2339]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Sep 06 08:56:37 volumio volumio[2339]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Sep 06 08:56:37 volumio volumio[2339]: info: Setting volume on startup at 100 Sep 06 08:56:37 volumio volumio[2339]: info: CoreStateMachine::setRepeat null single undefined Sep 06 08:56:37 volumio volumio[2339]: info: CoreStateMachine::pushState Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 08:56:37 volumio volumio[2339]: info: CoreStateMachine::setRandom null Sep 06 08:56:37 volumio volumio[2339]: info: CoreStateMachine::pushState Sep 06 08:56:37 volumio volumio[2339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 08:56:37 volumio volumio[2339]: error: updateQueue error: null Sep 06 08:56:37 volumio volumio[2339]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 06 08:56:37 volumio wireless.js[2639]: WIRELESS: No wireless interface, exiting Sep 06 08:56:37 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Sep 06 08:56:37 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Sep 06 08:56:37 volumio systemd[1]: Failed to start Wireless Services. Sep 06 08:56:37 volumio socketio[2340]: socket send Sep 06 08:56:37 volumio socketio[2340]: command_len 395 Sep 06 08:56:37 volumio socketio[2340]: pushSrctotal Sep 06 08:56:37 volumio socketio[2340]: { Sep 06 08:56:37 volumio socketio[2340]: "total": 8, Sep 06 08:56:37 volumio socketio[2340]: "cur": 0, Sep 06 08:56:37 volumio socketio[2340]: "source": [{ Sep 06 08:56:37 volumio socketio[2340]: "type": 0, Sep 06 08:56:37 volumio socketio[2340]: "name": "Media Player" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 4, Sep 06 08:56:37 volumio socketio[2340]: "name": "Bluetooth" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 2, Sep 06 08:56:37 volumio socketio[2340]: "name": "DAB" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 3, Sep 06 08:56:37 volumio socketio[2340]: "name": "FM" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 6, Sep 06 08:56:37 volumio socketio[2340]: "name": "AUX 1" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 5, Sep 06 08:56:37 volumio socketio[2340]: "name": "DIGITAL OPT1" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 5, Sep 06 08:56:37 volumio socketio[2340]: "name": "DIGITAL OPT2" Sep 06 08:56:37 volumio socketio[2340]: }, { Sep 06 08:56:37 volumio socketio[2340]: "type": 0, Sep 06 08:56:37 volumio socketio[2340]: "name": "HDMI ARC" Sep 06 08:56:37 volumio socketio[2340]: }] Sep 06 08:56:37 volumio socketio[2340]: } Sep 06 08:56:37 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:37 volumio volumio[2339]: info: Starting Shairport Sync Sep 06 08:56:37 volumio sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 08:56:37 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 06 08:56:37 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:37 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 08:56:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 08:56:38 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 06 08:56:38 volumio sudo[2672]: pam_unix(sudo:session): session closed for user root Sep 06 08:56:38 volumio volumio[2339]: info: Upmpdcli Daemon Started Sep 06 08:56:38 volumio volumio[2339]: info: Shairport-Sync Started Sep 06 08:56:38 volumio volumio[2339]: Error adding Membership: Error: addMembership EINVAL Sep 06 08:56:39 volumio volumio[2339]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 08:56:39 volumio volumio[2339]: TypeError: f.split(...)[0].capitalize is not a function Sep 06 08:56:39 volumio volumio[2339]: at /volumio/app/plugins/miscellanea/appearance/index.js:198:56 Sep 06 08:56:39 volumio volumio[2339]: at Array.forEach () Sep 06 08:56:39 volumio volumio[2339]: at /volumio/app/plugins/miscellanea/appearance/index.js:196:11 Sep 06 08:56:39 volumio volumio[2339]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Sep 06 08:56:39 volumio volumio[2339]: at FSReqWrap.args [as oncomplete] (fs.js:140:20) Sep 06 08:56:39 volumio volumio[2339]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 08:56:39 volumio volumio[2715]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 06 08:56:40 volumio volumio[2646]: Starting albumart workers Sep 06 08:56:41 volumio volumio[2715]: ............++++ Sep 06 08:56:43 volumio sudo[2737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 08:55 Sep 06 08:56:43 volumio sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7"