-- Logs begin at Wed 2024-10-16 22:25:45 BST, end at Sun 2024-10-27 22:19:54 GMT. -- Oct 27 22:18:03 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 27 22:18:04 volumio-pi3 volumiologrotate[627]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 27 22:18:04 volumio-pi3 volumiologrotate[627]: ls: cannot access 'PI3': No such file or directory Oct 27 22:18:04 volumio-pi3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Oct 27 22:18:04 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:18:04] [connect] Successful connection Oct 27 22:18:13 volumio-pi3 systemd[1]: mpd.service: Succeeded. Oct 27 22:18:13 volumio-pi3 systemd[1]: Stopped Music Player Daemon. Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: Adding METAVOLUMIO REST API Endpoints Oct 27 22:18:13 volumio-pi3 systemd[1]: Starting Music Player Daemon... Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 27 22:18:13 volumio-pi3 volumio[9007]: info: Preparing CD Folders Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Adding CD REST API Endpoints Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Starting UDEV Watcher for CD Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Detecting CD presence with UDEV Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Adding inputs REST Endpoints Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 27 22:18:14 volumio-pi3 volumio[9007]: info: Scanning Audio Inputs Oct 27 22:18:14 volumio-pi3 sudo[9722]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 27 22:18:14 volumio-pi3 sudo[9722]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 22:18:14 volumio-pi3 sudo[9722]: pam_unix(sudo:session): session closed for user root Oct 27 22:18:21 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:18:21] [connect] Successful connection Oct 27 22:18:22 volumio-pi3 volumio[9007]: info: Checking against Known Cards name Oct 27 22:18:24 volumio-pi3 volumio[9007]: info: Adding Server instance for streaming Oct 27 22:18:25 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 27 22:18:25 volumio-pi3 volumio[9007]: error: Hi Res Audio Failed Login: Missing Login Data Oct 27 22:18:25 volumio-pi3 volumio[9007]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 27 22:18:25 volumio-pi3 volumio[9007]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 27 22:18:25 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 27 22:18:27 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 27 22:18:27 volumio-pi3 volumio[9007]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 27 22:18:30 volumio-pi3 volumio[9007]: info: Stopping AccessToken refresher cron Oct 27 22:18:33 volumio-pi3 sudo[9730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 27 22:18:33 volumio-pi3 sudo[9730]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 22:18:34 volumio-pi3 volumio[9007]: info: AccessToken refresher cron started Oct 27 22:18:34 volumio-pi3 volumio[9007]: info: Stopping AccessToken refresher cron for QOBUZ Oct 27 22:18:34 volumio-pi3 volumio[9007]: info: AccessToken refresher cron started for QOBUZ Oct 27 22:18:34 volumio-pi3 volumio[9007]: info: Adding TIDAL REST API Endpoints Oct 27 22:18:34 volumio-pi3 volumio[9007]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 27 22:18:34 volumio-pi3 systemd[1]: Stopping Volumio Tidal Connect Service... Oct 27 22:18:34 volumio-pi3 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 27 22:18:34 volumio-pi3 systemd[1]: vtcs.service: Succeeded. Oct 27 22:18:34 volumio-pi3 systemd[1]: Stopped Volumio Tidal Connect Service. Oct 27 22:18:35 volumio-pi3 volumio[9007]: info: Adding QOBUZ REST API Endpoints Oct 27 22:18:35 volumio-pi3 volumio[9007]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 27 22:18:35 volumio-pi3 sudo[9730]: pam_unix(sudo:session): session closed for user root Oct 27 22:18:37 volumio-pi3 volumio[9007]: info: MRS: Pushing audio output on request Oct 27 22:18:37 volumio-pi3 volumio[9007]: info: MRS: Pushing multiroomSync output Oct 27 22:18:37 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:18:37] [connect] Successful connection Oct 27 22:18:38 volumio-pi3 volumio[9007]: info: Discovery: Connected to remote: 192.168.1.51 Oct 27 22:18:39 volumio-pi3 volumio[9007]: info: CoreCommandRouter::volumioGetState Oct 27 22:18:39 volumio-pi3 volumio[9007]: info: CorePlayQueue::getTrack 0 Oct 27 22:18:40 volumio-pi3 volumio[9007]: info: MRS: Getting audio outputs on start Oct 27 22:18:40 volumio-pi3 volumio[9007]: info: MRS: Requesting all other devices output Oct 27 22:18:41 volumio-pi3 sudo[9734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 27 22:18:41 volumio-pi3 sudo[9734]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 22:18:42 volumio-pi3 volumio[9007]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 27 22:18:49 volumio-pi3 sudo[9734]: pam_unix(sudo:session): session closed for user root Oct 27 22:18:53 volumio-pi3 go-librespot[9518]: time="2024-10-27T22:18:53Z" level=error msg="did not receive last pong from dealer, 30s passed" Oct 27 22:18:59 volumio-pi3 go-librespot[9518]: time="2024-10-27T22:18:54Z" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\"" Oct 27 22:18:59 volumio-pi3 go-librespot[9518]: time="2024-10-27T22:18:56Z" level=debug msg="re-established dealer connection" Oct 27 22:18:59 volumio-pi3 go-librespot[9518]: time="2024-10-27T22:18:56Z" level=debug msg="received connection id: ZmNjNWEyZDctMmM1NS00ZTM3LTkzZGMtYzIyZDE0NWNmMjE3K2RlYWxlcit0Y3A6Ly8wYWNiZjlmYi5pcC5ndWUxLnNwb3RpZnkubmV0OjU3MDArRTg3NzE5REI0Q0I5MkEwNDgyRjBGQzc3MTYwQThGQkVGRTk3Q0Y4NEMyQkQ2MjY3QzkwQkYzMTBGMDlDMkRFMg==" Oct 27 22:18:59 volumio-pi3 go-librespot[9518]: time="2024-10-27T22:18:56Z" level=debug msg="put connect state because NEW_DEVICE" Oct 27 22:18:59 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:18:55] [connect] Successful connection Oct 27 22:19:04 volumio-pi3 volumio[9007]: info: TidalConnect service stoped! Oct 27 22:19:10 volumio-pi3 volumiologrotate[627]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 27 22:19:10 volumio-pi3 volumiologrotate[627]: ls: cannot access 'PI3': No such file or directory Oct 27 22:19:10 volumio-pi3 dbus-daemon[622]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30232ms) Oct 27 22:19:10 volumio-pi3 dbus-daemon[622]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30168ms) Oct 27 22:19:11 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:19:11] [connect] Successful connection Oct 27 22:19:11 volumio-pi3 volumio[9007]: info: Bluetooth name changed to Volumio Pi3 Oct 27 22:19:11 volumio-pi3 volumio[9007]: info: MPD Permissions set Oct 27 22:19:13 volumio-pi3 volumio[9007]: info: Discovery: Disconnected from remote: 192.168.1.142 Oct 27 22:19:13 volumio-pi3 volumio[9007]: info: MRS: Cleaning up device: 306e9cac-cf03-4b8f-acb1-b88088308223 Oct 27 22:19:13 volumio-pi3 volumio[9007]: info: Removing audio output: Oct 27 22:19:13 volumio-pi3 volumio[9007]: info: Discovery: Disconnected from remote: 192.168.1.142 Oct 27 22:19:28 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:19:27] [connect] Successful connection Oct 27 22:19:41 volumio-pi3 volumio[9007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 22:19:44 volumio-pi3 volumio[9007]: Error: write EPIPE Oct 27 22:19:44 volumio-pi3 volumio[9007]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at writeGeneric (internal/stream_base_commons.js:147:3) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at Socket._writeGeneric (net.js:785:11) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at Socket._write (net.js:797:8) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at writeOrBuffer (internal/streams/writable.js:358:12) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at Socket.Writable.write (internal/streams/writable.js:303:10) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:82:16 Oct 27 22:19:44 volumio-pi3 volumio[9007]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at Socket.emit (events.js:315:20) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at emitReadable_ (internal/streams/readable.js:569:12) Oct 27 22:19:44 volumio-pi3 volumio[9007]: at processTicksAndRejections (internal/process/task_queues.js:79:21) { Oct 27 22:19:44 volumio-pi3 volumio[9007]: errno: -32, Oct 27 22:19:44 volumio-pi3 volumio[9007]: code: 'EPIPE', Oct 27 22:19:44 volumio-pi3 volumio[9007]: syscall: 'write' Oct 27 22:19:44 volumio-pi3 volumio[9007]: } Oct 27 22:19:44 volumio-pi3 volumio[9007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 22:19:44 volumio-pi3 volumio-remote-updater[629]: [2024-10-27 22:19:44] [connect] Successful connection Oct 27 22:19:45 volumio-pi3 systemd[1]: mpd.service: Start operation timed out. Terminating. Oct 27 22:19:46 volumio-pi3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 27 22:19:46 volumio-pi3 systemd[1]: mpd.service: Failed with result 'timeout'. Oct 27 22:19:46 volumio-pi3 systemd[1]: Failed to start Music Player Daemon. Oct 27 22:19:47 volumio-pi3 sudo[9635]: pam_unix(sudo:session): session closed for user root Oct 27 22:19:54 volumio-pi3 sudo[9813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 22:18 Oct 27 22:19:54 volumio-pi3 sudo[9813]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"