-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sun 2025-04-27 20:27:25 BST. -- Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:08 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:08 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:08 stormforce volumio[1072]: verbose: New Socket.io Connection to 192.168.1.145:3000 from 192.168.1.20 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:08 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 27 20:26:08 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 27 20:26:11 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:11 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:12 stormforce sudo[3450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 20:26:12 stormforce sudo[3450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:12 stormforce sudo[3450]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:12 stormforce sudo[3452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 20:26:12 stormforce sudo[3452]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:12 stormforce sudo[3452]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:13 stormforce volumio[1072]: verbose: New Socket.io Connection to 192.168.1.94 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 27 20:26:13 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 20:26:13 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:13 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:13 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:13 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:13 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:13 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:13 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 27 20:26:14 stormforce sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 20:26:14 stormforce sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:14 stormforce sudo[3459]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:14 stormforce sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 20:26:14 stormforce sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:14 stormforce sudo[3461]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:14 stormforce volumio[1072]: verbose: New Socket.io Connection to 192.168.1.94 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:14 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetQueue Apr 27 20:26:14 stormforce volumio[1072]: info: CoreStateMachine::getQueue Apr 27 20:26:14 stormforce volumio[1072]: info: CorePlayQueue::getQueue Apr 27 20:26:14 stormforce volumio[1072]: info: Listing playlists Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 20:26:14 stormforce volumio[1072]: info: Received Get System Info Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:14 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:14 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:14 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 20:26:14 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 27 20:26:16 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 20:26:16 stormforce volumio[1072]: info: Received Get System Info Apr 27 20:26:16 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:16 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:16 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:16 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:16 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:16 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:16 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 27 20:26:19 stormforce volumio[1072]: info: CoreCommandRouter::volumioPlay Apr 27 20:26:19 stormforce volumio[1072]: info: CoreStateMachine::play index undefined Apr 27 20:26:19 stormforce volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 20:26:19 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:19 stormforce volumio[1072]: info: CoreStateMachine::startPlaybackTimer Apr 27 20:26:19 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:19 stormforce volumio[1072]: info: [1745781979038] ControllerUPNPBrowser::resume Apr 27 20:26:19 stormforce volumio[1072]: verbose: ControllerMpd::sendMpdCommand play Apr 27 20:26:19 stormforce volumio[1072]: info: sendMpdCommand play took 4 milliseconds Apr 27 20:26:24 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 20:26:32 stormforce volumio[1072]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 27 20:26:32 stormforce volumio[1072]: info: Received Get System Version Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 20:26:32 stormforce volumio[1072]: info: Received Get System Info Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:32 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:32 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:32 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:34 stormforce kernel: usb 1-1.5: USB disconnect, device number 8 Apr 27 20:26:34 stormforce volumio[1072]: info: Apr 27 20:26:34 stormforce volumio[1072]: ---------------------------- USB Audio Device Detached Apr 27 20:26:34 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 27 20:26:34 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 20:26:34 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 27 20:26:35 stormforce kernel: usb 1-1.5: new high-speed USB device number 9 using dwc_otg Apr 27 20:26:35 stormforce kernel: usb 1-1.5: New USB device found, idVendor=1781, idProduct=0e4f, bcdDevice= 3.30 Apr 27 20:26:36 stormforce kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 27 20:26:36 stormforce kernel: usb 1-1.5: Product: Benchmark DAC2 USB Audio 2.0 Apr 27 20:26:36 stormforce kernel: usb 1-1.5: Manufacturer: Benchmark DAC2 Apr 27 20:26:36 stormforce kernel: usb 1-1.5: SerialNumber: 0000 Apr 27 20:26:36 stormforce systemd-udevd[3499]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Apr 27 20:26:36 stormforce volumio[1072]: info: Apr 27 20:26:36 stormforce volumio[1072]: ---------------------------- USB Audio Device Attached Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::Close All Modals sent Apr 27 20:26:36 stormforce volumio[1072]: info: Preparing to save Alsa Options, stopping services first Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:36 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::volumioPause Apr 27 20:26:36 stormforce volumio[1072]: info: CoreStateMachine::pause Apr 27 20:26:36 stormforce volumio[1072]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Benchmark DAC2 USB Audio 2.0","alsacard":"B20"},"i2s":false} Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 20:26:36 stormforce volumio[1072]: info: Setting mixer Benchmark DAC2 Audio 2.0 Output Playback Sw for card Benchmark DAC2 USB Audio 2.0 Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 27 20:26:36 stormforce volumio[1072]: info: Updating Volume Controller Parameters: Device: 5 Name: Benchmark DAC2 USB Audio 2.0 Mixer: Benchmark DAC2 Audio 2.0 Output Playback Sw Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 27 20:26:36 stormforce volumio[1072]: info: Disabling external Volume Control Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 20:26:36 stormforce volumio[1072]: info: Preparing to generate the ALSA configuration file Apr 27 20:26:36 stormforce volumio[1072]: Unable to parse: Apr 27 20:26:36 stormforce volumio[1072]: Simple mixer control 'Benchmark DAC2 Audio 2.0 Output Playback Sw',0 Apr 27 20:26:36 stormforce volumio[1072]: Capabilities: pswitch Apr 27 20:26:36 stormforce volumio[1072]: Playback channels: Front Left - Front Right Apr 27 20:26:36 stormforce volumio[1072]: Mono: Apr 27 20:26:36 stormforce volumio[1072]: Front Left: Playback [on] Apr 27 20:26:36 stormforce volumio[1072]: Front Right: Playback [on] Apr 27 20:26:36 stormforce volumio[1072]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 27 20:26:36 stormforce volumio[1072]: info: Asound.conf file unchanged, so no further update is needed Apr 27 20:26:36 stormforce volumio[1072]: info: Output device has changed, restarting MPD Apr 27 20:26:36 stormforce volumio[1072]: info: Output device has changed, restarting Shairport Sync Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 20:26:36 stormforce sudo[3534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 20:26:36 stormforce sudo[3534]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:36 stormforce sudo[3534]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:36 stormforce sudo[3536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 20:26:36 stormforce sudo[3536]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:36 stormforce systemd[1]: Stopping Music Player Daemon... Apr 27 20:26:36 stormforce volumio[1072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 20:26:36 stormforce systemd[1]: mpd.service: Succeeded. Apr 27 20:26:36 stormforce systemd[1]: Stopped Music Player Daemon. Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 20:26:36 stormforce systemd[1]: Starting Music Player Daemon... Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 20:26:36 stormforce volumio[1072]: info: MPD Permissions set Apr 27 20:26:36 stormforce volumio[1072]: Unable to parse: Apr 27 20:26:36 stormforce volumio[1072]: Simple mixer control 'Benchmark DAC2 Audio 2.0 Output Playback Sw',0 Apr 27 20:26:36 stormforce volumio[1072]: Capabilities: pswitch Apr 27 20:26:36 stormforce volumio[1072]: Playback channels: Front Left - Front Right Apr 27 20:26:36 stormforce volumio[1072]: Mono: Apr 27 20:26:36 stormforce volumio[1072]: Front Left: Playback [on] Apr 27 20:26:36 stormforce volumio[1072]: Front Right: Playback [on] Apr 27 20:26:36 stormforce volumio[1072]: info: VolumeController:: Volume=undefined Mute =false Apr 27 20:26:36 stormforce volumio[1072]: info: CoreStateMachine::pushState Apr 27 20:26:36 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::volumioPushState Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetQueue Apr 27 20:26:36 stormforce volumio[1072]: info: CoreStateMachine::getQueue Apr 27 20:26:36 stormforce volumio[1072]: info: CorePlayQueue::getQueue Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 20:26:36 stormforce volumio[1072]: info: Starting Shairport Sync Apr 27 20:26:36 stormforce sudo[3542]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 27 20:26:36 stormforce sudo[3542]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:36 stormforce sudo[3542]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:36 stormforce sudo[3552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 20:26:36 stormforce sudo[3552]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:37 stormforce systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 20:26:37 stormforce systemd[1]: shairport-sync.service: Succeeded. Apr 27 20:26:37 stormforce systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 20:26:37 stormforce systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 20:26:37 stormforce sudo[3552]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:37 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 27 20:26:37 stormforce volumio[1072]: info: Shairport-Sync Started Apr 27 20:26:38 stormforce mpd[3554]: Apr 27 20:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 20:26:38 stormforce systemd[1]: Started Music Player Daemon. Apr 27 20:26:38 stormforce sudo[3536]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:38 stormforce volumio[1072]: error: updateQueue error: null Apr 27 20:26:38 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:38 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:38 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:38 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:38 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:38 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 27 20:26:40 stormforce volumio[1072]: info: CoreStateMachine::getcurrentVolume Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 20:26:40 stormforce volumio[1072]: Unable to parse: Apr 27 20:26:40 stormforce volumio[1072]: Simple mixer control 'Benchmark DAC2 Audio 2.0 Output Playback Sw',0 Apr 27 20:26:40 stormforce volumio[1072]: Capabilities: pswitch Apr 27 20:26:40 stormforce volumio[1072]: Playback channels: Front Left - Front Right Apr 27 20:26:40 stormforce volumio[1072]: Mono: Apr 27 20:26:40 stormforce volumio[1072]: Front Left: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: Front Right: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 27 20:26:40 stormforce volumio[1072]: Unable to parse: Apr 27 20:26:40 stormforce volumio[1072]: Simple mixer control 'Benchmark DAC2 Audio 2.0 Output Playback Sw',0 Apr 27 20:26:40 stormforce volumio[1072]: Capabilities: pswitch Apr 27 20:26:40 stormforce volumio[1072]: Playback channels: Front Left - Front Right Apr 27 20:26:40 stormforce volumio[1072]: Mono: Apr 27 20:26:40 stormforce volumio[1072]: Front Left: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: Front Right: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: info: VolumeController:: Volume=undefined Mute =false Apr 27 20:26:40 stormforce volumio[1072]: info: CoreStateMachine::pushState Apr 27 20:26:40 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioPushState Apr 27 20:26:40 stormforce volumio[1072]: info: CoreStateMachine::updateTrackBlock Apr 27 20:26:40 stormforce volumio[1072]: info: CorePlayQueue::getTrackBlock Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetQueue Apr 27 20:26:40 stormforce volumio[1072]: info: CoreStateMachine::getQueue Apr 27 20:26:40 stormforce volumio[1072]: info: CorePlayQueue::getQueue Apr 27 20:26:40 stormforce volumio[1072]: Unable to parse: Apr 27 20:26:40 stormforce volumio[1072]: Simple mixer control 'Benchmark DAC2 Audio 2.0 Output Playback Sw',0 Apr 27 20:26:40 stormforce volumio[1072]: Capabilities: pswitch Apr 27 20:26:40 stormforce volumio[1072]: Playback channels: Front Left - Front Right Apr 27 20:26:40 stormforce volumio[1072]: Mono: Apr 27 20:26:40 stormforce volumio[1072]: Front Left: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: Front Right: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 27 20:26:40 stormforce volumio[1072]: Unable to parse: Apr 27 20:26:40 stormforce volumio[1072]: Simple mixer control 'Benchmark DAC2 Audio 2.0 Output Playback Sw',0 Apr 27 20:26:40 stormforce volumio[1072]: Capabilities: pswitch Apr 27 20:26:40 stormforce volumio[1072]: Playback channels: Front Left - Front Right Apr 27 20:26:40 stormforce volumio[1072]: Mono: Apr 27 20:26:40 stormforce volumio[1072]: Front Left: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: Front Right: Playback [on] Apr 27 20:26:40 stormforce volumio[1072]: info: VolumeController:: Volume=undefined Mute =false Apr 27 20:26:40 stormforce volumio[1072]: info: CoreStateMachine::pushState Apr 27 20:26:40 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioPushState Apr 27 20:26:40 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetQueue Apr 27 20:26:40 stormforce volumio[1072]: info: CoreStateMachine::getQueue Apr 27 20:26:40 stormforce volumio[1072]: info: CorePlayQueue::getQueue Apr 27 20:26:48 stormforce volumio[1072]: info: CoreCommandRouter::volumioPlay Apr 27 20:26:48 stormforce volumio[1072]: info: CoreStateMachine::play index undefined Apr 27 20:26:48 stormforce volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 20:26:48 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:48 stormforce volumio[1072]: info: CoreStateMachine::startPlaybackTimer Apr 27 20:26:48 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:48 stormforce volumio[1072]: info: [1745782008659] ControllerUPNPBrowser::resume Apr 27 20:26:48 stormforce volumio[1072]: verbose: ControllerMpd::sendMpdCommand play Apr 27 20:26:48 stormforce volumio[1072]: info: sendMpdCommand play took 1 milliseconds Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:52 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:52 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:52 stormforce volumio[1072]: verbose: New Socket.io Connection to 192.168.1.145:3000 from 192.168.1.20 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:52 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 27 20:26:52 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 27 20:26:55 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:55 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:56 stormforce sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 20:26:56 stormforce sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:56 stormforce sudo[3599]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:56 stormforce sudo[3601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 20:26:56 stormforce sudo[3601]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:56 stormforce sudo[3601]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:56 stormforce volumio[1072]: verbose: New Socket.io Connection to 192.168.1.94 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 27 20:26:57 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:57 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:57 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:57 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:57 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:57 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:57 stormforce sudo[3605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 20:26:57 stormforce sudo[3605]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:57 stormforce sudo[3605]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:57 stormforce sudo[3607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 20:26:57 stormforce sudo[3607]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 20:26:57 stormforce sudo[3607]: pam_unix(sudo:session): session closed for user root Apr 27 20:26:58 stormforce volumio[1072]: verbose: New Socket.io Connection to 192.168.1.94 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:58 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetQueue Apr 27 20:26:58 stormforce volumio[1072]: info: CoreStateMachine::getQueue Apr 27 20:26:58 stormforce volumio[1072]: info: CorePlayQueue::getQueue Apr 27 20:26:58 stormforce volumio[1072]: info: Listing playlists Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 20:26:58 stormforce volumio[1072]: info: Received Get System Info Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:58 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:58 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:58 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 20:26:58 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 27 20:26:59 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 20:26:59 stormforce volumio[1072]: info: Received Get System Info Apr 27 20:26:59 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:26:59 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:26:59 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:26:59 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:26:59 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:26:59 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:27:00 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 27 20:27:08 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 20:27:14 stormforce volumio[1072]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/David%20Gates/1a8bde25-286b-428f-9858-5991ca5ad402.jpg' Apr 27 20:27:14 stormforce volumio[1072]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Declan%20O%27Rourke/5c0be354-f1f8-46b3-b271-5cb72060bb9f.jpg' Apr 27 20:27:17 stormforce volumio[1072]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gary%20Boyle/49e816cc-a600-42a5-bef6-6ea3919b2a34.jpg' Apr 27 20:27:18 stormforce volumio[1072]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Geoff%20Achison/c3cb6fd0-8bef-4176-9edc-45c695fc3a97.jpg' Apr 27 20:27:20 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 20:27:22 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:27:22 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:27:22 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:27:22 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:27:22 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:27:22 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:27:23 stormforce volumio[1072]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 27 20:27:23 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 27 20:27:23 stormforce volumio[1072]: info: Received Get System Version Apr 27 20:27:23 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 20:27:23 stormforce volumio[1072]: info: Received Get System Info Apr 27 20:27:23 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 20:27:23 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 20:27:23 stormforce volumio[1072]: info: Discovery: Getting this device information Apr 27 20:27:23 stormforce volumio[1072]: info: CoreCommandRouter::volumioGetState Apr 27 20:27:23 stormforce volumio[1072]: info: CorePlayQueue::getTrack 0 Apr 27 20:27:23 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 20:27:24 stormforce volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 20:27:25 stormforce volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 20:27:25 stormforce volumio[1072]: TypeError: Cannot read property 'length' of undefined Apr 27 20:27:25 stormforce volumio[1072]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 27 20:27:25 stormforce volumio[1072]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 27 20:27:25 stormforce volumio[1072]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 27 20:27:25 stormforce volumio[1072]: at Parser.emit (events.js:315:20) Apr 27 20:27:25 stormforce volumio[1072]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 27 20:27:25 stormforce volumio[1072]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 27 20:27:25 stormforce volumio[1072]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 27 20:27:25 stormforce volumio[1072]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 27 20:27:25 stormforce volumio[1072]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 27 20:27:25 stormforce volumio[1072]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 27 20:27:25 stormforce volumio[1072]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 27 20:27:25 stormforce volumio[1072]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 27 20:27:25 stormforce volumio[1072]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 27 20:27:25 stormforce volumio[1072]: at IncomingMessage.emit (events.js:327:22) Apr 27 20:27:25 stormforce volumio[1072]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 20:27:25 stormforce volumio[1072]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 20:27:25 stormforce volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 20:27:25 stormforce sudo[3705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-27 20:26 Apr 27 20:27:25 stormforce sudo[3705]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"