-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2025-03-05 07:26:39 UTC. -- Mar 05 07:25:52 volumio systemd-timedated[990]: Changed local time to Wed Mar 5 07:25:52 2025 Mar 05 07:25:52 volumio sudo[981]: pam_unix(sudo:session): session closed for user root Mar 05 07:25:52 volumio volumio-time-update[653]: volumio-time-update-util: System time updated successfully. Mar 05 07:25:52 volumio systemd[1]: Starting Daily apt download activities... Mar 05 07:25:52 volumio systemd[1]: Started Volumio Time Update Utility. Mar 05 07:25:52 volumio winbindd[982]: [2025/03/05 07:25:52.066372, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 05 07:25:52 volumio winbindd[982]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 05 07:25:54 volumio winbindd[982]: [2025/03/05 07:25:54.015458, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 05 07:25:54 volumio systemd[1]: Started Samba Winbind Daemon. Mar 05 07:25:54 volumio winbindd[982]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 05 07:25:54 volumio systemd[1]: Starting Samba SMB Daemon... Mar 05 07:25:57 volumio volumio-remote-updater[637]: [2025-03-05 07:25:57] [info] asio async_connect error: system:111 (Connection refused) Mar 05 07:25:57 volumio volumio-remote-updater[637]: [2025-03-05 07:25:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 05 07:25:57 volumio volumio-remote-updater[637]: [2025-03-05 07:25:57] [error] handle_connect error: Underlying Transport Error Mar 05 07:26:02 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 05 07:26:04 volumio smbd[997]: [2025/03/05 07:26:04.153949, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 05 07:26:04 volumio smbd[997]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 05 07:26:04 volumio systemd[1]: Started Samba SMB Daemon. Mar 05 07:26:04 volumio systemd[1]: Reached target Multi-User System. Mar 05 07:26:04 volumio systemd[1]: Reached target Graphical Interface. Mar 05 07:26:04 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 05 07:26:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 05 07:26:04 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 05 07:26:04 volumio systemd[1]: apt-daily.service: Succeeded. Mar 05 07:26:04 volumio systemd[1]: Started Daily apt download activities. Mar 05 07:26:04 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Mar 05 07:26:04 volumio firststart.sh[963]: debconf: unable to initialize frontend: Dialog Mar 05 07:26:04 volumio firststart.sh[963]: debconf: (TERM is not set, so the dialog frontend is not usable.) Mar 05 07:26:04 volumio firststart.sh[963]: debconf: falling back to frontend: Readline Mar 05 07:26:04 volumio firststart.sh[963]: debconf: unable to initialize frontend: Readline Mar 05 07:26:04 volumio firststart.sh[963]: debconf: (This frontend requires a controlling tty.) Mar 05 07:26:04 volumio firststart.sh[963]: debconf: falling back to frontend: Teletype Mar 05 07:26:05 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 05 07:26:05 volumio systemd[1]: Started Daily apt upgrade and clean activities. Mar 05 07:26:05 volumio systemd[1]: Startup finished in 1min 46.477s (kernel) + 40.977s (userspace) = 2min 27.454s. Mar 05 07:26:06 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 05 07:26:07 volumio volumio[961]: info: ------------------------------------------- Mar 05 07:26:07 volumio volumio[961]: info: ----- Volumio3 ---- Mar 05 07:26:07 volumio volumio[961]: info: ------------------------------------------- Mar 05 07:26:07 volumio volumio[961]: info: ----- System startup ---- Mar 05 07:26:07 volumio volumio[961]: info: ------------------------------------------- Mar 05 07:26:07 volumio volumio-remote-updater[637]: [2025-03-05 07:26:07] [connect] Successful connection Mar 05 07:26:08 volumio firststart.sh[963]: Creating SSH2 RSA key; this may take some time ... Mar 05 07:26:08 volumio firststart.sh[963]: 2048 SHA256:2aJKFdj0/B99IipWZ3CSiPy/sUJ1tnXhc0524g0WpCs root@volumio (RSA) Mar 05 07:26:08 volumio volumio[961]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Mar 05 07:26:08 volumio volumio[961]: info: MYVOLUMIO Environment detected Mar 05 07:26:08 volumio volumio[961]: info: Plugin folders cleanup Mar 05 07:26:08 volumio volumio[961]: info: Scanning into folder /volumio/app/plugins/ Mar 05 07:26:08 volumio volumio[961]: info: Scanning category audio_interface Mar 05 07:26:08 volumio volumio[961]: info: Scanning category miscellanea Mar 05 07:26:08 volumio volumio[961]: info: Scanning category music_service Mar 05 07:26:08 volumio volumio[961]: info: Scanning category plugins.json Mar 05 07:26:08 volumio volumio[961]: info: Scanning category system_controller Mar 05 07:26:08 volumio volumio[961]: info: Scanning category user_interface Mar 05 07:26:08 volumio volumio[961]: info: Scanning into folder /data/plugins/ Mar 05 07:26:08 volumio volumio[961]: info: Plugin folders cleanup completed Mar 05 07:26:08 volumio volumio[961]: info: ------------------------------------------- Mar 05 07:26:08 volumio volumio[961]: info: ----- Core plugins startup ---- Mar 05 07:26:08 volumio volumio[961]: info: ------------------------------------------- Mar 05 07:26:08 volumio volumio[961]: info: Loading plugins from folder /volumio/app/plugins/ Mar 05 07:26:08 volumio volumio[961]: info: Adding plugin upnp to MyMusic Plugins Mar 05 07:26:08 volumio volumio[961]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 05 07:26:08 volumio volumio[961]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 05 07:26:08 volumio volumio[961]: info: Loading plugins from folder /data/plugins/ Mar 05 07:26:08 volumio volumio[961]: info: Loading plugin "system"... Mar 05 07:26:08 volumio volumio[961]: info: No id defined. Creating one Mar 05 07:26:08 volumio volumio[961]: info: Loading plugin "appearance"... Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "network"... Mar 05 07:26:09 volumio volumio[961]: info: Refreshing Cached IP Addresses Mar 05 07:26:09 volumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 05 07:26:09 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:09 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:09 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 05 07:26:09 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:09 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "services"... Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "alsa_controller"... Mar 05 07:26:09 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 05 07:26:09 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:09 volumio volumio[961]: warn: The ALSA output card is not set, defaulting to card [object Object] Mar 05 07:26:09 volumio firststart.sh[963]: Creating SSH2 DSA key; this may take some time ... Mar 05 07:26:09 volumio firststart.sh[963]: 1024 SHA256:szsPhRP4XDYpxEHQreROPsvMAjw2WmgI4+3VG4bbU3Q root@volumio (DSA) Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 05 07:26:09 volumio volumio[961]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Mar 05 07:26:09 volumio firststart.sh[963]: Creating SSH2 ECDSA key; this may take some time ... Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 05 07:26:09 volumio firststart.sh[963]: 256 SHA256:iDM/H7g9AN7jjATc9X85Y85VL5LoQHwsjADMLHrrTa0 root@volumio (ECDSA) Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "wizard"... Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "networkfs"... Mar 05 07:26:09 volumio volumio[961]: info: Starting Udev Watcher for removable devices Mar 05 07:26:09 volumio volumio[961]: info: Ignoring mount for partition: boot Mar 05 07:26:09 volumio volumio[961]: info: Ignoring mount for partition: volumio Mar 05 07:26:09 volumio volumio[961]: info: Ignoring mount for partition: volumio_data Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "volumio_command_line_client"... Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "upnp"... Mar 05 07:26:09 volumio volumio[961]: info: [1741159569791] Starting Upmpd Daemon Mar 05 07:26:09 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "my_music"... Mar 05 07:26:09 volumio volumio[961]: info: Loading plugin "mpd"... Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "upnp_browser"... Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "alarm-clock"... Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "airplay_emulation"... Mar 05 07:26:10 volumio volumio[961]: info: Starting Shairport Sync Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "last_100"... Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "webradio"... Mar 05 07:26:10 volumio systemd[1]: Reloading. Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "i2s_dacs"... Mar 05 07:26:10 volumio volumio[961]: info: I2S DAC not set, start Auto-detection Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "volumiodiscovery"... Mar 05 07:26:10 volumio volumio[961]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:10 volumio node[961]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:10 volumio volumio[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:10 volumio node[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:10 volumio volumio[961]: *** WARNING *** For more information see Mar 05 07:26:10 volumio node[961]: *** WARNING *** For more information see Mar 05 07:26:10 volumio volumio[961]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:10 volumio node[961]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:10 volumio volumio[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:10 volumio node[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:10 volumio volumio[961]: *** WARNING *** For more information see Mar 05 07:26:10 volumio node[961]: *** WARNING *** For more information see Mar 05 07:26:10 volumio volumio[961]: info: Discovery: Started advertising with name: Volumio Mar 05 07:26:10 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "outputs"... Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "albumart"... Mar 05 07:26:10 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 05 07:26:10 volumio volumio[961]: info: Plugin example_plugin is not enabled Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "inputs"... Mar 05 07:26:10 volumio volumio[961]: info: Loading plugin "updater_comm"... Mar 05 07:26:11 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 05 07:26:11 volumio volumio[961]: info: Plugin mpdemulation is not enabled Mar 05 07:26:11 volumio volumio[961]: info: Loading plugin "rest_api"... Mar 05 07:26:11 volumio volumio[961]: info: Loading plugin "websocket"... Mar 05 07:26:11 volumio volumio[961]: Forking 3 albumart workers Mar 05 07:26:11 volumio volumio[961]: info: Starting Socket.io Server version 2.3.0 Mar 05 07:26:11 volumio volumio[961]: info: Loading i18n strings for locale en Mar 05 07:26:11 volumio volumio[961]: Updating browse sources language Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:11 volumio firststart.sh[963]: rescue-ssh.target is a disabled or a static unit, not starting it. Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::initPlayerControls Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 07:26:11 volumio volumio[961]: Express server listening on port 3000 Mar 05 07:26:11 volumio volumio[961]: [Metrics] WebUI: 5s 712.62ms Mar 05 07:26:11 volumio volumio[961]: info: CoreStateMachine::resetVolumioState Mar 05 07:26:11 volumio volumio[961]: info: CoreStateMachine::getcurrentVolume Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 07:26:11 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:11 volumio firststart.sh[963]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Mar 05 07:26:11 volumio firststart.sh[963]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Mar 05 07:26:11 volumio volumio[961]: Starting albumart workers Mar 05 07:26:11 volumio volumio[961]: Starting albumart workers Mar 05 07:26:11 volumio volumio[961]: Starting albumart workers Mar 05 07:26:12 volumio volumio[961]: info: Cannot read play queue from file Mar 05 07:26:12 volumio volumio[961]: info: Volumio Network Manager: Network status updated: 1 Mar 05 07:26:12 volumio systemd[1]: Reloading. Mar 05 07:26:12 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 05 07:26:13 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:13 volumio volumio[961]: info: VolumeController:: Volume=86 Mute =false Mar 05 07:26:13 volumio volumio[961]: info: CoreStateMachine::pushState Mar 05 07:26:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:13 volumio volumio[961]: info: CoreStateMachine::updateTrackBlock Mar 05 07:26:13 volumio volumio[961]: info: CorePlayQueue::getTrackBlock Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 07:26:13 volumio volumio[961]: info: Setting Device type: Raspberry PI Mar 05 07:26:13 volumio volumio[961]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 05 07:26:13 volumio volumio[961]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Mar 05 07:26:13 volumio volumio[961]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 05 07:26:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:13 volumio volumio[961]: info: VolumeController:: Volume=86 Mute =false Mar 05 07:26:13 volumio volumio[961]: info: CoreStateMachine::pushState Mar 05 07:26:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:13 volumio volumio[961]: info: Discovery: adding b63b1697-2f55-4c49-9f80-0d914f11192a Mar 05 07:26:13 volumio volumio[961]: info: Discovery: Found device Volumio Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Mar 05 07:26:13 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 05 07:26:13 volumio volumio[961]: info: CoreStateMachine::getcurrentVolume Mar 05 07:26:13 volumio volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 07:26:15 volumio firststart.sh[963]: Enabling SSH for first boot Mar 05 07:26:15 volumio volumio[961]: info: Completed loading Core Plugins Mar 05 07:26:15 volumio volumio[961]: info: Preparing to generate the ALSA configuration file Mar 05 07:26:15 volumio volumio[961]: info: VolumeController:: Volume=86 Mute =false Mar 05 07:26:15 volumio volumio[961]: info: CoreStateMachine::pushState Mar 05 07:26:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:15 volumio volumio[961]: info: CoreStateMachine::updateTrackBlock Mar 05 07:26:15 volumio volumio[961]: info: CorePlayQueue::getTrackBlock Mar 05 07:26:15 volumio volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 07:26:15 volumio volumio[961]: info: Discovery: this is already registered, b63b1697-2f55-4c49-9f80-0d914f11192a Mar 05 07:26:15 volumio volumio[961]: info: Discovery: Found device Volumio Mar 05 07:26:15 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Mar 05 07:26:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:15 volumio systemd[1]: Starting OpenBSD Secure Shell server... Mar 05 07:26:15 volumio volumio[961]: info: VolumeController:: Volume=86 Mute =false Mar 05 07:26:15 volumio volumio[961]: info: CoreStateMachine::pushState Mar 05 07:26:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:15 volumio volumio[961]: info: Asound.conf file written Mar 05 07:26:16 volumio sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 05 07:26:16 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:16 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:16 volumio volumio[961]: No state is present for card b1 Mar 05 07:26:16 volumio volumio[961]: /usr/share/alsa/init/bcm2835:14: control element not found Mar 05 07:26:16 volumio volumio[961]: No state is present for card b1 Mar 05 07:26:16 volumio volumio[961]: No state is present for card Headphones Mar 05 07:26:16 volumio volumio[961]: /usr/share/alsa/init/bcm2835:20: control element not found Mar 05 07:26:16 volumio volumio[961]: No state is present for card Headphones Mar 05 07:26:16 volumio volumio[961]: info: Output device has changed, restarting MPD Mar 05 07:26:16 volumio volumio[961]: info: Output device has changed, restarting Shairport Sync Mar 05 07:26:16 volumio sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 07:26:16 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:16 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 07:26:16 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:16 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 07:26:16 volumio volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 07:26:16 volumio volumio[961]: info: ___________ START PLUGINS ___________ Mar 05 07:26:16 volumio sshd[1436]: Server listening on 0.0.0.0 port 22. Mar 05 07:26:16 volumio sshd[1436]: Server listening on :: port 22. Mar 05 07:26:16 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 05 07:26:16 volumio firststart.sh[963]: Disabling firststart service Mar 05 07:26:16 volumio volumio[961]: info: Setting Hotspot Unique name on first start: Volumio-C76A9 Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Mar 05 07:26:16 volumio volumio[961]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::Reload Ui Mar 05 07:26:16 volumio volumio[961]: info: ControllerMpd::onStart: Initializing MPD Mar 05 07:26:16 volumio volumio[961]: info: Creating MPD Configuration file Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 05 07:26:16 volumio volumio[961]: info: [1741159576219] CoreMusicLibrary::Adding element Media Servers Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:16 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:17 volumio sudo[1438]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:17 volumio sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 07:26:17 volumio sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 07:26:17 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:17 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:17 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:17 volumio volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:17 volumio firststart.sh[963]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Mar 05 07:26:17 volumio systemd[1]: Reloading. Mar 05 07:26:17 volumio volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 05 07:26:17 volumio volumio[961]: info: [1741159577607] CoreMusicLibrary::Adding element Last_100 Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 05 07:26:17 volumio volumio[961]: info: [1741159577610] CoreMusicLibrary::Adding element Webradio Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 07:26:17 volumio volumio[961]: info: Initializing BBC Radios Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:17 volumio volumio[961]: info: Volumio Calling Home Mar 05 07:26:17 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 05 07:26:17 volumio volumio[961]: info: MPD Permissions set Mar 05 07:26:17 volumio volumio[961]: info: MPD Permissions set Mar 05 07:26:17 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 05 07:26:17 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:17 volumio volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 05 07:26:17 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:17 volumio volumio[961]: info: Permissions for /etc/hostapd/hostapd.conf Mar 05 07:26:17 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 05 07:26:17 volumio volumio[961]: info: Discovery: Restarting Advertising due to device name change Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 05 07:26:18 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:18 volumio volumio[961]: info: CoreCommandRouter::volumioGetState Mar 05 07:26:18 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:18 volumio volumio[961]: info: Starting Shairport Sync Mar 05 07:26:18 volumio volumio[961]: info: Starting Shairport Sync Mar 05 07:26:18 volumio sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 07:26:18 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:18 volumio volumio[961]: info: Starting Shairport Sync Mar 05 07:26:18 volumio sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 07:26:18 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:18 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 07:26:18 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:18 volumio systemd[1]: mpd.service: Succeeded. Mar 05 07:26:18 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 07:26:18 volumio firststart.sh[963]: Finalizing Mar 05 07:26:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 05 07:26:18 volumio systemd[1]: Starting Wireless Services... Mar 05 07:26:18 volumio systemd[1]: Starting Music Player Daemon... Mar 05 07:26:18 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 05 07:26:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 05 07:26:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 05 07:26:18 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:18 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:18 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:18 volumio volumio[961]: info: Shairport-Sync Started Mar 05 07:26:18 volumio volumio[961]: Error adding Membership: Error: addMembership EINVAL Mar 05 07:26:18 volumio volumio[961]: info: Shairport-Sync Started Mar 05 07:26:18 volumio volumio[961]: info: Shairport-Sync Started Mar 05 07:26:18 volumio sudo[1512]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 07:26:18 volumio sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:18 volumio sudo[1512]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:18 volumio mpd_monitor.sh[646]: mpd: no process found Mar 05 07:26:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 05 07:26:18 volumio systemd[1]: mpd.service: Succeeded. Mar 05 07:26:18 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 07:26:18 volumio systemd[1]: Starting Music Player Daemon... Mar 05 07:26:18 volumio volumio[961]: info: Volumio called home Mar 05 07:26:18 volumio sudo[1526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 07:26:18 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:18 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:18 volumio wireless.js[1511]: Cleaning previous... Mar 05 07:26:18 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 05 07:26:18 volumio volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 07:26:18 volumio volumio[961]: Error: read ECONNRESET Mar 05 07:26:18 volumio volumio[961]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 05 07:26:18 volumio volumio[961]: errno: -104, Mar 05 07:26:18 volumio volumio[961]: code: 'ECONNRESET', Mar 05 07:26:18 volumio volumio[961]: syscall: 'read' Mar 05 07:26:18 volumio volumio[961]: } Mar 05 07:26:18 volumio volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 07:26:19 volumio mpd[1528]: Mar 05 07:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 07:26:19 volumio systemd[1]: Started Music Player Daemon. Mar 05 07:26:19 volumio mpd_monitor.sh[646]: MPD restarted due to no mpc output. Mar 05 07:26:19 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio systemd[1]: hostapd.service: Succeeded. Mar 05 07:26:19 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 05 07:26:19 volumio sudo[1547]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 05 07:26:19 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio avahi-daemon[654]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 05 07:26:19 volumio avahi-daemon[654]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 05 07:26:19 volumio avahi-daemon[654]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 05 07:26:19 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio sudo[1549]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 05 07:26:19 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 07:25 Mar 05 07:26:19 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio wireless.js[1511]: Stopped aP Mar 05 07:26:19 volumio volumio[961]: Terminated Mar 05 07:26:19 volumio volumio[961]: child_process.js:655 Mar 05 07:26:19 volumio volumio[961]: throw err; Mar 05 07:26:19 volumio volumio[961]: ^ Mar 05 07:26:19 volumio volumio[961]: Error: Command failed: /usr/bin/sudo /bin/journalctl --since="2025-03-05 07:25" > /tmp/crashdump Mar 05 07:26:19 volumio volumio[961]: Terminated Mar 05 07:26:19 volumio volumio[961]: at checkExecSyncError (child_process.js:616:11) Mar 05 07:26:19 volumio volumio[961]: at execSync (child_process.js:652:15) Mar 05 07:26:19 volumio volumio[961]: at Object. (/volumio/crashreport.js:14:18) Mar 05 07:26:19 volumio volumio[961]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 05 07:26:19 volumio volumio[961]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 05 07:26:19 volumio volumio[961]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 05 07:26:19 volumio volumio[961]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 05 07:26:19 volumio volumio[961]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) Mar 05 07:26:19 volumio volumio[961]: at internal/main/run_main_module.js:17:47 { Mar 05 07:26:19 volumio volumio[961]: status: 143, Mar 05 07:26:19 volumio volumio[961]: signal: null, Mar 05 07:26:19 volumio volumio[961]: output: [ Mar 05 07:26:19 volumio volumio[961]: null, Mar 05 07:26:19 volumio volumio[961]: Buffer(0) [Uint8Array] [], Mar 05 07:26:19 volumio volumio[961]: Buffer(11) [Uint8Array] [ Mar 05 07:26:19 volumio volumio[961]: 84, 101, 114, 109, Mar 05 07:26:19 volumio volumio[961]: 105, 110, 97, 116, Mar 05 07:26:19 volumio volumio[961]: 101, 100, 10 Mar 05 07:26:19 volumio volumio[961]: ] Mar 05 07:26:19 volumio volumio[961]: ], Mar 05 07:26:19 volumio volumio[961]: pid: 1550, Mar 05 07:26:19 volumio volumio[961]: stdout: Buffer(0) [Uint8Array] [], Mar 05 07:26:19 volumio volumio[961]: stderr: Buffer(11) [Uint8Array] [ Mar 05 07:26:19 volumio volumio[961]: 84, 101, 114, 109, Mar 05 07:26:19 volumio volumio[961]: 105, 110, 97, 116, Mar 05 07:26:19 volumio volumio[961]: 101, 100, 10 Mar 05 07:26:19 volumio volumio[961]: ] Mar 05 07:26:19 volumio volumio[961]: } Mar 05 07:26:19 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 05 07:26:19 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio volumio[961]: child_process.js:655 Mar 05 07:26:19 volumio volumio[961]: throw err; Mar 05 07:26:19 volumio volumio[961]: ^ Mar 05 07:26:19 volumio volumio[961]: Error: Command failed: /usr/bin/node /volumio/crashreport.js "read ECONNRESET" Mar 05 07:26:19 volumio volumio[961]: Terminated Mar 05 07:26:19 volumio volumio[961]: child_process.js:655 Mar 05 07:26:19 volumio volumio[961]: throw err; Mar 05 07:26:19 volumio volumio[961]: ^ Mar 05 07:26:19 volumio volumio[961]: Error: Command failed: /usr/bin/sudo /bin/journalctl --since="2025-03-05 07:25" > /tmp/crashdump Mar 05 07:26:19 volumio volumio[961]: Terminated Mar 05 07:26:19 volumio volumio[961]: at checkExecSyncError (child_process.js:616:11) Mar 05 07:26:19 volumio volumio[961]: at execSync (child_process.js:652:15) Mar 05 07:26:19 volumio volumio[961]: at Object. (/volumio/crashreport.js:14:18) Mar 05 07:26:19 volumio volumio[961]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 05 07:26:19 volumio volumio[961]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 05 07:26:19 volumio volumio[961]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 05 07:26:19 volumio volumio[961]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 05 07:26:19 volumio volumio[961]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) Mar 05 07:26:19 volumio volumio[961]: at internal/main/run_main_module.js:17:47 { Mar 05 07:26:19 volumio volumio[961]: status: 143, Mar 05 07:26:19 volumio volumio[961]: signal: null, Mar 05 07:26:19 volumio volumio[961]: output: [ Mar 05 07:26:19 volumio volumio[961]: null, Mar 05 07:26:19 volumio volumio[961]: Buffer(0) [Uint8Array] [], Mar 05 07:26:19 volumio volumio[961]: Buffer(11) [Uint8Array] [ Mar 05 07:26:19 volumio volumio[961]: 84, 101, 114, 109, Mar 05 07:26:19 volumio volumio[961]: 105, 110, 97, 116, Mar 05 07:26:19 volumio volumio[961]: 101, 100, 10 Mar 05 07:26:19 volumio volumio[961]: ] Mar 05 07:26:19 volumio volumio[961]: ], Mar 05 07:26:19 volumio volumio[961]: pid: 1550, Mar 05 07:26:19 volumio volumio[961]: stdout: Buffer(0) [Uint8Array] [], Mar 05 07:26:19 volumio volumio[961]: stderr: Buffer(11) [Uint8Array] [ Mar 05 07:26:19 volumio volumio[961]: 84, 101, 114, 109, Mar 05 07:26:19 volumio volumio[961]: 105, 110, 97, 116, Mar 05 07:26:19 volumio volumio[961]: 101, 100, 10 Mar 05 07:26:19 volumio volumio[961]: ] Mar 05 07:26:19 volumio volumio[961]: } Mar 05 07:26:19 volumio volumio[961]: at checkExecSyncError (child_process.js:616:11) Mar 05 07:26:19 volumio volumio[961]: at execSync (child_process.js:652:15) Mar 05 07:26:19 volumio volumio[961]: at process. (/volumio/index.js:78:3) Mar 05 07:26:19 volumio volumio[961]: at process.emit (events.js:315:20) Mar 05 07:26:19 volumio volumio[961]: at process._fatalException (internal/process/execution.js:163:25) { Mar 05 07:26:19 volumio volumio[961]: status: 1, Mar 05 07:26:19 volumio volumio[961]: signal: null, Mar 05 07:26:19 volumio volumio[961]: output: [ Mar 05 07:26:19 volumio volumio[961]: null, Mar 05 07:26:19 volumio volumio[961]: Buffer(0) [Uint8Array] [], Mar 05 07:26:19 volumio volumio[961]: Buffer(1076) [Uint8Array] [ Mar 05 07:26:19 volumio volumio[961]: 84, 101, 114, 109, 105, 110, 97, 116, 101, 100, 10, 99, Mar 05 07:26:19 volumio volumio[961]: 104, 105, 108, 100, 95, 112, 114, 111, 99, 101, 115, 115, Mar 05 07:26:19 volumio volumio[961]: 46, 106, 115, 58, 54, 53, 53, 10, 32, 32, 32, 32, Mar 05 07:26:19 volumio volumio[961]: 116, 104, 114, 111, 119, 32, 101, 114, 114, 59, 10, 32, Mar 05 07:26:19 volumio volumio[961]: 32, 32, 32, 94, 10, 10, 69, 114, 114, 111, 114, 58, Mar 05 07:26:19 volumio volumio[961]: 32, 67, 111, 109, 109, 97, 110, 100, 32, 102, 97, 105, Mar 05 07:26:19 volumio volumio[961]: 108, 101, 100, 58, 32, 47, 117, 115, 114, 47, 98, 105, Mar 05 07:26:19 volumio volumio[961]: 110, 47, 115, 117, 100, 111, 32, 47, 98, 105, 110, 47, Mar 05 07:26:19 volumio volumio[961]: 106, 111, 117, 114, Mar 05 07:26:19 volumio volumio[961]: ... 976 more items Mar 05 07:26:19 volumio volumio[961]: ] Mar 05 07:26:19 volumio volumio[961]: ], Mar 05 07:26:19 volumio volumio[961]: pid: 1535, Mar 05 07:26:19 volumio volumio[961]: stdout: Buffer(0) [Uint8Array] [], Mar 05 07:26:19 volumio volumio[961]: stderr: Buffer(1076) [Uint8Array] [ Mar 05 07:26:19 volumio volumio[961]: 84, 101, 114, 109, 105, 110, 97, 116, 101, 100, 10, 99, Mar 05 07:26:19 volumio volumio[961]: 104, 105, 108, 100, 95, 112, 114, 111, 99, 101, 115, 115, Mar 05 07:26:19 volumio volumio[961]: 46, 106, 115, 58, 54, 53, 53, 10, 32, 32, 32, 32, Mar 05 07:26:19 volumio volumio[961]: 116, 104, 114, 111, 119, 32, 101, 114, 114, 59, 10, 32, Mar 05 07:26:19 volumio volumio[961]: 32, 32, 32, 94, 10, 10, 69, 114, 114, 111, 114, 58, Mar 05 07:26:19 volumio volumio[961]: 32, 67, 111, 109, 109, 97, 110, 100, 32, 102, 97, 105, Mar 05 07:26:19 volumio volumio[961]: 108, 101, 100, 58, 32, 47, 117, 115, 114, 47, 98, 105, Mar 05 07:26:19 volumio volumio[961]: 110, 47, 115, 117, 100, 111, 32, 47, 98, 105, 110, 47, Mar 05 07:26:19 volumio volumio[961]: 106, 111, 117, 114, Mar 05 07:26:19 volumio volumio[961]: ... 976 more items Mar 05 07:26:19 volumio volumio[961]: ] Mar 05 07:26:19 volumio volumio[961]: } Mar 05 07:26:19 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 05 07:26:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=7/NOTRUNNING Mar 05 07:26:19 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 05 07:26:19 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 05 07:26:19 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 05 07:26:19 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:19 volumio systemd[1]: Started dynamicswap service. Mar 05 07:26:19 volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 05 07:26:19 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:19 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 05 07:26:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 05 07:26:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 05 07:26:20 volumio systemd[1]: Started dynamicswap service. Mar 05 07:26:20 volumio systemd[1]: Stopped Volumio Backend Module. Mar 05 07:26:20 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 05 07:26:21 volumio ntpd[776]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=33 secs Mar 05 07:26:22 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 05 07:26:22 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:22 volumio wireless.js[1511]: SETTING APPROPRIATE REG DOMAIN: AU Mar 05 07:26:22 volumio sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 05 07:26:22 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:22 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:22 volumio sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set AU Mar 05 07:26:22 volumio sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:22 volumio sudo[1605]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:22 volumio wireless.js[1511]: SUCCESSFULLY SET NEW REGDOMAIN: AU Mar 05 07:26:22 volumio volumio-remote-updater[637]: [2025-03-05 07:26:22] [info] asio async_connect error: system:111 (Connection refused) Mar 05 07:26:22 volumio volumio-remote-updater[637]: [2025-03-05 07:26:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 05 07:26:22 volumio volumio-remote-updater[637]: [2025-03-05 07:26:22] [error] handle_connect error: Underlying Transport Error Mar 05 07:26:31 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 07:26:32 volumio volumio-remote-updater[637]: [2025-03-05 07:26:32] [info] asio async_connect error: system:111 (Connection refused) Mar 05 07:26:32 volumio volumio-remote-updater[637]: [2025-03-05 07:26:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 05 07:26:32 volumio volumio-remote-updater[637]: [2025-03-05 07:26:32] [error] handle_connect error: Underlying Transport Error Mar 05 07:26:32 volumio systemd[1]: mpd.service: Succeeded. Mar 05 07:26:32 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 07:26:32 volumio sudo[1617]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 05 07:26:32 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:32 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:32 volumio sudo[1619]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 05 07:26:32 volumio sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:32 volumio systemd[1]: Starting Music Player Daemon... Mar 05 07:26:33 volumio sudo[1619]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:33 volumio sudo[1616]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 07:26:33 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:33 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:33 volumio avahi-daemon[654]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 05 07:26:33 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 05 07:26:33 volumio avahi-daemon[654]: New relevant interface wlan0.IPv4 for mDNS. Mar 05 07:26:33 volumio avahi-daemon[654]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 05 07:26:33 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 05 07:26:33 volumio hostapd[1627]: Configuration file: /etc/hostapd/hostapd.conf Mar 05 07:26:33 volumio hostapd[1627]: wlan0: Could not connect to kernel driver Mar 05 07:26:33 volumio hostapd[1627]: Using interface wlan0 with hwaddr e4:5f:01:f7:b0:21 and ssid "Volumio-C76A9" Mar 05 07:26:33 volumio hostapd[1627]: wlan0: interface state UNINITIALIZED->ENABLED Mar 05 07:26:33 volumio hostapd[1627]: wlan0: AP-ENABLED Mar 05 07:26:33 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 05 07:26:33 volumio iwconfig[1671]: Error for wireless request "Set Power Management" (8B2C) : Mar 05 07:26:33 volumio iwconfig[1671]: SET failed on device wlan0 ; Invalid argument. Mar 05 07:26:33 volumio systemd[1]: wireless.service: Succeeded. Mar 05 07:26:33 volumio systemd[1]: Started Wireless Services. Mar 05 07:26:33 volumio systemd[1]: Started Volumio Backend Module. Mar 05 07:26:34 volumio mpd[1624]: Mar 05 07:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 07:26:34 volumio systemd[1]: Started Music Player Daemon. Mar 05 07:26:34 volumio mpd_monitor.sh[646]: MPD restarted due to no mpc output. Mar 05 07:26:34 volumio volumio[1672]: info: ------------------------------------------- Mar 05 07:26:34 volumio volumio[1672]: info: ----- Volumio3 ---- Mar 05 07:26:34 volumio volumio[1672]: info: ------------------------------------------- Mar 05 07:26:34 volumio volumio[1672]: info: ----- System startup ---- Mar 05 07:26:34 volumio volumio[1672]: info: ------------------------------------------- Mar 05 07:26:35 volumio ntpd[776]: Listen normally on 5 wlan0 192.168.211.1:123 Mar 05 07:26:35 volumio ntpd[776]: new interface(s) found: waking up resolver Mar 05 07:26:35 volumio volumio[1672]: info: MYVOLUMIO Environment detected Mar 05 07:26:35 volumio volumio[1672]: info: Plugin folders cleanup Mar 05 07:26:35 volumio volumio[1672]: info: Scanning into folder /volumio/app/plugins/ Mar 05 07:26:35 volumio volumio[1672]: info: Scanning category audio_interface Mar 05 07:26:35 volumio volumio[1672]: info: Scanning category miscellanea Mar 05 07:26:35 volumio volumio[1672]: info: Scanning category music_service Mar 05 07:26:35 volumio volumio[1672]: info: Scanning category plugins.json Mar 05 07:26:35 volumio volumio[1672]: info: Scanning category system_controller Mar 05 07:26:35 volumio volumio[1672]: info: Scanning category user_interface Mar 05 07:26:35 volumio volumio[1672]: info: Scanning into folder /data/plugins/ Mar 05 07:26:35 volumio volumio[1672]: info: Plugin folders cleanup completed Mar 05 07:26:35 volumio volumio[1672]: info: ------------------------------------------- Mar 05 07:26:35 volumio volumio[1672]: info: ----- Core plugins startup ---- Mar 05 07:26:35 volumio volumio[1672]: info: ------------------------------------------- Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugins from folder /volumio/app/plugins/ Mar 05 07:26:35 volumio volumio[1672]: info: Adding plugin upnp to MyMusic Plugins Mar 05 07:26:35 volumio volumio[1672]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 05 07:26:35 volumio volumio[1672]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugins from folder /data/plugins/ Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugin "system"... Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugin "appearance"... Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugin "network"... Mar 05 07:26:35 volumio volumio[1672]: info: Refreshing Cached IP Addresses Mar 05 07:26:35 volumio sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 05 07:26:35 volumio sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:35 volumio sudo[1696]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:35 volumio sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 05 07:26:35 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:35 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugin "services"... Mar 05 07:26:35 volumio volumio[1672]: info: Loading plugin "alsa_controller"... Mar 05 07:26:35 volumio sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 05 07:26:35 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:36 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "wizard"... Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "networkfs"... Mar 05 07:26:36 volumio volumio[1672]: info: Starting Udev Watcher for removable devices Mar 05 07:26:36 volumio volumio[1672]: info: Ignoring mount for partition: boot Mar 05 07:26:36 volumio volumio[1672]: info: Ignoring mount for partition: volumio Mar 05 07:26:36 volumio volumio[1672]: info: Ignoring mount for partition: volumio_data Mar 05 07:26:36 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "volumio_command_line_client"... Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "upnp"... Mar 05 07:26:36 volumio volumio[1672]: info: [1741159596701] Starting Upmpd Daemon Mar 05 07:26:36 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "my_music"... Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "mpd"... Mar 05 07:26:36 volumio volumio[1672]: info: Loading plugin "upnp_browser"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "alarm-clock"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "airplay_emulation"... Mar 05 07:26:37 volumio volumio[1672]: info: Starting Shairport Sync Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "last_100"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "webradio"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "i2s_dacs"... Mar 05 07:26:37 volumio volumio[1672]: info: I2S DAC not set, start Auto-detection Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "volumiodiscovery"... Mar 05 07:26:37 volumio volumio[1672]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:37 volumio volumio[1672]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:37 volumio volumio[1672]: *** WARNING *** For more information see Mar 05 07:26:37 volumio node[1672]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:37 volumio volumio[1672]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:37 volumio volumio[1672]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:37 volumio volumio[1672]: *** WARNING *** For more information see Mar 05 07:26:37 volumio node[1672]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:37 volumio node[1672]: *** WARNING *** For more information see Mar 05 07:26:37 volumio node[1672]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 05 07:26:37 volumio node[1672]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 05 07:26:37 volumio node[1672]: *** WARNING *** For more information see Mar 05 07:26:37 volumio volumio[1672]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 05 07:26:37 volumio volumio[1672]: info: Discovery: Started advertising with name: Volumio Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "outputs"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "albumart"... Mar 05 07:26:37 volumio systemd[1]: firststart.service: Succeeded. Mar 05 07:26:37 volumio volumio[1672]: info: Plugin example_plugin is not enabled Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "inputs"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "updater_comm"... Mar 05 07:26:37 volumio volumio[1672]: info: Plugin mpdemulation is not enabled Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "rest_api"... Mar 05 07:26:37 volumio volumio[1672]: info: Loading plugin "websocket"... Mar 05 07:26:37 volumio volumio[1672]: info: Starting Socket.io Server version 2.3.0 Mar 05 07:26:37 volumio volumio[1672]: info: Loading i18n strings for locale en Mar 05 07:26:37 volumio volumio[1672]: Updating browse sources language Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:37 volumio volumio[1672]: Forking 3 albumart workers Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::initPlayerControls Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: Express server listening on port 3000 Mar 05 07:26:37 volumio volumio[1672]: [Metrics] WebUI: 3s 361.50ms Mar 05 07:26:37 volumio volumio[1672]: info: CoreStateMachine::resetVolumioState Mar 05 07:26:37 volumio volumio[1672]: info: CoreStateMachine::getcurrentVolume Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 07:26:37 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:37 volumio volumio[1672]: info: Cannot read play queue from file Mar 05 07:26:37 volumio volumio[1672]: info: Volumio Network Manager: Network status updated: 1 Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::setRepeat null single undefined Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::pushState Mar 05 07:26:38 volumio volumio[1672]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::setRandom null Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::pushState Mar 05 07:26:38 volumio volumio[1672]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:38 volumio volumio[1672]: info: Setting Device type: Raspberry PI Mar 05 07:26:38 volumio volumio[1672]: info: VolumeController:: Volume=86 Mute =false Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::pushState Mar 05 07:26:38 volumio volumio[1672]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::updateTrackBlock Mar 05 07:26:38 volumio volumio[1672]: info: CorePlayQueue::getTrackBlock Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioRetrievevolume Mar 05 07:26:38 volumio volumio[1672]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 05 07:26:38 volumio volumio[1672]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Mar 05 07:26:38 volumio volumio[1672]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 05 07:26:38 volumio volumio[1672]: info: Completed loading Core Plugins Mar 05 07:26:38 volumio volumio[1672]: info: Preparing to generate the ALSA configuration file Mar 05 07:26:38 volumio volumio[1672]: info: Asound.conf file unchanged, so no further update is needed Mar 05 07:26:38 volumio volumio[1672]: info: Output device has changed, restarting MPD Mar 05 07:26:38 volumio volumio[1672]: info: Output device has changed, restarting Shairport Sync Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:38 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 07:26:38 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:38 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:38 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 07:26:38 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:38 volumio volumio[1672]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 07:26:38 volumio volumio[1672]: info: ___________ START PLUGINS ___________ Mar 05 07:26:38 volumio volumio[1672]: info: ControllerMpd::onStart: Initializing MPD Mar 05 07:26:38 volumio volumio[1672]: info: Creating MPD Configuration file Mar 05 07:26:38 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 07:26:38 volumio systemd[1]: mpd.service: Succeeded. Mar 05 07:26:38 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 05 07:26:38 volumio volumio[1672]: info: [1741159598407] CoreMusicLibrary::Adding element Media Servers Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:38 volumio sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 07:26:38 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:38 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:38 volumio volumio[1672]: Starting albumart workers Mar 05 07:26:38 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:38 volumio systemd[1]: Starting Music Player Daemon... Mar 05 07:26:38 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 05 07:26:38 volumio systemd[1]: mpd.service: Succeeded. Mar 05 07:26:38 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 07:26:38 volumio systemd[1]: Starting Music Player Daemon... Mar 05 07:26:38 volumio volumio[1672]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: Starting albumart workers Mar 05 07:26:38 volumio volumio[1672]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 05 07:26:38 volumio volumio[1672]: info: [1741159598563] CoreMusicLibrary::Adding element Last_100 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 05 07:26:38 volumio volumio[1672]: info: [1741159598566] CoreMusicLibrary::Adding element Webradio Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 07:26:38 volumio sudo[1785]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 07:26:38 volumio sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 07:26:38 volumio volumio[1672]: info: Initializing BBC Radios Mar 05 07:26:38 volumio sudo[1785]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:38 volumio volumio[1672]: Starting albumart workers Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: Volumio Calling Home Mar 05 07:26:38 volumio volumio[1672]: info: MPD Permissions set Mar 05 07:26:38 volumio volumio[1672]: info: MPD Permissions set Mar 05 07:26:38 volumio volumio[1672]: info: VolumeController:: Volume=86 Mute =false Mar 05 07:26:38 volumio volumio[1672]: info: CoreStateMachine::pushState Mar 05 07:26:38 volumio volumio[1672]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::volumioPushState Mar 05 07:26:38 volumio volumio[1672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:38 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 07:26:39 volumio volumio[1672]: info: Discovery: adding b63b1697-2f55-4c49-9f80-0d914f11192a Mar 05 07:26:39 volumio volumio[1672]: info: Discovery: Found device Volumio Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::volumioGetState Mar 05 07:26:39 volumio volumio[1672]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:39 volumio volumio[1672]: info: Discovery: this is already registered, b63b1697-2f55-4c49-9f80-0d914f11192a Mar 05 07:26:39 volumio volumio[1672]: info: Discovery: Found device Volumio Mar 05 07:26:39 volumio volumio[1672]: info: CoreCommandRouter::volumioGetState Mar 05 07:26:39 volumio volumio[1672]: info: CorePlayQueue::getTrack 0 Mar 05 07:26:39 volumio volumio[1672]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 07:26:39 volumio volumio[1672]: Error: read ECONNRESET Mar 05 07:26:39 volumio volumio[1672]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 05 07:26:39 volumio volumio[1672]: errno: -104, Mar 05 07:26:39 volumio volumio[1672]: code: 'ECONNRESET', Mar 05 07:26:39 volumio volumio[1672]: syscall: 'read' Mar 05 07:26:39 volumio volumio[1672]: } Mar 05 07:26:39 volumio volumio[1672]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 07:26:39 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root Mar 05 07:26:39 volumio sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 07:25 Mar 05 07:26:39 volumio sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"