-- Logs begin at Wed 2025-04-30 21:11:48 UTC, end at Fri 2025-05-02 15:36:22 UTC. -- May 02 15:35:59 musical-fidelity systemd[1]: Time has been changed May 02 15:36:00 musical-fidelity wpa_supplicant[1050]: wlan0: Trying to associate with 3c:84:6a:a1:07:ee (SSID='Thomsen-net' freq=2422 MHz) May 02 15:36:00 musical-fidelity kernel: RTL871X: rtw_set_802_11_connect(wlan0) fw_state=0x00000008 May 02 15:36:00 musical-fidelity kernel: RTL871X: start auth May 02 15:36:00 musical-fidelity kernel: RTL871X: auth success, start assoc May 02 15:36:00 musical-fidelity kernel: RTL871X: rtw_cfg80211_indicate_connect(wlan0) BSS not found !! May 02 15:36:00 musical-fidelity kernel: RTL871X: assoc success May 02 15:36:00 musical-fidelity wpa_supplicant[1050]: wlan0: Associated with 3c:84:6a:a1:07:ee May 02 15:36:00 musical-fidelity wpa_supplicant[1050]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE May 02 15:36:00 musical-fidelity kernel: RTL871X: send eapol packet May 02 15:36:00 musical-fidelity wpa_supplicant[1050]: wlan0: WPA: Key negotiation completed with 3c:84:6a:a1:07:ee [PTK=CCMP GTK=CCMP] May 02 15:36:00 musical-fidelity wpa_supplicant[1050]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:84:6a:a1:07:ee completed [id=0 id_str=] May 02 15:36:00 musical-fidelity dhcpcd[1083]: wlan0: carrier acquired May 02 15:36:00 musical-fidelity kernel: RTL871X: send eapol packet May 02 15:36:00 musical-fidelity dhcpcd[1083]: wlan0: soliciting a DHCP lease May 02 15:36:00 musical-fidelity dhcpcd[1083]: wlan0: offered 192.168.68.66 from 192.168.68.1 May 02 15:36:00 musical-fidelity kernel: RTL871X: set pairwise key camid:4, addr:3c:84:6a:a1:07:ee, kid:0, type:AES May 02 15:36:00 musical-fidelity kernel: RTL871X: set group key camid:5, addr:3c:84:6a:a1:07:ee, kid:1, type:AES May 02 15:36:00 musical-fidelity wireless.js[884]: trying... May 02 15:36:00 musical-fidelity sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 02 15:36:00 musical-fidelity sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:00 musical-fidelity sudo[1146]: pam_unix(sudo:session): session closed for user root May 02 15:36:01 musical-fidelity wireless.js[884]: trying... May 02 15:36:01 musical-fidelity sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 02 15:36:01 musical-fidelity sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:01 musical-fidelity sudo[1155]: pam_unix(sudo:session): session closed for user root May 02 15:36:02 musical-fidelity wireless.js[884]: trying... May 02 15:36:02 musical-fidelity sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 02 15:36:02 musical-fidelity sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:02 musical-fidelity sudo[1164]: pam_unix(sudo:session): session closed for user root May 02 15:36:03 musical-fidelity wireless.js[884]: trying... May 02 15:36:03 musical-fidelity sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 02 15:36:03 musical-fidelity sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:03 musical-fidelity sudo[1173]: pam_unix(sudo:session): session closed for user root May 02 15:36:04 musical-fidelity dhcpcd[1083]: wlan0: leased 192.168.68.66 for 7200 seconds May 02 15:36:04 musical-fidelity avahi-daemon[764]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.66. May 02 15:36:04 musical-fidelity dhcpcd[1083]: eth0: adding route to 192.168.68.0/22 May 02 15:36:04 musical-fidelity avahi-daemon[764]: New relevant interface wlan0.IPv4 for mDNS. May 02 15:36:04 musical-fidelity dhcpcd[1083]: eth0: adding default route via 192.168.68.1 May 02 15:36:04 musical-fidelity avahi-daemon[764]: Registering new address record for 192.168.68.66 on wlan0.IPv4. May 02 15:36:04 musical-fidelity dhcpcd[1083]: wlan0: adding host route to 192.168.68.66 via 127.0.0.1 May 02 15:36:04 musical-fidelity dhcpcd[1083]: wlan0: adding route to 192.168.68.0/22 May 02 15:36:04 musical-fidelity dhcpcd[1083]: wlan0: adding default route via 192.168.68.1 May 02 15:36:04 musical-fidelity dhcpcd[1083]: eth0: removing route to 192.168.68.0/22 May 02 15:36:04 musical-fidelity dhcpcd[1083]: eth0: removing default route via 192.168.68.1 May 02 15:36:05 musical-fidelity wireless.js[884]: trying... May 02 15:36:05 musical-fidelity sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 02 15:36:05 musical-fidelity sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:05 musical-fidelity sudo[1204]: pam_unix(sudo:session): session closed for user root May 02 15:36:05 musical-fidelity wireless.js[884]: Connected to: ----Thomsen-net May 02 15:36:05 musical-fidelity wireless.js[884]: ---- May 02 15:36:05 musical-fidelity sudo[1213]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 15:36:05 musical-fidelity sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:05 musical-fidelity sudo[1213]: pam_unix(sudo:session): session closed for user root May 02 15:36:05 musical-fidelity wireless.js[884]: ... joined AP, wlan0 IPv4 is 192.168.68.66, ipV6 is undefined May 02 15:36:05 musical-fidelity wireless.js[884]: It's done! AP May 02 15:36:05 musical-fidelity systemd[1]: Started Wireless Services. May 02 15:36:05 musical-fidelity systemd[1]: Starting Volumio Backend Module... May 02 15:36:05 musical-fidelity systemd[1]: Started Volumio Backend Module. May 02 15:36:05 musical-fidelity systemd[1]: Starting Start Volumio Kiosk... May 02 15:36:05 musical-fidelity systemd[1]: Started Start Volumio Kiosk. May 02 15:36:05 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... May 02 15:36:05 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. May 02 15:36:05 musical-fidelity systemd[1]: Starting Multi-User System. May 02 15:36:05 musical-fidelity systemd[1]: Reached target Multi-User System. May 02 15:36:05 musical-fidelity systemd[1]: Starting Graphical Interface. May 02 15:36:05 musical-fidelity systemd[1]: Reached target Graphical Interface. May 02 15:36:05 musical-fidelity systemd[1]: Starting Update UTMP about System Runlevel Changes... May 02 15:36:05 musical-fidelity systemd[1]: Started Update UTMP about System Runlevel Changes. May 02 15:36:05 musical-fidelity systemd[1]: Startup finished in 9.535s (kernel) + 26.219s (userspace) = 35.754s. May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: ############################ May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: # Volumio Streaming Daemon # May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: # Running on port 7777 # May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: ############################ May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: Checking variant in static cache May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: Enabled services: May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: 1) tidal May 02 15:36:05 musical-fidelity volumio-streaming-daemon[1224]: 2) qobuz May 02 15:36:05 musical-fidelity startx[1223]: X.Org X Server 1.18.4 May 02 15:36:05 musical-fidelity startx[1223]: Release Date: 2016-07-19 May 02 15:36:05 musical-fidelity startx[1223]: X Protocol Version 11, Revision 0 May 02 15:36:05 musical-fidelity startx[1223]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian May 02 15:36:05 musical-fidelity startx[1223]: Current Operating System: Linux musical-fidelity 4.9.65-v7+ #1 SMP Thu Jul 19 18:43:14 UTC 2018 armv7l May 02 15:36:05 musical-fidelity startx[1223]: Kernel command line: bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:A3:5F:EC vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=2 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait smsc95xx.turbo_mode=N bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 consoleblank=0 May 02 15:36:05 musical-fidelity startx[1223]: Build Date: 11 November 2016 11:59:59AM May 02 15:36:05 musical-fidelity startx[1223]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) May 02 15:36:05 musical-fidelity startx[1223]: Current version of pixman: 0.32.6 May 02 15:36:05 musical-fidelity startx[1223]: Before reporting problems, check http://wiki.x.org May 02 15:36:05 musical-fidelity startx[1223]: to make sure that you have the latest version. May 02 15:36:05 musical-fidelity startx[1223]: Markers: (--) probed, (**) from config file, (==) default setting, May 02 15:36:05 musical-fidelity startx[1223]: (++) from command line, (!!) notice, (II) informational, May 02 15:36:05 musical-fidelity startx[1223]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 02 15:36:05 musical-fidelity startx[1223]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri May 2 15:36:05 2025 May 02 15:36:05 musical-fidelity startx[1223]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 02 15:36:05 musical-fidelity startx[1223]: modprobe: FATAL: Module g2d_23 not found. May 02 15:36:05 musical-fidelity volumio-remote-updater[725]: [2025-05-02 15:36:05] [info] asio async_connect error: system:111 (Connection refused) May 02 15:36:05 musical-fidelity volumio-remote-updater[725]: [2025-05-02 15:36:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 02 15:36:05 musical-fidelity volumio-remote-updater[725]: [2025-05-02 15:36:05] [error] handle_connect error: Underlying Transport Error May 02 15:36:05 musical-fidelity ntpd[871]: Listen normally on 4 wlan0 192.168.68.66 UDP 123 May 02 15:36:05 musical-fidelity ntpd[871]: peers refreshed May 02 15:36:08 musical-fidelity volumio[1222]: info: ------------------------------------------- May 02 15:36:08 musical-fidelity volumio[1222]: info: ----- Volumio2 ---- May 02 15:36:08 musical-fidelity volumio[1222]: info: ------------------------------------------- May 02 15:36:08 musical-fidelity volumio[1222]: info: ----- System startup ---- May 02 15:36:08 musical-fidelity volumio[1222]: info: ------------------------------------------- May 02 15:36:09 musical-fidelity volumio[1222]: info: Plugin folders cleanup May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning into folder /volumio/app/plugins/ May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning category audio_interface May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning category miscellanea May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning category music_service May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning category plugins.json May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning category system_controller May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning category user_interface May 02 15:36:09 musical-fidelity volumio[1222]: info: Scanning into folder /data/plugins/ May 02 15:36:09 musical-fidelity volumio[1222]: info: Plugin folders cleanup completed May 02 15:36:09 musical-fidelity volumio[1222]: info: ------------------------------------------- May 02 15:36:09 musical-fidelity volumio[1222]: info: ----- Core plugins startup ---- May 02 15:36:09 musical-fidelity volumio[1222]: info: ------------------------------------------- May 02 15:36:09 musical-fidelity volumio[1222]: info: Loading plugins from folder /volumio/app/plugins/ May 02 15:36:09 musical-fidelity volumio[1222]: info: Adding plugin upnp to MyMusic Plugins May 02 15:36:09 musical-fidelity volumio[1222]: info: Adding plugin metavolumio to MyMusic Plugins May 02 15:36:09 musical-fidelity volumio[1222]: info: Adding plugin airplay_emulation to MyMusic Plugins May 02 15:36:09 musical-fidelity volumio[1222]: info: Adding plugin cd_controller to MyMusic Plugins May 02 15:36:09 musical-fidelity volumio[1222]: info: Adding plugin raat to MyMusic Plugins May 02 15:36:09 musical-fidelity volumio[1222]: info: Adding plugin upnp_browser to MyMusic Plugins May 02 15:36:09 musical-fidelity volumio[1222]: info: Loading plugins from folder /data/plugins/ May 02 15:36:09 musical-fidelity volumio[1222]: info: Loading plugin "system"... May 02 15:36:09 musical-fidelity volumio[1222]: info: Applying required configuration parameters for plugin system May 02 15:36:09 musical-fidelity volumio[1222]: No id defined. Creating one May 02 15:36:09 musical-fidelity volumio[1222]: info: Loading plugin "appearance"... May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "network"... May 02 15:36:11 musical-fidelity volumio[1222]: info: Refreshing Cached IP Addresses May 02 15:36:11 musical-fidelity sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "services"... May 02 15:36:11 musical-fidelity sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "alsa_controller"... May 02 15:36:11 musical-fidelity sudo[1309]: pam_unix(sudo:session): session closed for user root May 02 15:36:11 musical-fidelity sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 15:36:11 musical-fidelity sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:11 musical-fidelity sudo[1312]: pam_unix(sudo:session): session closed for user root May 02 15:36:11 musical-fidelity volumio[1222]: info: Applying required configuration parameters for plugin alsa_controller May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "wizard"... May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "volumio_command_line_client"... May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "upnp"... May 02 15:36:11 musical-fidelity volumio[1222]: info: [1746200171588] Starting Upmpd Daemon May 02 15:36:11 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "my_music"... May 02 15:36:11 musical-fidelity volumio[1222]: info: Loading plugin "mpd"... May 02 15:36:12 musical-fidelity volumio[1222]: info: Loading plugin "upnp_browser"... May 02 15:36:12 musical-fidelity volumio[1222]: info: Loading plugin "networkfs"... May 02 15:36:13 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 15:36:13 musical-fidelity volumio[1222]: info: Loading plugin "bluetooth"... May 02 15:36:13 musical-fidelity volumio[1222]: info: [1746200173272] Starting BluetoothController May 02 15:36:13 musical-fidelity volumio[1222]: info: Loading plugin "multiroom"... May 02 15:36:13 musical-fidelity volumio[1222]: info: Loading plugin "alarm-clock"... May 02 15:36:13 musical-fidelity volumio[1222]: info: Loading plugin "metavolumio"... May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "airplay_emulation"... May 02 15:36:14 musical-fidelity volumio[1222]: info: Starting Shairport Sync May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "cd_controller"... May 02 15:36:14 musical-fidelity sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync May 02 15:36:14 musical-fidelity sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:14 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:36:14 musical-fidelity volumio[1222]: info: Plugin last_100 is not enabled May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "raat"... May 02 15:36:14 musical-fidelity sudo[1346]: pam_unix(sudo:session): session closed for user root May 02 15:36:14 musical-fidelity volumio[1222]: info: RAAT Plugin loaded May 02 15:36:14 musical-fidelity volumio[1222]: info: Adding restartRAATSocket REST API Endpoint May 02 15:36:14 musical-fidelity volumio[1222]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "streaming_services"... May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "webradio"... May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "i2s_dacs"... May 02 15:36:14 musical-fidelity volumio[1222]: info: I2S DAC not set, start Auto-detection May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "volumiodiscovery"... May 02 15:36:14 musical-fidelity node[1222]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 02 15:36:14 musical-fidelity node[1222]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 15:36:14 musical-fidelity node[1222]: *** WARNING *** For more information see May 02 15:36:14 musical-fidelity node[1222]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 02 15:36:14 musical-fidelity node[1222]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 15:36:14 musical-fidelity node[1222]: *** WARNING *** For more information see May 02 15:36:14 musical-fidelity volumio[1222]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 02 15:36:14 musical-fidelity volumio[1222]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 15:36:14 musical-fidelity volumio[1222]: *** WARNING *** For more information see May 02 15:36:14 musical-fidelity volumio[1222]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 02 15:36:14 musical-fidelity volumio[1222]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 15:36:14 musical-fidelity volumio[1222]: *** WARNING *** For more information see May 02 15:36:14 musical-fidelity volumio[1222]: info: Applying required configuration parameters for plugin volumiodiscovery May 02 15:36:14 musical-fidelity volumio[1222]: Discovery: StartAdv! undefined May 02 15:36:14 musical-fidelity volumio[1222]: Discovery: Started advertising... undefined - undefined May 02 15:36:14 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 15:36:14 musical-fidelity volumio[1222]: info: Plugin outputs is not enabled May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "albumart"... May 02 15:36:14 musical-fidelity volumio[1222]: info: Plugin example_plugin is not enabled May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "hi_res_audio"... May 02 15:36:14 musical-fidelity volumio[1222]: info: Applying required configuration parameters for plugin hi_res_audio May 02 15:36:14 musical-fidelity volumio[1222]: info: Loading plugin "inputs"... May 02 15:36:15 musical-fidelity volumio[1222]: Forking 3 albumart workers May 02 15:36:15 musical-fidelity volumio[1222]: info: Adding inputs REST Endpoints May 02 15:36:15 musical-fidelity volumio[1222]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/inputs May 02 15:36:15 musical-fidelity volumio[1222]: info: Scanning Audio Inputs May 02 15:36:15 musical-fidelity volumio[1222]: info: Checking against Known Cards name May 02 15:36:15 musical-fidelity volumio[1222]: info: Loading plugin "qobuz"... May 02 15:36:15 musical-fidelity volumio-remote-updater[725]: [2025-05-02 15:36:15] [connect] Successful connection May 02 15:36:16 musical-fidelity volumio[1222]: Starting albumart workers May 02 15:36:16 musical-fidelity volumio[1222]: Starting albumart workers May 02 15:36:16 musical-fidelity volumio[1222]: Starting albumart workers May 02 15:36:17 musical-fidelity volumio[1222]: info: Loading plugin "spop"... May 02 15:36:18 musical-fidelity volumio[1222]: info: Loading plugin "tidal"... May 02 15:36:20 musical-fidelity volumio[1222]: info: Loading plugin "gpios"... May 02 15:36:20 musical-fidelity volumio[1222]: error: ATTENTION!!!: Plugin gpios does not return adequate promise from onVolumioStart: please update! May 02 15:36:20 musical-fidelity volumio[1222]: info: Loading plugin "updater_comm"... May 02 15:36:20 musical-fidelity volumio[1222]: info: Plugin mpdemulation is not enabled May 02 15:36:20 musical-fidelity volumio[1222]: info: Loading plugin "rest_api"... May 02 15:36:20 musical-fidelity volumio[1222]: info: Loading plugin "websocket"... May 02 15:36:20 musical-fidelity volumio[1222]: info: ___________ START PLUGINS ___________ May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding METAVOLUMIO REST API Endpoints May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 02 15:36:20 musical-fidelity volumio[1222]: info: [1746200180691] CoreMusicLibrary::Adding element Shairport-Sync May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 15:36:20 musical-fidelity sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name undefined May 02 15:36:20 musical-fidelity volumio[1222]: info: Preparing CD Folders May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding CD REST API Endpoints May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller May 02 15:36:20 musical-fidelity volumio[1222]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 02 15:36:20 musical-fidelity volumio[1222]: info: Detecting CD presence with Flag file May 02 15:36:20 musical-fidelity volumio[1222]: info: Starting RAAT Plugin May 02 15:36:20 musical-fidelity sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 02 15:36:20 musical-fidelity volumio[1222]: info: Additional UI Settings Added for plugin music_service/raat May 02 15:36:20 musical-fidelity sudo[1451]: pam_unix(sudo:session): session closed for user root May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: Streaming services startup May 02 15:36:20 musical-fidelity volumio[1222]: info: Starting Streaming Daemon May 02 15:36:20 musical-fidelity sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 02 15:36:20 musical-fidelity sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:20 musical-fidelity systemd[1]: Stopping Volumio Streaming Daemon... May 02 15:36:20 musical-fidelity systemd[1]: Starting Volumio Streaming Daemon... May 02 15:36:20 musical-fidelity systemd[1]: Started Volumio Streaming Daemon. May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 02 15:36:20 musical-fidelity volumio[1222]: info: [1746200180851] CoreMusicLibrary::Adding element Webradio May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 15:36:20 musical-fidelity sudo[1467]: pam_unix(sudo:session): session closed for user root May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: ############################ May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: # Volumio Streaming Daemon # May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: # Running on port 7777 # May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: ############################ May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: Checking variant in static cache May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: Enabled services: May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: 1) tidal May 02 15:36:20 musical-fidelity volumio-streaming-daemon[1478]: 2) qobuz May 02 15:36:20 musical-fidelity volumio[1222]: error: Hi Res Audio Failed Login: Missing Login Data May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 15:36:20 musical-fidelity volumio[1222]: [SpotifyConnect] Creating VLS config file May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:20 musical-fidelity volumio[1222]: [SpotifyConnect] SpotifyConnect Daemon config issues! May 02 15:36:20 musical-fidelity volumio[1222]: [SpotifyConnect] volspotify config error: [ 'device-name = "undefined" # Name of the Spotify connect device', May 02 15:36:20 musical-fidelity volumio[1222]: [length]: 1 ] May 02 15:36:20 musical-fidelity volumio[1222]: [SpotifyConnect] Starting metadata listener May 02 15:36:20 musical-fidelity volumio[1222]: info: Refreshing TIDAL token May 02 15:36:20 musical-fidelity sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 02 15:36:20 musical-fidelity sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:20 musical-fidelity volumio[1222]: error: Plugin gpios does not return adequate promise from onStart: please update! May 02 15:36:20 musical-fidelity systemd[1]: Starting Volspotconnect2 Daemon... May 02 15:36:20 musical-fidelity systemd[1]: Started Volspotconnect2 Daemon. May 02 15:36:20 musical-fidelity volumio[1222]: info: Loading i18n strings for locale da May 02 15:36:21 musical-fidelity sudo[1487]: pam_unix(sudo:session): session closed for user root May 02 15:36:21 musical-fidelity volumio[1222]: Updating browse sources language May 02 15:36:21 musical-fidelity volumio[1222]: Cannot find translation for sourceBluetooth May 02 15:36:21 musical-fidelity volumio[1222]: Cannot find translation for sourceUSB/PC May 02 15:36:21 musical-fidelity volumio[1222]: Cannot find translation for sourceShairport-Sync May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::initPlayerControls May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: BOOT COMPLETED May 02 15:36:21 musical-fidelity volumio[1222]: [Metrics] CommandRouter: 12s 513.26ms May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::Close All Modals sent May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::Close All Modals sent May 02 15:36:21 musical-fidelity volumio[1222]: Express server listening on port 3000 May 02 15:36:21 musical-fidelity volumio[1222]: [Metrics] WebUI: 13s 562.42ms May 02 15:36:21 musical-fidelity volumio[1222]: Volumio Calling Home May 02 15:36:21 musical-fidelity volumio[1492]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 May 02 15:36:21 musical-fidelity volumio[1492]: Reading Config from "/tmp/volspotify.toml" May 02 15:36:21 musical-fidelity volumio[1492]: Malformed config key: missing field `Authentication` May 02 15:36:21 musical-fidelity systemd[1]: volspotconnect.service: main process exited, code=exited, status=1/FAILURE May 02 15:36:21 musical-fidelity systemd[1]: Unit volspotconnect.service entered failed state. May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreStateMachine::resetVolumioState May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreStateMachine::getcurrentVolume May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume May 02 15:36:21 musical-fidelity volumio[1222]: info: Stopping AccessToken refresher cron for QOBUZ May 02 15:36:21 musical-fidelity volumio[1222]: info: AccessToken refresher cron started for QOBUZ May 02 15:36:21 musical-fidelity kernel: uart-pl011 3f201000.serial: no DMA platform data May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:36:21 musical-fidelity volumio[1222]: info: Serial port opened successfully May 02 15:36:21 musical-fidelity sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart leddaemon.service May 02 15:36:21 musical-fidelity sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:21 musical-fidelity systemd[1]: Starting LED Daemon Service... May 02 15:36:21 musical-fidelity systemd[1]: Started LED Daemon Service. May 02 15:36:21 musical-fidelity sudo[1520]: pam_unix(sudo:session): session closed for user root May 02 15:36:21 musical-fidelity led-daemon[1525]: Starting Network LED Daemon May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreStateMachine::pushState May 02 15:36:21 musical-fidelity volumio[1222]: info: CorePlayQueue::getTrack 0 May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::volumioPushState May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreStateMachine::updateTrackBlock May 02 15:36:21 musical-fidelity volumio[1222]: info: CorePlayQueue::getTrackBlock May 02 15:36:21 musical-fidelity volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume May 02 15:36:21 musical-fidelity volumio[1222]: info: Volumio called home May 02 15:36:21 musical-fidelity volumio[1222]: info: Setting Device type: Raspberry PI May 02 15:36:21 musical-fidelity volumio[1222]: [SpotifyConnect] Vollibrespot Daemon service started! May 02 15:36:21 musical-fidelity volumio[1222]: [Metrics] SpotifyConnect: 0s 709.57ms May 02 15:36:21 musical-fidelity volumio[1222]: info: RAAT Albumart path created successfully May 02 15:36:21 musical-fidelity volumio[1222]: info: Bluetooth name changed to undefined May 02 15:36:21 musical-fidelity volumio[1222]: info: Shairport-Sync Stopped May 02 15:36:21 musical-fidelity volumio[1222]: info: MPD running with PID769 May 02 15:36:21 musical-fidelity volumio[1222]: ,establishing connection May 02 15:36:21 musical-fidelity volumio[1222]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 15:36:21 musical-fidelity sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 02 15:36:21 musical-fidelity volumio[1222]: TypeError: Cannot read property 'charAt' of undefined May 02 15:36:21 musical-fidelity volumio[1222]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) May 02 15:36:21 musical-fidelity volumio[1222]: at ontimeout (timers.js:482:11) May 02 15:36:21 musical-fidelity volumio[1222]: at tryOnTimeout (timers.js:317:5) May 02 15:36:21 musical-fidelity volumio[1222]: at Timer.listOnTimeout (timers.js:277:5) May 02 15:36:21 musical-fidelity volumio[1222]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 15:36:21 musical-fidelity sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:36:21 musical-fidelity sudo[1540]: pam_unix(sudo:session): session closed for user root May 02 15:36:22 musical-fidelity sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 15:35 May 02 15:36:22 musical-fidelity sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"