Feb 12 11:01:11 musicbox1 ntpd[862]: CLOCK: time stepped by 167144.172812 Feb 12 11:01:11 musicbox1 ntpd[862]: CLOCK: time changed from 2026-02-10 to 2026-02-12 Feb 12 11:01:11 musicbox1 ntpd[862]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 12 11:01:11 musicbox1 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 12 11:01:11 musicbox1 systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Feb 12 11:01:11 musicbox1 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 12 11:01:11 musicbox1 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 12 11:01:12 musicbox1 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 12 11:01:12 musicbox1 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 12 11:01:12 musicbox1 fstrim[2001]: /boot: 273.6 MiB (286887936 bytes) trimmed on /dev/mmcblk0p1 Feb 12 11:01:12 musicbox1 systemd[1]: fstrim.service: Deactivated successfully. Feb 12 11:01:12 musicbox1 systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Feb 12 11:01:12 musicbox1 volumio[1880]: info: Loading plugin "autostart"... Feb 12 11:01:12 musicbox1 volumio[1880]: info: Applying required configuration parameters for plugin autostart Feb 12 11:01:12 musicbox1 volumio[1880]: info: AutoStart - onVolumioStart - read config.json Feb 12 11:01:12 musicbox1 volumio[1880]: info: Loading plugin "outputs"... Feb 12 11:01:12 musicbox1 volumio[1880]: info: Loading plugin "albumart"... Feb 12 11:01:12 musicbox1 volumio[1880]: info: Plugin example_plugin is not enabled Feb 12 11:01:12 musicbox1 volumio[1880]: info: Loading plugin "inputs"... Feb 12 11:01:12 musicbox1 volumio[1880]: info: Loading plugin "updater_comm"... Feb 12 11:01:13 musicbox1 volumio[1880]: info: Plugin mpdemulation is not enabled Feb 12 11:01:13 musicbox1 volumio[1880]: info: Loading plugin "rest_api"... Feb 12 11:01:13 musicbox1 volumio[1880]: info: Loading plugin "websocket"... Feb 12 11:01:13 musicbox1 volumio[1880]: info: Starting Socket.io Server version 1.7.4 Feb 12 11:01:13 musicbox1 volumio[1880]: info: Loading plugin "ir_controller"... Feb 12 11:01:13 musicbox1 volumio[1880]: info: Applying required configuration parameters for plugin ir_controller Feb 12 11:01:13 musicbox1 volumio[1880]: info: Loading plugin "Systeminfo"... Feb 12 11:01:14 musicbox1 volumio[2012]: Forking 3 albumart workers Feb 12 11:01:14 musicbox1 volumio-remote-updater[680]: [2026-02-12 11:01:14] [connect] Successful connection Feb 12 11:01:15 musicbox1 volumio[1880]: info: Loading i18n strings for locale de Feb 12 11:01:15 musicbox1 volumio[1880]: Updating browse sources language Feb 12 11:01:15 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 11:01:16 musicbox1 dhcpcd[784]: eth0: carrier lost Feb 12 11:01:16 musicbox1 kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Feb 12 11:01:16 musicbox1 avahi-daemon[659]: Withdrawing address record for 192.168.178.44 on eth0. Feb 12 11:01:16 musicbox1 avahi-daemon[659]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.44. Feb 12 11:01:16 musicbox1 avahi-daemon[659]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 12 11:01:16 musicbox1 dhcpcd[784]: eth0: deleting route to 192.168.178.0/24 Feb 12 11:01:16 musicbox1 dhcpcd[784]: eth0: deleting default route via 192.168.178.1 Feb 12 11:01:16 musicbox1 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 11:01:16 musicbox1 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 12 11:01:16 musicbox1 systemd[1]: welcome.service: Deactivated successfully. Feb 12 11:01:16 musicbox1 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 11:01:16 musicbox1 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 11:01:16 musicbox1 ifplugd(eth0)[880]: Link beat lost. Feb 12 11:01:16 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 11:01:16 musicbox1 volumio[1880]: info: CoreCommandRouter::initPlayerControls Feb 12 11:01:16 musicbox1 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 11:01:16 musicbox1 welcome[2093]: Resolved ip:[0] Feb 12 11:01:16 musicbox1 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 11:01:16 musicbox1 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 11:01:17 musicbox1 volumio[1880]: Express server listening on port 3000 Feb 12 11:01:17 musicbox1 volumio[1880]: [Metrics] WebUI: 16s 322.12ms Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreStateMachine::resetVolumioState Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreStateMachine::getcurrentVolume Feb 12 11:01:17 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 11:01:17 musicbox1 sudo[2110]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 11:01:17 musicbox1 sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 11:01:17 musicbox1 sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:17 musicbox1 ntpd[862]: IO: Deleting interface #3 eth0, 192.168.178.44#123, interface stats: received=48, sent=48, dropped=1, active_time=12 secs Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 172.233.59.234 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 86.80.166.233 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 45.83.205.227 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 193.36.132.241 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 178.239.19.61 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 103.204.195.5 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 162.159.200.1 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 195.35.113.80 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 5.39.184.12 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 158.101.216.150 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 185.51.192.63 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 212.34.149.56 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 81.172.248.188 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 94.237.125.46 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 178.239.19.63 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 ntpd[862]: PROTO: 103.149.168.181 unlink local addr 192.168.178.44 -> Feb 12 11:01:17 musicbox1 sudo[2110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:17 musicbox1 sudo[2109]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:17 musicbox1 volumio[1880]: info: Volumio Network Manager: Network status updated: 1 Feb 12 11:01:17 musicbox1 sudo[2110]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:18 musicbox1 volumio[1880]: info: CoreStateMachine::pushState Feb 12 11:01:18 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:18 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 11:01:18 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioPushState Feb 12 11:01:18 musicbox1 volumio[1880]: info: CoreStateMachine::updateTrackBlock Feb 12 11:01:18 musicbox1 volumio[1880]: info: CorePlayQueue::getTrackBlock Feb 12 11:01:18 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreStateMachine::pushState Feb 12 11:01:19 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioPushState Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 11:01:19 musicbox1 volumio[1880]: info: Reloading queue from file Feb 12 11:01:19 musicbox1 volumio-remote-updater[680]: [2026-02-12 11:01:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770890474 101 Feb 12 11:01:19 musicbox1 volumio[1880]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreStateMachine::setRepeat null single undefined Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreStateMachine::pushState Feb 12 11:01:19 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioPushState Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreStateMachine::setRandom null Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreStateMachine::pushState Feb 12 11:01:19 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:19 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioPushState Feb 12 11:01:19 musicbox1 volumio[1880]: info: Setting Device type: Raspberry PI Feb 12 11:01:20 musicbox1 volumio[1880]: info: Completed loading Core Plugins Feb 12 11:01:20 musicbox1 volumio[1880]: info: Preparing to generate the ALSA configuration file Feb 12 11:01:20 musicbox1 volumio[1880]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 12 11:01:20 musicbox1 volumio[1880]: info: Reading ALSA contributions from plugins. Feb 12 11:01:20 musicbox1 volumio[1880]: info: Discovery: A device disappeared from network Feb 12 11:01:20 musicbox1 volumio[1880]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Feb 12 11:01:20 musicbox1 volumio[1880]: info: Discovery: A device disappeared from network Feb 12 11:01:20 musicbox1 volumio[1880]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Feb 12 11:01:20 musicbox1 sudo[2130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 11:01:20 musicbox1 sudo[2130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:20 musicbox1 volumio[1880]: info: Discovery: adding 7a7224f9-0cf4-43ca-b034-d4edb9178309 Feb 12 11:01:20 musicbox1 volumio[1880]: info: Discovery: Found device Musicbox1 Feb 12 11:01:20 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioGetState Feb 12 11:01:20 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:20 musicbox1 sudo[2130]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:21 musicbox1 volumio[1880]: info: Upmpdcli Daemon Started Feb 12 11:01:21 musicbox1 volumio[1880]: info: Discovery: A device disappeared from network Feb 12 11:01:21 musicbox1 volumio[1880]: info: Discovery: Device musicbox1 disappeared from network Feb 12 11:01:21 musicbox1 volumio[1880]: info: Asound.conf file unchanged, so no further update is needed Feb 12 11:01:21 musicbox1 volumio[1880]: info: Output device has changed, restarting MPD Feb 12 11:01:21 musicbox1 volumio[1880]: info: Output device has changed, restarting Shairport Sync Feb 12 11:01:21 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:21 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 11:01:21 musicbox1 sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 11:01:21 musicbox1 sudo[2142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 11:01:21 musicbox1 sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:21 musicbox1 sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:21 musicbox1 sudo[2140]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:21 musicbox1 volumio[1880]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 11:01:21 musicbox1 volumio[1880]: info: ___________ START PLUGINS ___________ Feb 12 11:01:21 musicbox1 volumio[1880]: info: ControllerMpd::onStart: Initializing MPD Feb 12 11:01:21 musicbox1 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 11:01:21 musicbox1 volumio[1880]: info: Creating MPD Configuration file Feb 12 11:01:21 musicbox1 systemd[1]: mpd.service: Deactivated successfully. Feb 12 11:01:21 musicbox1 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 11:01:21 musicbox1 systemd[1]: mpd.service: Consumed 2.736s CPU time. Feb 12 11:01:21 musicbox1 systemd[1]: mpd.socket: Deactivated successfully. Feb 12 11:01:21 musicbox1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 11:01:21 musicbox1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 11:01:21 musicbox1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 11:01:21 musicbox1 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 11:01:21 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 11:01:21 musicbox1 sudo[2150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 11:01:21 musicbox1 sudo[2150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:21 musicbox1 sudo[2150]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:21 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 11:01:21 musicbox1 volumio[1880]: info: [1770890481985] CoreMusicLibrary::Adding element Medienserver Feb 12 11:01:22 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 11:01:22 musicbox1 sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 11:01:22 musicbox1 sudo[2154]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 11:01:22 musicbox1 sudo[2154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:22 musicbox1 sudo[2154]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:22 musicbox1 sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:22 musicbox1 systemd[1]: mpd.service: Deactivated successfully. Feb 12 11:01:22 musicbox1 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 11:01:22 musicbox1 systemd[1]: mpd.socket: Deactivated successfully. Feb 12 11:01:22 musicbox1 volumio[1880]: info: UPNP Browser: Client initialized successfully Feb 12 11:01:22 musicbox1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 11:01:22 musicbox1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 11:01:22 musicbox1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 11:01:22 musicbox1 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 11:01:22 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:22 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 11:01:22 musicbox1 sudo[2160]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 11:01:22 musicbox1 sudo[2160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:22 musicbox1 sudo[2160]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:22 musicbox1 volumio[1880]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 11:01:22 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:22 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 11:01:22 musicbox1 volumio[1880]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 11:01:23 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 11:01:23 musicbox1 volumio[1880]: info: [1770890483021] CoreMusicLibrary::Adding element Last_100 Feb 12 11:01:23 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 11:01:23 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 11:01:23 musicbox1 volumio[1880]: info: [1770890483025] CoreMusicLibrary::Adding element Webradio Feb 12 11:01:23 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 11:01:23 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 11:01:23 musicbox1 volumio[1880]: info: Initializing BBC Radios Feb 12 11:01:24 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 11:01:24 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 11:01:24 musicbox1 volumio[1880]: info: Creating Spotify config file Feb 12 11:01:24 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:25 musicbox1 mpd[2166]: 2026-02-12T11:01:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 11:01:25 musicbox1 systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 11:01:25 musicbox1 sudo[2142]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:25 musicbox1 sudo[2153]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:25 musicbox1 volumio[2023]: Starting albumart workers Feb 12 11:01:25 musicbox1 volumio[2022]: Starting albumart workers Feb 12 11:01:26 musicbox1 volumio[2024]: Starting albumart workers Feb 12 11:01:26 musicbox1 volumio[1880]: info: [squeezelite_mc] Starting proxy server... Feb 12 11:01:26 musicbox1 volumio[1880]: info: AutoStart - onStart Feb 12 11:01:26 musicbox1 volumio[1880]: info: Loading i18n strings for locale de Feb 12 11:01:26 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 11:01:26 musicbox1 volumio[1880]: info: Volumio Calling Home Feb 12 11:01:26 musicbox1 ifplugd(eth0)[880]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 12 11:01:27 musicbox1 dhcpcd[2213]: dhcpcd not running Feb 12 11:01:27 musicbox1 ifplugd(eth0)[880]: client: dhcpcd not running Feb 12 11:01:27 musicbox1 volumio[1880]: info: [squeezelite_mc] Proxy server started on port 36447 Feb 12 11:01:27 musicbox1 wireless.js[684]: WIRELESS.JS: Wired network status changed to: ---disconnected--- Feb 12 11:01:27 musicbox1 wireless.js[684]: WIRELESS.JS: Wireless.js initializing wireless flow Feb 12 11:01:27 musicbox1 wireless.js[684]: WIRELESS.JS: Cleaning previous... Feb 12 11:01:27 musicbox1 ifplugd(eth0)[880]: Program executed successfully. Feb 12 11:01:27 musicbox1 kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Feb 12 11:01:27 musicbox1 kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Feb 12 11:01:27 musicbox1 sudo[2232]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 12 11:01:27 musicbox1 sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:27 musicbox1 sudo[2232]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:27 musicbox1 sudo[2234]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 12 11:01:27 musicbox1 sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:28 musicbox1 sudo[2234]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:28 musicbox1 sudo[2237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 12 11:01:28 musicbox1 sudo[2237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:28 musicbox1 sudo[2237]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:28 musicbox1 wireless.js[684]: WIRELESS.JS: Stopped aP Feb 12 11:01:28 musicbox1 sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 11:01:28 musicbox1 sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:28 musicbox1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 12 11:01:28 musicbox1 sudo[2245]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:28 musicbox1 sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 12 11:01:28 musicbox1 sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:28 musicbox1 sudo[2247]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:28 musicbox1 sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 11:01:28 musicbox1 sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:28 musicbox1 sudo[2255]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:29 musicbox1 sudo[2257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 12 11:01:29 musicbox1 sudo[2257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:29 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 11:01:29 musicbox1 volumio[1880]: info: Discovery: adding 7a7224f9-0cf4-43ca-b034-d4edb9178309 Feb 12 11:01:29 musicbox1 volumio[1880]: info: Discovery: Found device Musicbox1 Feb 12 11:01:29 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioGetState Feb 12 11:01:29 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:30 musicbox1 volumio[1880]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Feb 12 11:01:30 musicbox1 ifplugd(eth0)[880]: Link beat detected. Feb 12 11:01:30 musicbox1 dhcpcd[784]: eth0: carrier acquired Feb 12 11:01:30 musicbox1 kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Feb 12 11:01:30 musicbox1 ifplugd(eth0)[880]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 12 11:01:30 musicbox1 volumio[1880]: Unhandled rejection Error: No sockets available, cannot start. Feb 12 11:01:30 musicbox1 volumio[1880]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 12 11:01:30 musicbox1 volumio[1880]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 12 11:01:30 musicbox1 volumio[1880]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 12 11:01:30 musicbox1 volumio[1880]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 12 11:01:30 musicbox1 volumio[1880]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 12 11:01:30 musicbox1 volumio[1880]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 12 11:01:30 musicbox1 volumio[1880]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 12 11:01:30 musicbox1 volumio[1880]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 12 11:01:30 musicbox1 volumio[1880]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Feb 12 11:01:30 musicbox1 volumio[1880]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 12 11:01:30 musicbox1 volumio[1880]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 12 11:01:30 musicbox1 volumio[1880]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 12 11:01:30 musicbox1 volumio[1880]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 12 11:01:30 musicbox1 volumio[1880]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 12 11:01:30 musicbox1 volumio[1880]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 12 11:01:30 musicbox1 volumio[1880]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 12 11:01:30 musicbox1 dhcpcd[784]: eth0: IAID eb:67:47:12 Feb 12 11:01:30 musicbox1 dhcpcd[784]: eth0: soliciting an IPv6 router Feb 12 11:01:30 musicbox1 dhcpcd[784]: ps_ctl_dispatch: cannot handle another client Feb 12 11:01:30 musicbox1 ifplugd(eth0)[880]: client: sending commands to dhcpcd process Feb 12 11:01:30 musicbox1 dhcpcd[784]: control_free: No such file or directory Feb 12 11:01:30 musicbox1 volumio[1880]: info: Volumio Network Manager: Network status updated: 0 Feb 12 11:01:30 musicbox1 volumio[1880]: info: CoreStateMachine::pushState Feb 12 11:01:30 musicbox1 volumio[1880]: info: CorePlayQueue::getTrack 0 Feb 12 11:01:30 musicbox1 volumio[1880]: info: CoreCommandRouter::volumioPushState Feb 12 11:01:30 musicbox1 dhcpcd[784]: eth0: rebinding lease of 192.168.178.44 Feb 12 11:01:31 musicbox1 dhcpcd[784]: eth0: probing address 192.168.178.44/24 Feb 12 11:01:31 musicbox1 volumio[1880]: info: [squeezelite_mc] Server discovery started Feb 12 11:01:31 musicbox1 volumio[1880]: info: [squeezelite_mc] Player finder started Feb 12 11:01:31 musicbox1 ifplugd(eth0)[880]: Program executed successfully. Feb 12 11:01:31 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:31 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:31 musicbox1 volumio[1880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 11:01:31 musicbox1 volumio[1880]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 12 11:01:31 musicbox1 volumio[1880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 11:01:31 musicbox1 volumio[1880]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 12 11:01:31 musicbox1 volumio[1880]: at doSend (node:dgram:717:16) Feb 12 11:01:31 musicbox1 volumio[1880]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Feb 12 11:01:31 musicbox1 volumio[1880]: at afterDns (node:dgram:663:5) Feb 12 11:01:31 musicbox1 volumio[1880]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Feb 12 11:01:31 musicbox1 volumio[1880]: errno: -101, Feb 12 11:01:31 musicbox1 volumio[1880]: code: 'ENETUNREACH', Feb 12 11:01:31 musicbox1 volumio[1880]: syscall: 'send', Feb 12 11:01:31 musicbox1 volumio[1880]: address: '255.255.255.255', Feb 12 11:01:31 musicbox1 volumio[1880]: port: 3483 Feb 12 11:01:31 musicbox1 volumio[1880]: } Feb 12 11:01:31 musicbox1 volumio[1880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 11:01:31 musicbox1 sudo[2257]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:31 musicbox1 wireless.js[684]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Feb 12 11:01:31 musicbox1 sudo[2339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 11:01:31 musicbox1 sudo[2339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:31 musicbox1 sudo[2339]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:31 musicbox1 sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Feb 12 11:01:31 musicbox1 sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:31 musicbox1 sudo[2345]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:31 musicbox1 wireless.js[684]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Feb 12 11:01:31 musicbox1 wireless.js[684]: WIRELESS.JS: Wired network status changed to: ---connected--- Feb 12 11:01:31 musicbox1 wireless.js[684]: WIRELESS.JS: Wireless.js initializing wireless flow Feb 12 11:01:31 musicbox1 wireless.js[684]: WIRELESS.JS: Cleaning previous... Feb 12 11:01:32 musicbox1 sudo[2362]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 12 11:01:32 musicbox1 sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:32 musicbox1 sudo[2362]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:32 musicbox1 sudo[2364]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 12 11:01:32 musicbox1 sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:32 musicbox1 sudo[2364]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:32 musicbox1 sudo[2366]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 12 11:01:32 musicbox1 sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:32 musicbox1 sudo[2366]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:32 musicbox1 sudo[2369]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 12 11:01:32 musicbox1 sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 11:01:32 musicbox1 avahi-daemon[659]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 12 11:01:32 musicbox1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 12 11:01:32 musicbox1 avahi-daemon[659]: New relevant interface wlan0.IPv4 for mDNS. Feb 12 11:01:32 musicbox1 avahi-daemon[659]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Feb 12 11:01:32 musicbox1 systemd[1]: welcome.service: Deactivated successfully. Feb 12 11:01:32 musicbox1 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 11:01:32 musicbox1 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 11:01:32 musicbox1 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 11:01:33 musicbox1 avahi-daemon[659]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 12 11:01:33 musicbox1 avahi-daemon[659]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 12 11:01:33 musicbox1 sudo[2369]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:33 musicbox1 avahi-daemon[659]: Withdrawing address record for 192.168.211.1 on wlan0. Feb 12 11:01:33 musicbox1 avahi-daemon[659]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 12 11:01:33 musicbox1 avahi-daemon[659]: New relevant interface wlan0.IPv4 for mDNS. Feb 12 11:01:33 musicbox1 welcome[2375]: Resolved ip:[1] 192.168.211.1 Feb 12 11:01:33 musicbox1 avahi-daemon[659]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Feb 12 11:01:33 musicbox1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 12 11:01:33 musicbox1 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 11:01:33 musicbox1 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 11:01:33 musicbox1 wireless.js[684]: WIRELESS.JS: Notified systemd about wireless ready Feb 12 11:01:33 musicbox1 systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Feb 12 11:01:33 musicbox1 dhcpcd[784]: wlan0: carrier acquired Feb 12 11:01:33 musicbox1 hostapd[2385]: wlan0: interface state UNINITIALIZED->ENABLED Feb 12 11:01:33 musicbox1 hostapd[2385]: wlan0: AP-ENABLED Feb 12 11:01:33 musicbox1 dhcpcd[784]: wlan0: IAID eb:32:12:47 Feb 12 11:01:33 musicbox1 systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory Feb 12 11:01:33 musicbox1 dhcpcd[784]: wlan0: adding address fe80::1211:ccd2:706d:9a0d Feb 12 11:01:33 musicbox1 systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Feb 12 11:01:33 musicbox1 dhcpcd[784]: ipv6_addaddr1: Permission denied Feb 12 11:01:33 musicbox1 dhcpcd[784]: wlan0: soliciting an IPv6 router Feb 12 11:01:33 musicbox1 systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Feb 12 11:01:34 musicbox1 wireless.js[684]: WIRELESS.JS: Stopped aP Feb 12 11:01:34 musicbox1 dnsmasq[2397]: started, version 2.90 cachesize 150 Feb 12 11:01:34 musicbox1 dnsmasq[2397]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Feb 12 11:01:34 musicbox1 dnsmasq-dhcp[2397]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Feb 12 11:01:34 musicbox1 dnsmasq[2397]: reading /etc/resolv.conf Feb 12 11:01:34 musicbox1 dnsmasq[2397]: using nameserver 208.67.222.222#53 Feb 12 11:01:34 musicbox1 dnsmasq[2397]: using nameserver 208.67.220.220#53 Feb 12 11:01:34 musicbox1 dnsmasq[2397]: read /etc/hosts - 4 names Feb 12 11:01:34 musicbox1 systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Feb 12 11:01:34 musicbox1 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 12 11:01:34 musicbox1 sudo[2395]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 11:01:34 musicbox1 sudo[2395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:34 musicbox1 sudo[2395]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:34 musicbox1 sudo[2401]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 12 11:01:34 musicbox1 sudo[2401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:34 musicbox1 sudo[2401]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:34 musicbox1 sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 11:01:34 musicbox1 sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:34 musicbox1 sudo[2409]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:34 musicbox1 sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 12 11:01:34 musicbox1 sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:34 musicbox1 dhcpcd[784]: wlan0: soliciting a DHCP lease Feb 12 11:01:35 musicbox1 ntpd[862]: IO: Listen normally on 4 wlan0 192.168.211.1:123 Feb 12 11:01:35 musicbox1 ntpd[862]: IO: new interface(s) found: waking up resolver Feb 12 11:01:35 musicbox1 dhcpcd[784]: eth0: leased 192.168.178.44 for 864000 seconds Feb 12 11:01:35 musicbox1 dhcpcd[784]: eth0: adding route to 192.168.178.0/24 Feb 12 11:01:35 musicbox1 dhcpcd[784]: eth0: adding default route via 192.168.178.1 Feb 12 11:01:35 musicbox1 avahi-daemon[659]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.44. Feb 12 11:01:35 musicbox1 avahi-daemon[659]: New relevant interface eth0.IPv4 for mDNS. Feb 12 11:01:35 musicbox1 avahi-daemon[659]: Registering new address record for 192.168.178.44 on eth0.IPv4. Feb 12 11:01:35 musicbox1 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 11:01:35 musicbox1 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 12 11:01:35 musicbox1 systemd[1]: welcome.service: Deactivated successfully. Feb 12 11:01:35 musicbox1 systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 11:01:35 musicbox1 systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 11:01:35 musicbox1 dnsmasq[2397]: reading /etc/resolv.conf Feb 12 11:01:35 musicbox1 dnsmasq[2397]: using nameserver 192.168.178.1#53 Feb 12 11:01:35 musicbox1 dnsmasq[2397]: using nameserver 208.67.222.222#53 Feb 12 11:01:35 musicbox1 dnsmasq[2397]: using nameserver 208.67.220.220#53 Feb 12 11:01:35 musicbox1 systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 11:01:35 musicbox1 welcome[2431]: Resolved ip:[2] 192.168.178.44 192.168.211.1 Feb 12 11:01:36 musicbox1 systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 11:01:36 musicbox1 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 11:01:37 musicbox1 ntpd[862]: IO: Listen normally on 5 eth0 192.168.178.44:123 Feb 12 11:01:37 musicbox1 ntpd[862]: IO: new interface(s) found: waking up resolver Feb 12 11:01:38 musicbox1 sudo[2411]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:38 musicbox1 wireless.js[684]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Feb 12 11:01:38 musicbox1 sudo[2458]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 11:01:38 musicbox1 sudo[2458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:38 musicbox1 sudo[2458]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:38 musicbox1 sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Feb 12 11:01:38 musicbox1 sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 11:01:38 musicbox1 sudo[2462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 11:00' Feb 12 11:01:38 musicbox1 sudo[2463]: pam_unix(sudo:session): session closed for user root Feb 12 11:01:38 musicbox1 wireless.js[684]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Feb 12 11:01:38 musicbox1 wireless.js[684]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Feb 12 11:01:38 musicbox1 wireless.js[684]: WIRELESS.JS: Notified systemd about wireless ready Feb 12 11:01:38 musicbox1 sudo[2462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"