-- Logs begin at Sun 2024-08-11 08:08:17 UTC, end at Sat 2025-05-03 06:48:42 UTC. -- May 03 06:48:26 badradio kernel: usb 1-1: USB disconnect, device number 2 May 03 06:48:26 badradio kernel: Indeed it is in host mode hprt0 = 00021501 May 03 06:48:26 badradio kernel: usb 1-1: new full-speed USB device number 3 using dwc_otg May 03 06:48:26 badradio kernel: Indeed it is in host mode hprt0 = 00021501 May 03 06:48:26 badradio volumio[926]: info: May 03 06:48:26 badradio volumio[926]: ---------------------------- USB Audio Device Detached May 03 06:48:26 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 03 06:48:26 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 06:48:26 badradio kernel: usb 1-1: New USB device found, idVendor=0d8c, idProduct=000c, bcdDevice= 1.00 May 03 06:48:26 badradio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 May 03 06:48:26 badradio kernel: usb 1-1: Product: C-Media USB Headphone Set May 03 06:48:27 badradio kernel: input: C-Media USB Headphone Set as /devices/platform/soc/20980000.usb/usb1/1-1/1-1:1.3/0003:0D8C:000C.0002/input/input1 May 03 06:48:27 badradio kernel: hid-generic 0003:0D8C:000C.0002: input,hidraw0: USB HID v1.00 Device [C-Media USB Headphone Set ] on usb-20980000.usb-1/input3 May 03 06:48:27 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 03 06:48:27 badradio volumio[926]: info: No valid Plugin REST Endpoint May 03 06:48:28 badradio volumio[926]: info: May 03 06:48:28 badradio volumio[926]: ---------------------------- USB Audio Device Attached May 03 06:48:28 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::Close All Modals sent May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 06:48:29 badradio volumio[926]: info: Output device has changed, restarting MPD May 03 06:48:29 badradio volumio[926]: info: Output device has changed, restarting Shairport Sync May 03 06:48:29 badradio sudo[4349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 06:48:29 badradio sudo[4349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:29 badradio sudo[4349]: pam_unix(sudo:session): session closed for user root May 03 06:48:29 badradio sudo[4351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 06:48:29 badradio sudo[4351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:29 badradio systemd[1]: Stopping Music Player Daemon... May 03 06:48:29 badradio volumio[926]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 03 06:48:29 badradio volumio[926]: [SpotifyConnect] Creating VLS config file May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: [SpotifyConnect] Creating VLS config file May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: [SpotifyConnect] Creating VLS config file May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:29 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio systemd[1]: Starting Music Player Daemon... May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 06:48:30 badradio volumio[926]: info: Setting mixer Headphone for card C-Media USB Headphone Set May 03 06:48:30 badradio volumio[926]: [SpotifyConnect] Creating VLS config file May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: [SpotifyConnect] Creating VLS config file May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: [SpotifyConnect] Creating VLS config file May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 03 06:48:30 badradio volumio[926]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Headphone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 May 03 06:48:30 badradio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 06:48:31 badradio volumio[926]: [SpotifyConnect] Restarting Vollibrespot Daemon May 03 06:48:31 badradio volumio[926]: [SpotifyConnect] Restarting Vollibrespot Daemon May 03 06:48:31 badradio volumio[926]: [SpotifyConnect] Restarting Vollibrespot Daemon May 03 06:48:31 badradio volumio[926]: [SpotifyConnect] Restarting Vollibrespot Daemon May 03 06:48:31 badradio sudo[4378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 03 06:48:31 badradio sudo[4378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:31 badradio sudo[4380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 03 06:48:31 badradio systemd[1]: Starting Volspotconnect2 Daemon... May 03 06:48:31 badradio volumio[926]: info: No valid Plugin REST Endpoint May 03 06:48:31 badradio systemd[1]: Started Volspotconnect2 Daemon. May 03 06:48:31 badradio sudo[4380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:31 badradio sudo[4378]: pam_unix(sudo:session): session closed for user root May 03 06:48:32 badradio volumio[4390]: /usr/lib/startconnect.sh: line 5: 4395 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml May 03 06:48:32 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 03 06:48:32 badradio sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 03 06:48:32 badradio systemd[1]: Stopping Volspotconnect2 Daemon... May 03 06:48:32 badradio systemd[1]: Starting Volspotconnect2 Daemon... May 03 06:48:32 badradio systemd[1]: Started Volspotconnect2 Daemon. May 03 06:48:32 badradio sudo[4380]: pam_unix(sudo:session): session closed for user root May 03 06:48:32 badradio sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:32 badradio volumio[926]: [SpotifyConnect] Error creating SpotifyConnect Daemon config Error: ENOENT: no such file or directory, open '/data/plugins/music_service/volspotconnect2/volspotify.tmpl' May 03 06:48:32 badradio volumio[926]: [SpotifyConnect] Restarting Vollibrespot Daemon May 03 06:48:32 badradio volumio[4399]: /usr/lib/startconnect.sh: line 5: 4405 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml May 03 06:48:32 badradio sudo[4392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service May 03 06:48:32 badradio systemd[1]: Stopping Volspotconnect2 Daemon... May 03 06:48:32 badradio systemd[1]: Starting Volspotconnect2 Daemon... May 03 06:48:32 badradio systemd[1]: Started Volspotconnect2 Daemon. May 03 06:48:32 badradio sudo[4392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:32 badradio sudo[4383]: pam_unix(sudo:session): session closed for user root May 03 06:48:32 badradio volumio[926]: [SpotifyConnect] Error creating SpotifyConnect Daemon config Error: ENOENT: no such file or directory, open '/data/plugins/music_service/volspotconnect2/volspotify.tmpl' May 03 06:48:32 badradio volumio[4412]: /usr/lib/startconnect.sh: line 5: 4417 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml May 03 06:48:32 badradio volumio[926]: [SpotifyConnect] Restarting Vollibrespot Daemon May 03 06:48:33 badradio systemd[1]: Stopping Volspotconnect2 Daemon... May 03 06:48:33 badradio systemd[1]: Starting Volspotconnect2 Daemon... May 03 06:48:33 badradio systemd[1]: Started Volspotconnect2 Daemon. May 03 06:48:33 badradio sudo[4392]: pam_unix(sudo:session): session closed for user root May 03 06:48:33 badradio volumio[4422]: /usr/lib/startconnect.sh: line 5: 4425 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml May 03 06:48:33 badradio sudo[4416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 03 06:48:33 badradio volumio[926]: error: Upnp client error: Error: This socket has been ended by the other party May 03 06:48:33 badradio sudo[4416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:33 badradio sudo[4416]: pam_unix(sudo:session): session closed for user root May 03 06:48:33 badradio sudo[4429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 03 06:48:33 badradio volumio[926]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 03 06:48:33 badradio sudo[4429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:33 badradio sudo[4429]: pam_unix(sudo:session): session closed for user root May 03 06:48:33 badradio volumio[926]: info: MPD Permissions set May 03 06:48:33 badradio volumio[926]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 03 06:48:33 badradio volumio[926]: info: CoreStateMachine::getcurrentVolume May 03 06:48:33 badradio volumio[926]: info: CoreCommandRouter::volumioRetrievevolume May 03 06:48:34 badradio volumio[926]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 03 06:48:34 badradio volumio[926]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 03 06:48:34 badradio volumio[926]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 03 06:48:34 badradio volumio[926]: info: VolumeController:: Volume=0 Mute =false May 03 06:48:34 badradio volumio[926]: info: CoreStateMachine::pushState May 03 06:48:34 badradio volumio[926]: info: CorePlayQueue::getTrack 0 May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::volumioPushState May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 06:48:34 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 03 06:48:35 badradio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. May 03 06:48:35 badradio systemd[1]: Stopping Volspotconnect2 Daemon... May 03 06:48:35 badradio systemd[1]: Starting Volspotconnect2 Daemon... May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 06:48:35 badradio systemd[1]: Started Volspotconnect2 Daemon. May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 06:48:35 badradio volumio[4452]: /usr/lib/startconnect.sh: line 5: 4453 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 03 06:48:35 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 03 06:48:35 badradio volumio[926]: info: Starting Shairport Sync May 03 06:48:36 badradio sudo[4459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 03 06:48:36 badradio volumio[926]: info: VolumeController:: Volume=0 Mute =false May 03 06:48:36 badradio volumio[926]: info: CoreStateMachine::pushState May 03 06:48:36 badradio volumio[926]: info: CorePlayQueue::getTrack 0 May 03 06:48:36 badradio volumio[926]: info: CoreCommandRouter::volumioPushState May 03 06:48:36 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 06:48:36 badradio sudo[4459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 06:48:36 badradio volumio[926]: info: CoreStateMachine::updateTrackBlock May 03 06:48:36 badradio volumio[926]: info: CorePlayQueue::getTrackBlock May 03 06:48:36 badradio volumio[926]: info: CoreCommandRouter::volumioRetrievevolume May 03 06:48:36 badradio systemd[1]: Started Music Player Daemon. May 03 06:48:36 badradio sudo[4351]: pam_unix(sudo:session): session closed for user root May 03 06:48:36 badradio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 03 06:48:36 badradio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 03 06:48:36 badradio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 03 06:48:36 badradio sudo[4459]: pam_unix(sudo:session): session closed for user root May 03 06:48:37 badradio volumio[926]: info: Shairport-Sync Started May 03 06:48:37 badradio volumio[926]: error: updateQueue error: null May 03 06:48:37 badradio volumio[926]: info: VolumeController:: Volume=0 Mute =false May 03 06:48:37 badradio volumio[926]: info: CoreStateMachine::pushState May 03 06:48:37 badradio volumio[926]: info: CorePlayQueue::getTrack 0 May 03 06:48:37 badradio volumio[926]: info: CoreCommandRouter::volumioPushState May 03 06:48:37 badradio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 06:48:37 badradio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. May 03 06:48:37 badradio systemd[1]: Stopping Volspotconnect2 Daemon... May 03 06:48:37 badradio systemd[1]: Starting Volspotconnect2 Daemon... May 03 06:48:37 badradio systemd[1]: volspotconnect.service start request repeated too quickly, refusing to start. May 03 06:48:37 badradio systemd[1]: Failed to start Volspotconnect2 Daemon. May 03 06:48:37 badradio systemd[1]: Unit volspotconnect.service entered failed state. May 03 06:48:39 badradio volumio[926]: info: Checking Spotify Web API May 03 06:48:39 badradio volumio[926]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 06:48:39 badradio volumio[926]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running May 03 06:48:39 badradio volumio[926]: at Socket._healthCheck (dgram.js:638:11) May 03 06:48:39 badradio volumio[926]: at Socket.send (dgram.js:435:8) May 03 06:48:39 badradio volumio[926]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) May 03 06:48:39 badradio volumio[926]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23) May 03 06:48:39 badradio volumio[926]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2651:18) May 03 06:48:39 badradio volumio[926]: at ontimeout (timers.js:482:11) May 03 06:48:39 badradio volumio[926]: at tryOnTimeout (timers.js:317:5) May 03 06:48:39 badradio volumio[926]: at Timer.listOnTimeout (timers.js:277:5) May 03 06:48:39 badradio volumio[926]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 06:48:42 badradio sudo[4495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-03 06:47 May 03 06:48:42 badradio sudo[4495]: 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="e81b2037a65d8f554cb0c857a1007d659ad7f838" VOLUMIO_FE_VERSION="e489037accd8e0b2dcbd6e0b054400d8cd36d4e2" VOLUMIO_BE_VERSION="25875b197c75cd1ac7eb5664d94cdd19afb06985" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Feb 19 17:39:10 CET 2021" VOLUMIO_VERSION="2.873" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="781d4c9fbf86d562939968306a6a26f4"