-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-10-09 15:04:05 UTC. -- Oct 09 15:03:56 volumio kernel: usb 1-1.3: new high-speed USB device number 8 using dwc_otg Oct 09 15:03:57 volumio kernel: usb 1-1.3: config 1 has an invalid interface number: 3 but max is 2 Oct 09 15:03:57 volumio kernel: usb 1-1.3: config 1 has an invalid interface number: 3 but max is 2 Oct 09 15:03:57 volumio kernel: usb 1-1.3: config 1 has an invalid interface number: 3 but max is 2 Oct 09 15:03:57 volumio kernel: usb 1-1.3: config 1 has no interface number 1 Oct 09 15:03:57 volumio kernel: usb 1-1.3: New USB device found, idVendor=0644, idProduct=8038, bcdDevice= 0.03 Oct 09 15:03:57 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 09 15:03:57 volumio kernel: usb 1-1.3: Product: TEAC USB AUDIO DEVICE Oct 09 15:03:57 volumio kernel: usb 1-1.3: Manufacturer: TEAC Oct 09 15:03:57 volumio kernel: hid-generic 0003:0644:8038.0005: No inputs registered, leaving Oct 09 15:03:57 volumio kernel: hid-generic 0003:0644:8038.0005: hidraw0: USB HID v1.00 Device [TEAC TEAC USB AUDIO DEVICE] on usb-3f980000.usb-1.3/input0 Oct 09 15:03:57 volumio volumio[913]: info: Oct 09 15:03:57 volumio volumio[913]: ---------------------------- USB Audio Device Attached Oct 09 15:03:57 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 09 15:03:57 volumio volumio[913]: info: CoreCommandRouter::Close All Modals sent Oct 09 15:03:57 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 09 15:03:57 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 09 15:03:58 volumio volumio[913]: info: Device 5 does not have any Mixer Control Available Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Creating VLS config file Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Creating VLS config file Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Creating VLS config file Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 09 15:03:58 volumio volumio[913]: info: Updating Volume Controller Parameters: Device: 5 Name: TEAC USB AUDIO DEVICE Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 15:03:58 volumio volumio[913]: info: CoreStateMachine::pushState Oct 09 15:03:58 volumio volumio[913]: info: CorePlayQueue::getTrack 4 Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 09 15:03:58 volumio volumio[913]: info: Preparing to generate the ALSA configuration file Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 09 15:03:58 volumio sudo[25278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 09 15:03:58 volumio sudo[25278]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 09 15:03:58 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Succeeded. Oct 09 15:03:58 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 09 15:03:58 volumio sudo[25281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 09 15:03:58 volumio sudo[25281]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 09 15:03:58 volumio sudo[25283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 09 15:03:58 volumio sudo[25283]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio volumio[25285]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 09 15:03:58 volumio volumio[25285]: Reading Config from "/tmp/volspotify.toml" Oct 09 15:03:58 volumio volumio[25285]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 09 15:03:58 volumio sudo[25278]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Succeeded. Oct 09 15:03:58 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 09 15:03:58 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 09 15:03:58 volumio sudo[25283]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio sudo[25281]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio volumio[25289]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 09 15:03:58 volumio volumio[25289]: Reading Config from "/tmp/volspotify.toml" Oct 09 15:03:58 volumio volumio[25289]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 09 15:03:58 volumio volumio[25289]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 09 15:03:58 volumio volumio[913]: info: Asound.conf file unchanged, so no further update is needed Oct 09 15:03:58 volumio volumio[913]: info: Output device has changed, restarting MPD Oct 09 15:03:58 volumio sudo[25295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 09 15:03:58 volumio sudo[25295]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio volumio[913]: info: Output device has changed, restarting Shairport Sync Oct 09 15:03:58 volumio sudo[25295]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 15:03:58 volumio sudo[25299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 09 15:03:58 volumio sudo[25299]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio systemd[1]: Stopping Music Player Daemon... Oct 09 15:03:58 volumio volumio[913]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Creating VLS config file Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Creating VLS config file Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio systemd[1]: mpd.service: Succeeded. Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Creating VLS config file Oct 09 15:03:58 volumio systemd[1]: Stopped Music Player Daemon. Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 09 15:03:58 volumio systemd[1]: Starting Music Player Daemon... Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 09 15:03:58 volumio sudo[25306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 09 15:03:58 volumio sudo[25306]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 09 15:03:58 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Succeeded. Oct 09 15:03:58 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 09 15:03:58 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 09 15:03:58 volumio sudo[25306]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio sudo[25309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 09 15:03:58 volumio sudo[25309]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio volumio[913]: info: MPD Permissions set Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 09 15:03:58 volumio volumio[25312]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 09 15:03:58 volumio volumio[25312]: Reading Config from "/tmp/volspotify.toml" Oct 09 15:03:58 volumio volumio[25312]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 09 15:03:58 volumio sudo[25311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 09 15:03:58 volumio sudo[25311]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 15:03:58 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 15:03:58 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 09 15:03:58 volumio systemd[1]: volspotconnect.service: Succeeded. Oct 09 15:03:58 volumio volumio[913]: info: Starting Shairport Sync Oct 09 15:03:58 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 09 15:03:58 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 09 15:03:58 volumio sudo[25311]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio sudo[25309]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio volumio[25323]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 09 15:03:58 volumio volumio[25323]: Reading Config from "/tmp/volspotify.toml" Oct 09 15:03:58 volumio volumio[25323]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 09 15:03:58 volumio volumio[913]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 09 15:03:58 volumio sudo[25325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 09 15:03:58 volumio sudo[25325]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 15:03:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 09 15:03:58 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 09 15:03:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 09 15:03:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 09 15:03:58 volumio volumio[25323]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 09 15:03:58 volumio sudo[25325]: pam_unix(sudo:session): session closed for user root Oct 09 15:03:58 volumio volumio[913]: info: Shairport-Sync Started Oct 09 15:03:59 volumio mpd[25328]: Oct 09 15:03 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 09 15:04:00 volumio mpd[25328]: Oct 09 15:04 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 09 15:04:00 volumio mpd[25328]: Oct 09 15:04 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 09 15:04:00 volumio systemd[1]: Started Music Player Daemon. Oct 09 15:04:00 volumio sudo[25299]: pam_unix(sudo:session): session closed for user root Oct 09 15:04:00 volumio volumio[913]: error: updateQueue error: null Oct 09 15:04:02 volumio volumio[913]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 09 15:04:02 volumio volumio[913]: info: CoreStateMachine::getcurrentVolume Oct 09 15:04:02 volumio volumio[913]: info: CoreCommandRouter::volumioRetrievevolume Oct 09 15:04:02 volumio volumio[913]: info: CoreStateMachine::pushState Oct 09 15:04:02 volumio volumio[913]: info: CorePlayQueue::getTrack 4 Oct 09 15:04:02 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 09 15:04:04 volumio volumio[913]: info: Checking Spotify Web API Oct 09 15:04:04 volumio volumio[913]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 15:04:04 volumio volumio[913]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Oct 09 15:04:04 volumio volumio[913]: at healthCheck (dgram.js:897:11) Oct 09 15:04:04 volumio volumio[913]: at Socket.send (dgram.js:622:3) Oct 09 15:04:04 volumio volumio[913]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Oct 09 15:04:04 volumio volumio[913]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2354:23) Oct 09 15:04:04 volumio volumio[913]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2694:18) Oct 09 15:04:04 volumio volumio[913]: at listOnTimeout (internal/timers.js:554:17) Oct 09 15:04:04 volumio volumio[913]: at processTimers (internal/timers.js:497:7) { Oct 09 15:04:04 volumio volumio[913]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Oct 09 15:04:04 volumio volumio[913]: } Oct 09 15:04:04 volumio volumio[913]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 15:04:05 volumio sudo[25343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-09 15:03 Oct 09 15:04:05 volumio sudo[25343]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"