-- Logs begin at Mon 2024-07-22 17:22:30 UTC, end at Sat 2024-08-31 16:31:25 UTC. -- Aug 31 16:30:00 volumio sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Aug 31 16:30:00 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:01 volumio volumio[841]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 16:30:01 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 16:30:01 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 31 16:30:04 volumio kernel: Bluetooth: Core ver 2.22 Aug 31 16:30:04 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Aug 31 16:30:04 volumio kernel: Bluetooth: HCI device and connection manager initialized Aug 31 16:30:04 volumio kernel: Bluetooth: HCI socket layer initialized Aug 31 16:30:04 volumio kernel: Bluetooth: L2CAP socket layer initialized Aug 31 16:30:04 volumio kernel: Bluetooth: SCO socket layer initialized Aug 31 16:30:04 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:06 volumio volumio[841]: info: Adding METAVOLUMIO REST API Endpoints Aug 31 16:30:06 volumio volumio[841]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 31 16:30:06 volumio volumio[841]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 31 16:30:06 volumio volumio[841]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 31 16:30:06 volumio volumio[841]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 31 16:30:06 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 31 16:30:06 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 31 16:30:06 volumio volumio[841]: info: Preparing CD Folders Aug 31 16:30:06 volumio volumio[841]: info: Adding CD REST API Endpoints Aug 31 16:30:06 volumio volumio[841]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 31 16:30:06 volumio volumio[841]: info: Starting UDEV Watcher for CD Aug 31 16:30:06 volumio volumio[841]: info: Detecting CD presence with UDEV Aug 31 16:30:06 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 31 16:30:07 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 31 16:30:07 volumio volumio[841]: info: Adding inputs REST Endpoints Aug 31 16:30:07 volumio volumio[841]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 31 16:30:07 volumio volumio[841]: info: Scanning Audio Inputs Aug 31 16:30:10 volumio volumio[841]: info: Checking against Known Cards name Aug 31 16:30:10 volumio volumio[841]: info: Adding Server instance for streaming Aug 31 16:30:11 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 31 16:30:13 volumio volumio-remote-updater[567]: [2024-08-31 16:30:13] [connect] Successful connection Aug 31 16:30:15 volumio systemd[1]: mpd.service: Succeeded. Aug 31 16:30:15 volumio volumio[841]: error: Hi Res Audio Failed Login: Missing Login Data Aug 31 16:30:15 volumio volumio[841]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 31 16:30:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 16:30:15 volumio volumio[841]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 31 16:30:15 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 31 16:30:15 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 31 16:30:15 volumio volumio[841]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 31 16:30:15 volumio systemd[1]: Starting Music Player Daemon... Aug 31 16:30:18 volumio sudo[1863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 16:30:18 volumio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 16:30:18 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:18 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:18 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:18 volumio volumio[841]: info: Stopping AccessToken refresher cron Aug 31 16:30:18 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:20 volumio volumio[841]: info: AccessToken refresher cron started Aug 31 16:30:20 volumio volumio[841]: info: Stopping AccessToken refresher cron for QOBUZ Aug 31 16:30:20 volumio volumio[841]: info: AccessToken refresher cron started for QOBUZ Aug 31 16:30:20 volumio volumio[841]: info: Adding TIDAL REST API Endpoints Aug 31 16:30:20 volumio volumio[841]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Aug 31 16:30:20 volumio volumio[841]: info: Adding QOBUZ REST API Endpoints Aug 31 16:30:20 volumio volumio[841]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Aug 31 16:30:22 volumio volumio[841]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.239 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 13 Aug 31 16:30:22 volumio volumio[841]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.239 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 14 Aug 31 16:30:23 volumio volumio[841]: verbose: New Socket.io Connection to 192.168.1.146 from 192.168.1.239 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 15 Aug 31 16:30:24 volumio volumio[841]: info: MRS: Getting audio outputs on start Aug 31 16:30:24 volumio volumio[841]: info: MRS: Requesting all other devices output Aug 31 16:30:24 volumio volumio[841]: error: error Aug 31 16:30:24 volumio volumio[841]: error: error Aug 31 16:30:24 volumio volumio[841]: error: error Aug 31 16:30:25 volumio volumio[841]: error: error Aug 31 16:30:25 volumio volumio[841]: error: error Aug 31 16:30:25 volumio volumio[841]: error: error Aug 31 16:30:28 volumio sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 31 16:30:28 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:29 volumio volumio-remote-updater[567]: [2024-08-31 16:30:28] [connect] Successful connection Aug 31 16:30:30 volumio systemd[1]: Started Volumio Bluetooth Module. Aug 31 16:30:30 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:32 volumio sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Aug 31 16:30:32 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:32 volumio volumiobt[1878]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Aug 31 16:30:32 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:34 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Aug 31 16:30:34 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:34 volumio volumiobt[1878]: pulseaudio: no process found Aug 31 16:30:34 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:37 volumio dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.18' (uid=1000 pid=1890 comm="/usr/bin/pulseaudio --high-priority=true ") Aug 31 16:30:39 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 31 16:30:40 volumio dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 31 16:30:40 volumio rtkit-daemon[1903]: Successfully called chroot. Aug 31 16:30:40 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 31 16:30:40 volumio rtkit-daemon[1903]: Successfully dropped privileges. Aug 31 16:30:40 volumio rtkit-daemon[1903]: Successfully limited resources. Aug 31 16:30:40 volumio rtkit-daemon[1903]: Running. Aug 31 16:30:40 volumio rtkit-daemon[1903]: Watchdog thread running. Aug 31 16:30:40 volumio rtkit-daemon[1903]: Canary thread running. Aug 31 16:30:40 volumio dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.19' (uid=0 pid=1903 comm="/usr/lib/rtkit/rtkit-daemon ") Aug 31 16:30:40 volumio volumio[841]: info: TidalConnect service stoped! Aug 31 16:30:40 volumio systemd[1]: Starting Authorization Manager... Aug 31 16:30:42 volumio sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 31 16:30:42 volumio sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 16:30:42 volumio polkitd[1920]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 31 16:30:42 volumio dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 31 16:30:42 volumio systemd[1]: Started Authorization Manager. Aug 31 16:30:43 volumio volumio[841]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio' Aug 31 16:30:43 volumio volumio[841]: Can't get device info: No such device Aug 31 16:30:43 volumio volumio[841]: info: MPD Permissions set Aug 31 16:30:44 volumio volumio-remote-updater[567]: [2024-08-31 16:30:44] [connect] Successful connection Aug 31 16:30:45 volumio systemd[1]: Started MyVolumio SSH Tunnel. Aug 31 16:30:45 volumio sudo[1908]: pam_unix(sudo:session): session closed for user root Aug 31 16:30:46 volumio autossh[1944]: port set to 0, monitoring disabled Aug 31 16:30:46 volumio autossh[1944]: starting ssh (count 1) Aug 31 16:30:46 volumio autossh[1944]: ssh child pid is 1947 Aug 31 16:30:48 volumio dbus-daemon[428]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.39' (uid=1000 pid=1890 comm="/usr/bin/pulseaudio --high-priority=true ") Aug 31 16:30:48 volumio pulseaudio[1890]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Aug 31 16:30:48 volumio pulseaudio[1890]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Aug 31 16:30:48 volumio pulseaudio[1890]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Aug 31 16:30:48 volumio pulseaudio[1890]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Aug 31 16:30:49 volumio systemd[1]: Starting Bluetooth service... Aug 31 16:30:49 volumio volumiossh-tunnel[1943]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Aug 31 16:30:49 volumio pulseaudio[1890]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 31 16:30:49 volumio pulseaudio[1890]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 31 16:30:50 volumio bluetoothd[1949]: Bluetooth daemon 5.50 Aug 31 16:30:50 volumio volumiobt[1878]: Applying permissions Aug 31 16:30:50 volumio volumiobt[1878]: Setting BT discoverable and pairable Aug 31 16:30:50 volumio bluetoothd[1949]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 31 16:30:50 volumio dbus-daemon[428]: [system] Successfully activated service 'org.bluez' Aug 31 16:30:50 volumio bluetoothd[1949]: Starting SDP server Aug 31 16:30:50 volumio bluetoothd[1949]: Excluding (cli) sap Aug 31 16:30:50 volumio systemd[1]: Started Bluetooth service. Aug 31 16:30:51 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 31 16:30:51 volumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 31 16:30:51 volumio kernel: Bluetooth: BNEP socket layer initialized Aug 31 16:30:51 volumio bluetoothd[1949]: Bluetooth management interface 1.22 initialized Aug 31 16:30:51 volumio dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.40' (uid=0 pid=1949 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# -e power on Aug 31 16:30:51 volumio volumiobt[1878]: Invalid command in menu main: -e Aug 31 16:30:51 volumio volumiobt[1878]: Aug 31 16:30:51 volumio volumiobt[1878]: Use "help" for a list of available commands in a menu. Aug 31 16:30:51 volumio volumiobt[1878]: Use "menu " if you want to enter any submenu. Aug 31 16:30:51 volumio volumiobt[1878]: Use "back" if you want to return to menu main. Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# agent on Aug 31 16:30:51 volumio volumiobt[1878]: Failed to register agent object Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# discoverable on Aug 31 16:30:51 volumio volumiobt[1878]: No default controller available Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# pairable on Aug 31 16:30:51 volumio volumiobt[1878]: No default controller available Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# agent NoInputNoOutput Aug 31 16:30:51 volumio volumiobt[1878]: Failed to register agent object Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# default-agent Aug 31 16:30:51 volumio volumiobt[1878]: No agent is registered Aug 31 16:30:51 volumio volumiobt[1878]: [bluetooth]# quit Aug 31 16:30:51 volumio pulseaudio[1890]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Aug 31 16:30:51 volumio volumiobt[1878]: [59B blob data] Aug 31 16:30:53 volumio systemd[1]: Starting Hostname Service... Aug 31 16:30:56 volumio dbus-daemon[428]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30022ms) Aug 31 16:30:56 volumio dbus-daemon[428]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30012ms) Aug 31 16:30:58 volumio dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 31 16:30:58 volumio systemd[1]: Started Hostname Service. Aug 31 16:30:59 volumio volumio-remote-updater[567]: [2024-08-31 16:30:59] [connect] Successful connection Aug 31 16:31:03 volumio volumio[841]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:31:04 volumio volumio[841]: Error: write EPIPE Aug 31 16:31:04 volumio volumio[841]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Aug 31 16:31:04 volumio volumio[841]: at writeGeneric (internal/stream_base_commons.js:147:3) Aug 31 16:31:04 volumio volumio[841]: at Socket._writeGeneric (net.js:785:11) Aug 31 16:31:04 volumio volumio[841]: at Socket._write (net.js:797:8) Aug 31 16:31:04 volumio volumio[841]: at writeOrBuffer (internal/streams/writable.js:358:12) Aug 31 16:31:04 volumio volumio[841]: at Socket.Writable.write (internal/streams/writable.js:303:10) Aug 31 16:31:04 volumio volumio[841]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:82:16 Aug 31 16:31:04 volumio volumio[841]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Aug 31 16:31:04 volumio volumio[841]: at Socket.emit (events.js:315:20) Aug 31 16:31:04 volumio volumio[841]: at emitReadable_ (internal/streams/readable.js:569:12) Aug 31 16:31:04 volumio volumio[841]: at processTicksAndRejections (internal/process/task_queues.js:79:21) { Aug 31 16:31:04 volumio volumio[841]: errno: -32, Aug 31 16:31:04 volumio volumio[841]: code: 'EPIPE', Aug 31 16:31:04 volumio volumio[841]: syscall: 'write' Aug 31 16:31:04 volumio volumio[841]: } Aug 31 16:31:05 volumio volumio[841]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:31:15 volumio volumio-remote-updater[567]: [2024-08-31 16:31:15] [connect] Successful connection Aug 31 16:31:24 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 16:30 Aug 31 16:31:24 volumio sudo[1969]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"