-- Logs begin at Fri 2024-11-29 07:06:16 UTC, end at Mon 2024-12-02 02:07:55 UTC. -- Dec 02 02:06:40 mx kernel: usb 1-1.4.2: USB disconnect, device number 6 Dec 02 02:06:40 mx kernel: usb 1-1.4.2.1: USB disconnect, device number 7 Dec 02 02:06:40 mx kernel: usb 1-1.4.2.1: cannot submit urb (err = -19) Dec 02 02:06:40 mx kernel: usb 1-1.4.2.1: 3:1: cannot set freq 44100 to ep 0x3 Dec 02 02:06:40 mx volumio[1280]: info: Dec 02 02:06:40 mx volumio[1280]: ---------------------------- MPD announces state update: player Dec 02 02:06:40 mx volumio[1280]: info: ControllerMpd::getState Dec 02 02:06:40 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand status Dec 02 02:06:40 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 02 02:06:40 mx volumio[1280]: info: sendMpdCommand status took 5 milliseconds Dec 02 02:06:40 mx volumio[1280]: verbose: ControllerMpd::parseState Dec 02 02:06:40 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 02:06:40 mx volumio[1280]: info: sendMpdCommand clearerror took 5 milliseconds Dec 02 02:06:40 mx volumio[1280]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 02:06:40 mx volumio[1280]: verbose: ControllerMpd::parseTrackInfo Dec 02 02:06:40 mx volumio[1280]: info: ControllerMpd::pushState Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::servicePushState Dec 02 02:06:40 mx volumio[1280]: verbose: In UPNP mode Dec 02 02:06:40 mx volumio[1280]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":121754,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唐古拉","artist":"王麟","album":"咪咕咪咕","uri":"http://isure6.stream.qqmusic.qq.com/F000000x00tj3HD0tS.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=02890B974F2EAB502DFDFC26A9398DFEF683EA70ED78F0A3B0CCF95AEAA94D362711FB8FE93C28CC5B672E184AB4274F523BA34F63C8CAF8&uin=2687950226&src=F000000ydbsE3RxRzO.flac&redirect=1&fromtag=111114","trackType":""} Dec 02 02:06:40 mx volumio[1280]: verbose: CURRENT POSITION 0 Dec 02 02:06:40 mx volumio[1280]: info: CoreStateMachine::syncState stateService pause Dec 02 02:06:40 mx volumio[1280]: info: CoreStateMachine::syncState currentStatus play Dec 02 02:06:40 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:40 mx volumio[1280]: info: CoreStateMachine::stPlaybackTimer Dec 02 02:06:40 mx volumio[1280]: info: ------------------------------ 31ms Dec 02 02:06:40 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:40 mx volumio[1280]: info: Dec 02 02:06:40 mx volumio[1280]: ---------------------------- USB Audio Device Detached Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , usbAudioDetach Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 02:06:40 mx volumio[1280]: aplay: device_list:268: no soundcards found... Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 02 02:06:40 mx volumio[1280]: info: Stopping RAAT Plugin Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 02 02:06:40 mx volumio[1280]: info: Stopping RAAT Plugin Dec 02 02:06:40 mx sudo[2741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 02 02:06:40 mx sudo[2743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 02 02:06:40 mx sudo[2741]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:40 mx volumio[1280]: info: Executing endpoint scanAudioInputs Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , scanAudioInputs Dec 02 02:06:40 mx volumio[1280]: info: Scanning Audio Inputs Dec 02 02:06:40 mx sudo[2743]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:40 mx volumio[1280]: arecord: device_list:268: no soundcards found... Dec 02 02:06:40 mx systemd[1]: Stopping RAAT DAEMON... Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesDigiHug USB Audio Dec 02 02:06:40 mx systemd[1]: Stopped RAAT DAEMON. Dec 02 02:06:40 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 02:06:40 mx sudo[2743]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:40 mx sudo[2741]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:40 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:40 mx systemd[1]: Service sound.target is not needed anymore. Stopping. Dec 02 02:06:40 mx systemd[1]: Stopping Sound Card. Dec 02 02:06:40 mx systemd[1]: Stopped target Sound Card. Dec 02 02:06:40 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:40 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:40 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:40 mx volumio[1280]: info: Raat Daemon stopped successfully Dec 02 02:06:40 mx volumio[1280]: info: Raat Daemon stopped successfully Dec 02 02:06:42 mx kernel: usb 1-1.2: new high-speed USB device number 8 using dwc_otg Dec 02 02:06:43 mx kernel: usb 1-1.2: New USB device found, idVendor=1a40, idProduct=0101 Dec 02 02:06:43 mx kernel: usb 1-1.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 02 02:06:43 mx kernel: usb 1-1.2: Product: USB 2.0 Hub Dec 02 02:06:43 mx kernel: hub 1-1.2:1.0: USB hub found Dec 02 02:06:43 mx kernel: hub 1-1.2:1.0: 4 ports detected Dec 02 02:06:43 mx kernel: usb 1-1.2.1: new full-speed USB device number 9 using dwc_otg Dec 02 02:06:43 mx kernel: usb 1-1.2.1: New USB device found, idVendor=1852, idProduct=7022 Dec 02 02:06:43 mx kernel: usb 1-1.2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 02 02:06:43 mx kernel: usb 1-1.2.1: Product: DigiHug USB Audio Dec 02 02:06:43 mx kernel: usb 1-1.2.1: Manufacturer: GFEC ASSP Dec 02 02:06:43 mx kernel: input: GFEC ASSP DigiHug USB Audio as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2.1/1-1.2.1:1.0/0003:1852:7022.0002/input/input1 Dec 02 02:06:43 mx kernel: hid-generic 0003:1852:7022.0002: input,hidraw0: USB HID v1.00 Device [GFEC ASSP DigiHug USB Audio] on usb-3f980000.usb-1.2.1/input0 Dec 02 02:06:43 mx systemd[1]: Starting Sound Card. Dec 02 02:06:43 mx systemd[1]: Reached target Sound Card. Dec 02 02:06:43 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:43 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:43 mx volumio[1280]: info: Dec 02 02:06:43 mx volumio[1280]: ---------------------------- USB Audio Device Attached Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , usbAudioAttach Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 02 02:06:43 mx volumio[1280]: No protocol specified Dec 02 02:06:43 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDefaultMixer Dec 02 02:06:43 mx volumio[1280]: No protocol specified Dec 02 02:06:43 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 02:06:43 mx volumio[1280]: No protocol specified Dec 02 02:06:43 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:43 mx volumio[1280]: info: Device 5 does not have any Mixer Control Available, setting a softvol device Dec 02 02:06:43 mx volumio[1280]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 02 02:06:43 mx volumio[1280]: [SpotifyConnect] Creating VLS config file Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:43 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: No protocol specified Dec 02 02:06:44 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 02 02:06:44 mx volumio[1280]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::Close All Modals sent Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:44 mx volumio[1280]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 02 02:06:44 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:44 mx volumio[1280]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 02 02:06:44 mx volumio[1280]: [SpotifyConnect] Restarting Vollibrespot Daemon Dec 02 02:06:44 mx sudo[2805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Dec 02 02:06:44 mx volumio[1280]: info: Executing endpoint scanAudioInputs Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , scanAudioInputs Dec 02 02:06:44 mx volumio[1280]: info: Scanning Audio Inputs Dec 02 02:06:44 mx sudo[2805]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:44 mx systemd[1]: Stopping Volspotconnect2 Daemon... Dec 02 02:06:44 mx systemd[1]: Starting Volspotconnect2 Daemon... Dec 02 02:06:44 mx systemd[1]: Started Volspotconnect2 Daemon. Dec 02 02:06:44 mx sudo[2805]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:44 mx volumio[1280]: No protocol specified Dec 02 02:06:44 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:44 mx volumio[1280]: info: Checking against Known Cards name Dec 02 02:06:44 mx volumio[2813]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Dec 02 02:06:44 mx volumio[2813]: Reading Config from "/tmp/volspotify.toml" Dec 02 02:06:44 mx volumio[2813]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 02:06:44 mx volumio[1280]: info: [1733105204198] CoreMusicLibrary::Adding element DigiHug USB Audio Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 02:06:44 mx volumio[1280]: info: Checking against Known Cards name Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 02 02:06:44 mx volumio[1280]: No protocol specified Dec 02 02:06:44 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:44 mx volumio[2813]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Dec 02 02:06:44 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:44 mx sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 02 02:06:44 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:44 mx sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:44 mx volumio[1280]: [SpotifyConnect] Vollibrespot Daemon service restarted! Dec 02 02:06:44 mx systemd[1]: Starting RAAT DAEMON... Dec 02 02:06:44 mx systemd[1]: Started RAAT DAEMON. Dec 02 02:06:44 mx sudo[2826]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:44 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:44 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:44 mx volumio[1280]: info: Raat Daemon started successfully Dec 02 02:06:44 mx volumio[1280]: info: Executing endpoint restartRAATSocket Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 02 02:06:44 mx volumio[1280]: No protocol specified Dec 02 02:06:44 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:44 mx sudo[2852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 02 02:06:44 mx sudo[2852]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:44 mx systemd[1]: Stopping RAAT DAEMON... Dec 02 02:06:44 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:44 mx systemd[1]: Starting RAAT DAEMON... Dec 02 02:06:44 mx systemd[1]: Started RAAT DAEMON. Dec 02 02:06:44 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:44 mx sudo[2852]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:44 mx volumio[1280]: info: Raat Daemon started successfully Dec 02 02:06:44 mx volumio[1280]: info: Executing endpoint restartRAATSocket Dec 02 02:06:44 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 02 02:06:46 mx volumio[1280]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 02 02:06:46 mx volumio[1280]: info: CoreStateMachine::getcurrentVolume Dec 02 02:06:46 mx volumio[1280]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 02:06:46 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:46 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:46 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:46 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:47 mx kernel: usb 1-1.2: USB disconnect, device number 8 Dec 02 02:06:47 mx kernel: usb 1-1.2.1: USB disconnect, device number 9 Dec 02 02:06:47 mx volumio[1280]: info: Executing endpoint scanAudioInputs Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , scanAudioInputs Dec 02 02:06:47 mx volumio[1280]: info: Scanning Audio Inputs Dec 02 02:06:47 mx volumio[1280]: arecord: device_list:268: no soundcards found... Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesDigiHug USB Audio Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 02:06:47 mx volumio[1280]: info: Dec 02 02:06:47 mx volumio[1280]: ---------------------------- USB Audio Device Detached Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , usbAudioDetach Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 02:06:47 mx volumio[1280]: aplay: device_list:268: no soundcards found... Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 02 02:06:47 mx volumio[1280]: info: Stopping RAAT Plugin Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 02 02:06:47 mx volumio[1280]: info: Stopping RAAT Plugin Dec 02 02:06:47 mx sudo[2903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 02 02:06:47 mx systemd[1]: Service sound.target is not needed anymore. Stopping. Dec 02 02:06:47 mx systemd[1]: Stopping Sound Card. Dec 02 02:06:47 mx systemd[1]: Stopped target Sound Card. Dec 02 02:06:47 mx sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:47 mx sudo[2905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 02 02:06:47 mx sudo[2905]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:47 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:47 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:47 mx systemd[1]: Stopping RAAT DAEMON... Dec 02 02:06:47 mx systemd[1]: Stopped RAAT DAEMON. Dec 02 02:06:47 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:47 mx systemd[1]: Stopped RAAT DAEMON. Dec 02 02:06:47 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:47 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:47 mx sudo[2903]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:47 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:47 mx sudo[2905]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:47 mx volumio[1280]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::volumiosetStartupVolume Dec 02 02:06:47 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:47 mx volumio[1280]: info: Raat Daemon stopped successfully Dec 02 02:06:47 mx volumio[1280]: info: Raat Daemon stopped successfully Dec 02 02:06:48 mx kernel: usb 1-1.4.2: new high-speed USB device number 10 using dwc_otg Dec 02 02:06:48 mx kernel: usb 1-1.4.2: New USB device found, idVendor=1a40, idProduct=0101 Dec 02 02:06:48 mx kernel: usb 1-1.4.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 02 02:06:48 mx kernel: usb 1-1.4.2: Product: USB 2.0 Hub Dec 02 02:06:48 mx kernel: hub 1-1.4.2:1.0: USB hub found Dec 02 02:06:48 mx kernel: hub 1-1.4.2:1.0: 4 ports detected Dec 02 02:06:49 mx kernel: usb 1-1.4.2.1: new full-speed USB device number 11 using dwc_otg Dec 02 02:06:49 mx kernel: usb 1-1.4.2.1: New USB device found, idVendor=1852, idProduct=7022 Dec 02 02:06:49 mx kernel: usb 1-1.4.2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 02 02:06:49 mx kernel: usb 1-1.4.2.1: Product: DigiHug USB Audio Dec 02 02:06:49 mx kernel: usb 1-1.4.2.1: Manufacturer: GFEC ASSP Dec 02 02:06:49 mx kernel: input: GFEC ASSP DigiHug USB Audio as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4.2/1-1.4.2.1/1-1.4.2.1:1.0/0003:1852:7022.0003/input/input2 Dec 02 02:06:49 mx kernel: hid-generic 0003:1852:7022.0003: input,hidraw0: USB HID v1.00 Device [GFEC ASSP DigiHug USB Audio] on usb-3f980000.usb-1.4.2.1/input0 Dec 02 02:06:49 mx systemd[1]: Starting Sound Card. Dec 02 02:06:49 mx systemd[1]: Reached target Sound Card. Dec 02 02:06:49 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:49 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:49 mx volumio[1280]: info: Executing endpoint scanAudioInputs Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , scanAudioInputs Dec 02 02:06:49 mx volumio[1280]: info: Scanning Audio Inputs Dec 02 02:06:49 mx volumio[1280]: No protocol specified Dec 02 02:06:49 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:49 mx volumio[1280]: info: Checking against Known Cards name Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 02:06:49 mx volumio[1280]: info: [1733105209668] CoreMusicLibrary::Adding element DigiHug USB Audio Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 02:06:49 mx volumio[1280]: info: Checking against Known Cards name Dec 02 02:06:49 mx volumio[1280]: info: Dec 02 02:06:49 mx volumio[1280]: ---------------------------- USB Audio Device Attached Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: inputs , usbAudioAttach Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 02 02:06:49 mx volumio[1280]: No protocol specified Dec 02 02:06:49 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDefaultMixer Dec 02 02:06:49 mx volumio[1280]: No protocol specified Dec 02 02:06:49 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 02:06:49 mx volumio[1280]: No protocol specified Dec 02 02:06:49 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:49 mx volumio[1280]: info: Device 5 does not have any Mixer Control Available, setting a softvol device Dec 02 02:06:49 mx volumio[1280]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 02 02:06:49 mx volumio[1280]: [SpotifyConnect] Creating VLS config file Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: No protocol specified Dec 02 02:06:49 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 02 02:06:49 mx volumio[1280]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::Close All Modals sent Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 02:06:49 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:49 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:49 mx volumio[1280]: [SpotifyConnect] Restarting Vollibrespot Daemon Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 02 02:06:50 mx sudo[2959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Dec 02 02:06:50 mx sudo[2959]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:50 mx systemd[1]: Stopping Volspotconnect2 Daemon... Dec 02 02:06:50 mx systemd[1]: Starting Volspotconnect2 Daemon... Dec 02 02:06:50 mx systemd[1]: Started Volspotconnect2 Daemon. Dec 02 02:06:50 mx volumio[1280]: No protocol specified Dec 02 02:06:50 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:50 mx sudo[2959]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:50 mx volumio[2967]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Dec 02 02:06:50 mx volumio[2967]: Reading Config from "/tmp/volspotify.toml" Dec 02 02:06:50 mx volumio[2967]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Dec 02 02:06:50 mx volumio[1280]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 02 02:06:50 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:50 mx volumio[1280]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 02 02:06:50 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:50 mx sudo[2973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 02 02:06:50 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:50 mx sudo[2973]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:50 mx volumio[1280]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Dec 02 02:06:50 mx volumio[1280]: [SpotifyConnect] Vollibrespot Daemon service restarted! Dec 02 02:06:50 mx systemd[1]: Starting RAAT DAEMON... Dec 02 02:06:50 mx systemd[1]: Started RAAT DAEMON. Dec 02 02:06:50 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:50 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:50 mx sudo[2973]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:50 mx volumio[2967]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Dec 02 02:06:50 mx volumio[1280]: info: Raat Daemon started successfully Dec 02 02:06:50 mx volumio[1280]: info: Executing endpoint restartRAATSocket Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 02:06:50 mx volumio[1280]: info: Checking Spotify Web API Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 02 02:06:50 mx volumio[1280]: No protocol specified Dec 02 02:06:50 mx volumio[1280]: xcb_connection_has_error() returned true Dec 02 02:06:50 mx sudo[3002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 02 02:06:50 mx sudo[3002]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 02:06:50 mx systemd[1]: Stopping RAAT DAEMON... Dec 02 02:06:50 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:50 mx systemd[1]: Starting RAAT DAEMON... Dec 02 02:06:50 mx systemd[1]: Started RAAT DAEMON. Dec 02 02:06:50 mx sudo[3002]: pam_unix(sudo:session): session closed for user root Dec 02 02:06:50 mx volumio[1280]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 02 02:06:50 mx volumio[1280]: info: Raat Daemon started successfully Dec 02 02:06:50 mx volumio[1280]: info: Executing endpoint restartRAATSocket Dec 02 02:06:50 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 02 02:06:52 mx volumio[1280]: info: Dec 02 02:06:52 mx volumio[1280]: ---------------------------- MPD announces state update: player Dec 02 02:06:52 mx volumio[1280]: info: ControllerMpd::getState Dec 02 02:06:52 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand status Dec 02 02:06:52 mx volumio[1280]: info: sendMpdCommand status took 2 milliseconds Dec 02 02:06:52 mx volumio[1280]: verbose: ControllerMpd::parseState Dec 02 02:06:52 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 02:06:52 mx volumio[1280]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 02:06:52 mx volumio[1280]: verbose: ControllerMpd::parseTrackInfo Dec 02 02:06:52 mx volumio[1280]: info: ControllerMpd::pushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::servicePushState Dec 02 02:06:52 mx volumio[1280]: verbose: In UPNP mode Dec 02 02:06:52 mx volumio[1280]: verbose: STATE SERVICE {"status":"play","position":0,"seek":122264,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唐古拉","artist":"王麟","album":"咪咕咪咕","uri":"http://isure6.stream.qqmusic.qq.com/F000000x00tj3HD0tS.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=02890B974F2EAB502DFDFC26A9398DFEF683EA70ED78F0A3B0CCF95AEAA94D362711FB8FE93C28CC5B672E184AB4274F523BA34F63C8CAF8&uin=2687950226&src=F000000ydbsE3RxRzO.flac&redirect=1&fromtag=111114","trackType":""} Dec 02 02:06:52 mx volumio[1280]: verbose: CURRENT POSITION 0 Dec 02 02:06:52 mx volumio[1280]: info: CoreStateMachine::syncState stateService play Dec 02 02:06:52 mx volumio[1280]: info: CoreStateMachine::syncState currentStatus play Dec 02 02:06:52 mx volumio[1280]: info: Received an update from plugin. extracting info from payload Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 02:06:52 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:52 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:52 mx volumio[1280]: info: ------------------------------ 37ms Dec 02 02:06:52 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:52 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 02 02:06:52 mx volumio[1280]: info: CoreStateMachine::getcurrentVolume Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 02:06:52 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:06:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:06:52 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:06:53 mx volumio[1280]: info: CoreCommandRouter::volumiosetStartupVolume Dec 02 02:06:53 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 02:06:56 mx volumio[1280]: info: Checking Spotify Web API Dec 02 02:07:00 mx volumio[1669]: 10 Dec 02 02:07:00 mx volumio[1669]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 02 02:07:00 mx volumio[1669]: Dload Upload Total Spent Left Speed Dec 02 02:07:00 mx volumio[1280]: info: Dec 02 02:07:00 mx volumio[1280]: ---------------------------- Client requests Volume 10 Dec 02 02:07:00 mx volumio[1280]: info: VolumeController::SetAlsaVolume10 Dec 02 02:07:00 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:00 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:00 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:00 mx volumio[1669]: [155B blob data] Dec 02 02:07:00 mx volumio[1669]: {"time":1733105220562,"response":"volume Success"}10 Dec 02 02:07:00 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:01 mx volumio[1669]: 20 Dec 02 02:07:01 mx volumio[1669]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 02 02:07:01 mx volumio[1669]: Dload Upload Total Spent Left Speed Dec 02 02:07:01 mx volumio[1280]: info: Dec 02 02:07:01 mx volumio[1280]: ---------------------------- Client requests Volume 20 Dec 02 02:07:01 mx volumio[1280]: info: VolumeController::SetAlsaVolume20 Dec 02 02:07:01 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:01 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:01 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:01 mx volumio[1669]: [155B blob data] Dec 02 02:07:01 mx volumio[1669]: {"time":1733105221471,"response":"volume Success"}20 Dec 02 02:07:01 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces state update: player Dec 02 02:07:30 mx volumio[1280]: info: ControllerMpd::getState Dec 02 02:07:30 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand status Dec 02 02:07:30 mx volumio[1280]: info: sendMpdCommand status took 1 milliseconds Dec 02 02:07:30 mx volumio[1280]: verbose: ControllerMpd::parseState Dec 02 02:07:30 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 02:07:30 mx volumio[1280]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 02:07:30 mx volumio[1280]: verbose: ControllerMpd::parseTrackInfo Dec 02 02:07:30 mx volumio[1280]: info: ControllerMpd::pushState Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::servicePushState Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:30 mx volumio[1280]: info: CorePlayQueue::getTrack 0 Dec 02 02:07:30 mx volumio[1280]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唐古拉","artist":"王麟","album":"咪咕咪咕","uri":"http://isure6.stream.qqmusic.qq.com/F000000x00tj3HD0tS.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=02890B974F2EAB502DFDFC26A9398DFEF683EA70ED78F0A3B0CCF95AEAA94D362711FB8FE93C28CC5B672E184AB4274F523BA34F63C8CAF8&uin=2687950226&src=F000000ydbsE3RxRzO.flac&redirect=1&fromtag=111114","trackType":""} Dec 02 02:07:30 mx volumio[1280]: verbose: CURRENT POSITION 0 Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::syncState stateService stop Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::syncState currentStatus play Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::play index undefined Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:30 mx volumio[1280]: info: CorePlayQueue::getTrack 0 Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:30 mx volumio[1280]: info: CorePlayQueue::getTrack 0 Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 25ms Dec 02 02:07:30 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:30 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:30 mx volumio[1280]: info: Clearing queue after UPNP request Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::ClearQueue Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::stop Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 02:07:30 mx volumio[1280]: verbose: UNSET VOLATILE Dec 02 02:07:30 mx volumio[1280]: info: CorePlayQueue::clearPlayQueue Dec 02 02:07:30 mx volumio[1280]: info: CorePlayQueue::saveQueue Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::volumioPushQueue Dec 02 02:07:30 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:30 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:30 mx volumio[1280]: error: updateQueue error: null Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 3ms Dec 02 02:07:30 mx volumio[1280]: info: Starting UPNP Playback Dec 02 02:07:30 mx volumio[1280]: info: Preparing playback through UPNP Dec 02 02:07:30 mx volumio[1280]: info: CoreCommandRouter::volumioGetState Dec 02 02:07:30 mx volumio[1280]: info: CorePlayQueue::getTrack 0 Dec 02 02:07:30 mx volumio[1280]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:30 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:30 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 3ms Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 3ms Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:30 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:30 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 4ms Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 2ms Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:30 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:30 mx volumio[1280]: info: ------------------------------ 2ms Dec 02 02:07:30 mx volumio[1280]: info: Dec 02 02:07:30 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:31 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:31 mx volumio[1280]: info: ------------------------------ 2ms Dec 02 02:07:31 mx volumio[1280]: info: Dec 02 02:07:31 mx volumio[1280]: ---------------------------- MPD announces state update: player Dec 02 02:07:31 mx volumio[1280]: info: ControllerMpd::getState Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand status Dec 02 02:07:31 mx volumio[1280]: info: Dec 02 02:07:31 mx volumio[1280]: ---------------------------- MPD announces state update: player Dec 02 02:07:31 mx volumio[1280]: info: Dec 02 02:07:31 mx volumio[1280]: ---------------------------- MPD announces system playlist update Dec 02 02:07:31 mx volumio[1280]: info: Ignoring MPD Status Update Dec 02 02:07:31 mx volumio[1280]: info: ControllerMpd::getState Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand status Dec 02 02:07:31 mx volumio[1280]: info: sendMpdCommand status took 4 milliseconds Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::parseState Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 02:07:31 mx volumio[1280]: info: sendMpdCommand status took 5 milliseconds Dec 02 02:07:31 mx volumio[1280]: info: ------------------------------ 6ms Dec 02 02:07:31 mx volumio[1280]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::parseState Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::parseTrackInfo Dec 02 02:07:31 mx volumio[1280]: info: ControllerMpd::pushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::servicePushState Dec 02 02:07:31 mx volumio[1280]: verbose: In UPNP mode Dec 02 02:07:31 mx volumio[1280]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"506 Kbps","isStreaming":false,"title":"伤不起","artist":"DJ何鹏","album":"为所欲为","uri":"http://isure6.stream.qqmusic.qq.com/F000003mIHDg2ltAFP.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1E3B6B0664BCD685C652F4DBB6609952ED0F56A40A40E90652D2673D01BB9194035C676005FE567C6A117F72E33D2B608FEAB84E43B5751A&uin=2687950226&redirect=1&fromtag=111114","trackType":""} Dec 02 02:07:31 mx volumio[1280]: verbose: CURRENT POSITION 0 Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::syncState stateService play Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::syncState currentStatus stop Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:31 mx volumio[1280]: info: ------------------------------ 35ms Dec 02 02:07:31 mx volumio[1280]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 02 02:07:31 mx volumio[1280]: verbose: ControllerMpd::parseTrackInfo Dec 02 02:07:31 mx volumio[1280]: info: ControllerMpd::pushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::servicePushState Dec 02 02:07:31 mx volumio[1280]: verbose: In UPNP mode Dec 02 02:07:31 mx volumio[1280]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"506 Kbps","isStreaming":false,"title":"伤不起","artist":"DJ何鹏","album":"为所欲为","uri":"http://isure6.stream.qqmusic.qq.com/F000003mIHDg2ltAFP.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1E3B6B0664BCD685C652F4DBB6609952ED0F56A40A40E90652D2673D01BB9194035C676005FE567C6A117F72E33D2B608FEAB84E43B5751A&uin=2687950226&redirect=1&fromtag=111114","trackType":""} Dec 02 02:07:31 mx volumio[1280]: verbose: CURRENT POSITION 0 Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::syncState stateService play Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::syncState currentStatus play Dec 02 02:07:31 mx volumio[1280]: info: Received an update from plugin. extracting info from payload Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:31 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:31 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:31 mx volumio[1280]: info: ------------------------------ 62ms Dec 02 02:07:31 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:31 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:31 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:31 mx volumio[1280]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E9%BA%9F/%E5%92%AA%E5%92%95%E5%92%AA%E5%92%95/75ea5498-55e9-403f-832f-e4b601c93f37.jpg' Dec 02 02:07:52 mx volumio[1280]: info: Dec 02 02:07:52 mx volumio[1280]: ---------------------------- MPD announces state update: player Dec 02 02:07:52 mx volumio[1280]: info: ControllerMpd::getState Dec 02 02:07:52 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand status Dec 02 02:07:52 mx volumio[1280]: info: sendMpdCommand status took 1 milliseconds Dec 02 02:07:52 mx volumio[1280]: verbose: ControllerMpd::parseState Dec 02 02:07:52 mx volumio[1280]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 02:07:52 mx volumio[1280]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 02:07:52 mx volumio[1280]: verbose: ControllerMpd::parseTrackInfo Dec 02 02:07:52 mx volumio[1280]: info: ControllerMpd::pushState Dec 02 02:07:52 mx volumio[1280]: info: CoreCommandRouter::servicePushState Dec 02 02:07:52 mx volumio[1280]: verbose: In UPNP mode Dec 02 02:07:52 mx volumio[1280]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62000,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"伤不起","artist":"DJ何鹏","album":"为所欲为","uri":"http://isure6.stream.qqmusic.qq.com/F000003mIHDg2ltAFP.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1E3B6B0664BCD685C652F4DBB6609952ED0F56A40A40E90652D2673D01BB9194035C676005FE567C6A117F72E33D2B608FEAB84E43B5751A&uin=2687950226&redirect=1&fromtag=111114","trackType":""} Dec 02 02:07:52 mx volumio[1280]: verbose: CURRENT POSITION 0 Dec 02 02:07:52 mx volumio[1280]: info: CoreStateMachine::syncState stateService play Dec 02 02:07:52 mx volumio[1280]: info: CoreStateMachine::syncState currentStatus play Dec 02 02:07:52 mx volumio[1280]: info: Received an update from plugin. extracting info from payload Dec 02 02:07:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 02:07:52 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:52 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:52 mx volumio[1280]: info: CoreStateMachine::pushState Dec 02 02:07:52 mx volumio[1280]: info: CoreCommandRouter::volumioPushState Dec 02 02:07:52 mx volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 02:07:52 mx volumio[1280]: info: ------------------------------ 28ms Dec 02 02:07:52 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:52 mx volumio[1280]: info: Updating RAAT Signal Path Dec 02 02:07:55 mx volumio[1280]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 02:07:55 mx volumio[1280]: { Error: connect ETIMEDOUT 173.244.217.42:443 Dec 02 02:07:55 mx volumio[1280]: at Object._errnoException (util.js:1022:11) Dec 02 02:07:55 mx volumio[1280]: at _exceptionWithHostPort (util.js:1044:20) Dec 02 02:07:55 mx volumio[1280]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 02 02:07:55 mx volumio[1280]: code: 'ETIMEDOUT', Dec 02 02:07:55 mx volumio[1280]: errno: 'ETIMEDOUT', Dec 02 02:07:55 mx volumio[1280]: syscall: 'connect', Dec 02 02:07:55 mx volumio[1280]: address: '173.244.217.42', Dec 02 02:07:55 mx volumio[1280]: port: 443 } Dec 02 02:07:55 mx volumio[1280]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 02:07:55 mx sudo[3483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 02:06 Dec 02 02:07:55 mx sudo[3483]: 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="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"