-- Logs begin at Thu 2025-01-16 19:24:37 UTC, end at Sun 2025-01-19 10:26:49 UTC. -- Jan 19 10:26:40 dark systemd[1]: Time has been changed Jan 19 10:26:41 dark volumio[1057]: info: ___________ START PLUGINS ___________ Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 10:26:41 dark volumio[1057]: info: [1737282401333] CoreMusicLibrary::Adding element Media Servers Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 10:26:41 dark volumio[1057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 10:26:41 dark volumio[1057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 10:26:41 dark volumio[1057]: info: [1737282401494] CoreMusicLibrary::Adding element Last_100 Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 10:26:41 dark volumio[1057]: info: [1737282401500] CoreMusicLibrary::Adding element Webradio Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 10:26:41 dark volumio[1057]: info: ------------------------------------------- Jan 19 10:26:41 dark volumio[1057]: info: ----- MyVolumio plugins startup ---- Jan 19 10:26:41 dark volumio[1057]: info: ------------------------------------------- Jan 19 10:26:41 dark volumio[1057]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 19 10:26:41 dark volumio[1057]: info: Loading i18n strings for locale en Jan 19 10:26:41 dark volumio[1057]: Updating browse sources language Jan 19 10:26:41 dark volumio[1057]: Cannot find translation for sourceMedia Servers Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::initPlayerControls Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 10:26:41 dark volumio[1057]: info: BOOT COMPLETED Jan 19 10:26:41 dark volumio[1057]: [Metrics] CommandRouter: 8s 147.94ms Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 10:26:41 dark volumio[1057]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Jan 19 10:26:41 dark volumio[1057]: aplay: main:722: audio open error: No such file or directory Jan 19 10:26:41 dark volumio[1057]: Cannot play startup sound Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::Close All Modals sent Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::Close All Modals sent Jan 19 10:26:41 dark volumio[1057]: Express server listening on port 3000 Jan 19 10:26:41 dark volumio[1057]: [Metrics] WebUI: 9s 0.14ms Jan 19 10:26:41 dark volumio[1057]: Starting albumart workers Jan 19 10:26:41 dark volumio[1057]: Volumio Calling Home Jan 19 10:26:41 dark volumio[1057]: Starting albumart workers Jan 19 10:26:41 dark volumio[1057]: info: CoreStateMachine::resetVolumioState Jan 19 10:26:41 dark volumio[1057]: info: CoreStateMachine::getcurrentVolume Jan 19 10:26:41 dark volumio[1057]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 10:26:42 dark volumio[1057]: Starting albumart workers Jan 19 10:26:42 dark volumio[1057]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 19 10:26:42 dark volumio[1057]: info: Setting Device type: Raspberry PI Jan 19 10:26:42 dark volumio[1057]: info: MPD running with PID700 Jan 19 10:26:42 dark volumio[1057]: ,establishing connection Jan 19 10:26:42 dark volumio[1057]: info: VolumeController:: Volume=undefined Mute =false Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::pushState Jan 19 10:26:42 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::updateTrackBlock Jan 19 10:26:42 dark volumio[1057]: info: CorePlayQueue::getTrackBlock Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 10:26:42 dark volumio[1057]: info: Jan 19 10:26:42 dark volumio[1057]: ---------------------------- USB Audio Device Detached Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 19 10:26:42 dark volumio[1057]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 19 10:26:42 dark volumio[1057]: info: Volumio called home Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 19 10:26:42 dark volumio[1057]: info: Reloading queue from file Jan 19 10:26:42 dark volumio[1057]: info: VolumeController:: Volume=undefined Mute =false Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::pushState Jan 19 10:26:42 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::setRepeat null single undefined Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::pushState Jan 19 10:26:42 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::setRandom null Jan 19 10:26:42 dark volumio[1057]: info: CoreStateMachine::pushState Jan 19 10:26:42 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 19 10:26:42 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 10:26:43 dark volumio[1057]: info: mDNS: A device disapperared from network Jan 19 10:26:43 dark volumio[1057]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 19 10:26:43 dark volumio[1057]: info: Starting Shairport Sync Jan 19 10:26:43 dark volumio[1057]: info: Starting Shairport Sync Jan 19 10:26:43 dark sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 10:26:43 dark sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 10:26:43 dark sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 10:26:43 dark sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 10:26:43 dark systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jan 19 10:26:43 dark systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 10:26:43 dark sudo[1188]: pam_unix(sudo:session): session closed for user root Jan 19 10:26:43 dark systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 10:26:43 dark systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jan 19 10:26:43 dark systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 10:26:43 dark sudo[1190]: pam_unix(sudo:session): session closed for user root Jan 19 10:26:43 dark volumio[1057]: Discovery: adding 04d197ad-f1ea-4f0d-8ba6-191f8d012f3b Jan 19 10:26:43 dark volumio[1057]: info: mDNS: Found device dark Jan 19 10:26:43 dark volumio[1057]: info: CoreCommandRouter::volumioGetState Jan 19 10:26:43 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:43 dark volumio[1057]: info: Shairport-Sync Started Jan 19 10:26:43 dark volumio[1057]: Error adding Membership: Error: addMembership EINVAL Jan 19 10:26:43 dark volumio[1057]: info: Shairport-Sync Started Jan 19 10:26:43 dark volumio[1057]: Discovery: this is already registered, 04d197ad-f1ea-4f0d-8ba6-191f8d012f3b Jan 19 10:26:43 dark volumio[1057]: info: mDNS: Found device dark Jan 19 10:26:43 dark volumio[1057]: info: CoreCommandRouter::volumioGetState Jan 19 10:26:43 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:43 dark volumio[1057]: info: Jan 19 10:26:43 dark volumio[1057]: ---------------------------- Client requests Volume 40 Jan 19 10:26:43 dark volumio[1057]: info: VolumeController::SetAlsaVolume40 Jan 19 10:26:43 dark volumio[1057]: info: CoreStateMachine::pushState Jan 19 10:26:43 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:43 dark volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 19 10:26:43 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 10:26:43 dark volumio[1057]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 19 10:26:43 dark volumio[1057]: info: Setting volume on startup at 40 Jan 19 10:26:44 dark volumio[1057]: info: CoreCommandRouter::volumioGetState Jan 19 10:26:44 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:45 dark systemd[1]: Started Music Player Daemon. Jan 19 10:26:45 dark systemd[1]: Starting Multi-User System. Jan 19 10:26:45 dark systemd[1]: Reached target Multi-User System. Jan 19 10:26:45 dark systemd[1]: Starting Graphical Interface. Jan 19 10:26:45 dark systemd[1]: Reached target Graphical Interface. Jan 19 10:26:45 dark systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 19 10:26:45 dark systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 19 10:26:45 dark systemd[1]: Startup finished in 9.586s (kernel) + 28.390s (userspace) = 37.977s. Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 10:26:45 dark udisks-glue[631]: Updating DB (#1) ... Jan 19 10:26:45 dark udisks-glue[631]: volume: 40% repeat: off random: off single: off consume: off Jan 19 10:26:45 dark kernel: usb 1-1.2: new full-speed USB device number 6 using dwc_otg Jan 19 10:26:45 dark kernel: usb 1-1.2: config 1 has an invalid interface number: 3 but max is 2 Jan 19 10:26:45 dark kernel: usb 1-1.2: config 1 has no interface number 2 Jan 19 10:26:45 dark kernel: usb 1-1.2: New USB device found, idVendor=041e, idProduct=3273, bcdDevice= 1.00 Jan 19 10:26:45 dark kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 19 10:26:45 dark kernel: usb 1-1.2: Product: Stage V2 Jan 19 10:26:45 dark kernel: usb 1-1.2: Manufacturer: ACTIONS Jan 19 10:26:45 dark kernel: usb 1-1.2: SerialNumber: ㉕捤稰眷㕳愳㤷湲 Jan 19 10:26:45 dark kernel: input: ACTIONS Stage V2 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:041E:3273.0002/input/input1 Jan 19 10:26:45 dark volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jan 19 10:26:45 dark volumio[1057]: info: Jan 19 10:26:45 dark volumio[1057]: ---------------------------- MPD announces state update: update Jan 19 10:26:45 dark volumio[1057]: error: updateQueue error: null Jan 19 10:26:45 dark volumio[1057]: info: ControllerMpd::getState Jan 19 10:26:45 dark volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Jan 19 10:26:45 dark kernel: hid-generic 0003:041E:3273.0002: input,hidraw0: USB HID v1.00 Device [ACTIONS Stage V2] on usb-3f980000.usb-1.2/input3 Jan 19 10:26:45 dark volumio[1057]: info: Jan 19 10:26:45 dark volumio[1057]: ---------------------------- USB Audio Device Attached Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::Close All Modals sent Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 10:26:45 dark volumio[1057]: info: Output device has changed, restarting MPD Jan 19 10:26:45 dark volumio[1057]: info: Output device has changed, restarting Shairport Sync Jan 19 10:26:45 dark sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 10:26:45 dark sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 10:26:45 dark sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 10:26:45 dark sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 10:26:45 dark sudo[1235]: pam_unix(sudo:session): session closed for user root Jan 19 10:26:45 dark systemd[1]: Stopping Music Player Daemon... Jan 19 10:26:45 dark volumio[1057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 10:26:45 dark volumio[1057]: info: Setting mixer PCM for card Stage V2 Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 19 10:26:45 dark volumio[1057]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Jan 19 10:26:45 dark volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 10:26:45 dark volumio[1057]: info: MPD Permissions set Jan 19 10:26:46 dark systemd[1]: Starting Music Player Daemon... Jan 19 10:26:46 dark volumio[1057]: Unable to parse: Jan 19 10:26:46 dark volumio[1057]: Simple mixer control 'PCM',0 Jan 19 10:26:46 dark volumio[1057]: Capabilities: pswitch pswitch-joined Jan 19 10:26:46 dark volumio[1057]: Playback channels: Mono Jan 19 10:26:46 dark volumio[1057]: Mono: Playback [on] Jan 19 10:26:46 dark volumio[1057]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 19 10:26:47 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 19 10:26:48 dark volumio[1057]: Unable to parse: Jan 19 10:26:48 dark volumio[1057]: Simple mixer control 'PCM',0 Jan 19 10:26:48 dark volumio[1057]: Capabilities: pswitch pswitch-joined Jan 19 10:26:48 dark volumio[1057]: Playback channels: Mono Jan 19 10:26:48 dark volumio[1057]: Mono: Playback [on] Jan 19 10:26:48 dark volumio[1057]: info: VolumeController:: Volume=undefined Mute =false Jan 19 10:26:48 dark volumio[1057]: info: CoreStateMachine::pushState Jan 19 10:26:48 dark volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 19 10:26:48 dark volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 19 10:26:48 dark volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 10:26:48 dark sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 10:26:48 dark volumio[1057]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 19 10:26:48 dark volumio[1057]: info: CoreStateMachine::getcurrentVolume Jan 19 10:26:48 dark volumio[1057]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 10:26:48 dark sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 10:26:48 dark sudo[1267]: pam_unix(sudo:session): session closed for user root Jan 19 10:26:48 dark volumio[1057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 10:26:48 dark sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 10:26:48 dark volumio[1057]: { Error: write EPIPE Jan 19 10:26:48 dark volumio[1057]: at _errnoException (util.js:1022:11) Jan 19 10:26:48 dark volumio[1057]: at WriteWrap.afterWrite [as oncomplete] (net.js:880:14) code: 'EPIPE', errno: 'EPIPE', syscall: 'write' } Jan 19 10:26:48 dark volumio[1057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 10:26:48 dark sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 10:26:48 dark sudo[1271]: pam_unix(sudo:session): session closed for user root Jan 19 10:26:48 dark systemd[1]: Started Music Player Daemon. Jan 19 10:26:48 dark sudo[1237]: pam_unix(sudo:session): session closed for user root Jan 19 10:26:49 dark sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 10:25 Jan 19 10:26:49 dark sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"