-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-03-31 09:57:20 CEST. -- Mar 31 09:56:00 volumio volumio[767]: 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 Total Clients: 23 Mar 31 09:56:01 volumio volumio[767]: info: Setting Geolocation for MyVolumio to eu4 Mar 31 09:56:01 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:01 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:01 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:01 volumio volumio[767]: info: Setting Geolocation for MyVolumio to eu4 Mar 31 09:56:01 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:01 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:01 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:02 volumio volumio[767]: info: Remote config written successfully Mar 31 09:56:02 volumio volumio[767]: info: Starting Tunnel 1 Mar 31 09:56:02 volumio volumio[767]: info: Starting Tunnel Connection Checker Mar 31 09:56:02 volumio volumio[767]: 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 Total Clients: 24 Mar 31 09:56:03 volumio volumio[767]: info: Updating MyVolumio device info Mar 31 09:56:03 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:03 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:03 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:03 volumio volumio[767]: info: Updating MyVolumio device info Mar 31 09:56:03 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:03 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:03 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 09:56:03 volumio volumio[767]: info: MYVolumio Device enabled Mar 31 09:56:03 volumio volumio[767]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 31 09:56:03 volumio volumio[767]: info: MyVolumio status changed Mar 31 09:56:03 volumio volumio[767]: info: Streaming services startup Mar 31 09:56:03 volumio volumio[767]: info: Starting Streaming Daemon Mar 31 09:56:04 volumio sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 31 09:56:04 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:56:04 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Mar 31 09:56:06 volumio volumio[767]: info: Successfully Added MyVolumio device Mar 31 09:56:06 volumio volumio[767]: info: Successfully Added MyVolumio device Mar 31 09:56:06 volumio volumio[767]: info: CoreCommandRouter::volumioGetState Mar 31 09:56:06 volumio volumio[767]: info: CorePlayQueue::getTrack 0 Mar 31 09:56:06 volumio volumio[767]: error: Cannot start Volumio Streaming Daemon Mar 31 09:56:06 volumio volumio[767]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 31 09:56:06 volumio volumio[767]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 31 09:56:07 volumio volumio[767]: 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 Total Clients: 25 Mar 31 09:56:07 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 31 09:56:07 volumio volumio[767]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 31 09:56:08 volumio volumio[767]: info: [1711871768027] Starting BluetoothController Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 31 09:56:08 volumio volumio[767]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 31 09:56:08 volumio volumio[767]: info: Adding Manifest REST API Endpoints Mar 31 09:56:08 volumio volumio[767]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 31 09:56:08 volumio volumio[767]: info: Refreshing TIDAL token Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 31 09:56:08 volumio volumio[767]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 31 09:56:08 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Mar 31 09:56:08 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:56:08 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Mar 31 09:56:08 volumio volumio[767]: info: Stopping AccessToken refresher cron for QOBUZ Mar 31 09:56:08 volumio sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 09:56:08 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:56:08 volumio volumio[767]: info: AccessToken refresher cron started for QOBUZ Mar 31 09:56:08 volumio volumio[767]: info: Adding QOBUZ REST API Endpoints Mar 31 09:56:08 volumio volumio[767]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 31 09:56:08 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root Mar 31 09:56:09 volumio volumio[767]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:56:09 volumio volumio[767]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 09:56:09 volumio volumio[767]: info: Retrieving Cloud Streaming UI Mar 31 09:56:09 volumio volumio[767]: info: Bluetooth name changed to Volumio Mar 31 09:56:18 volumio volumio[767]: info: Successfully Updated MyVolumio device Mar 31 09:56:18 volumio volumio[767]: info: Successfully Updated MyVolumio device Mar 31 09:56:18 volumio sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 31 09:56:18 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:56:18 volumio systemd[1]: Started Volumio Bluetooth Module. Mar 31 09:56:18 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root Mar 31 09:56:18 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 31 09:56:18 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:56:18 volumio volumiobt[1776]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 31 09:56:18 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Mar 31 09:56:19 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 31 09:56:19 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 09:56:19 volumio volumiobt[1776]: pulseaudio: no process found Mar 31 09:56:19 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Mar 31 09:56:19 volumio dbus-daemon[564]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.18' (uid=1000 pid=1784 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 31 09:56:19 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 31 09:56:19 volumio dbus-daemon[564]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 31 09:56:19 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 31 09:56:19 volumio rtkit-daemon[1785]: Successfully called chroot. Mar 31 09:56:19 volumio rtkit-daemon[1785]: Successfully dropped privileges. Mar 31 09:56:19 volumio rtkit-daemon[1785]: Successfully limited resources. Mar 31 09:56:19 volumio rtkit-daemon[1785]: Running. Mar 31 09:56:19 volumio rtkit-daemon[1785]: Canary thread running. Mar 31 09:56:19 volumio rtkit-daemon[1785]: Watchdog thread running. Mar 31 09:56:19 volumio dbus-daemon[564]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.19' (uid=0 pid=1785 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 31 09:56:19 volumio systemd[1]: Starting Authorization Manager... Mar 31 09:56:19 volumio polkitd[1788]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 31 09:56:19 volumio dbus-daemon[564]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 31 09:56:19 volumio systemd[1]: Started Authorization Manager. Mar 31 09:56:19 volumio pulseaudio[1784]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 31 09:56:19 volumio pulseaudio[1784]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 31 09:56:19 volumio volumiobt[1776]: Applying permissions Mar 31 09:56:19 volumio volumiobt[1776]: Setting BT discoverable and pairable Mar 31 09:56:19 volumio bluetoothd[713]: Endpoint registered: sender=:1.39 path=/MediaEndpoint/A2DPSource Mar 31 09:56:19 volumio bluetoothd[713]: Endpoint registered: sender=:1.39 path=/MediaEndpoint/A2DPSink Mar 31 09:56:19 volumio pulseaudio[1784]: 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 Mar 31 09:56:19 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Mar 31 09:56:19 volumio kernel: Bluetooth: RFCOMM socket layer initialized Mar 31 09:56:19 volumio kernel: Bluetooth: RFCOMM ver 1.11 Mar 31 09:56:19 volumio volumiobt[1776]: [CHG] Controller E4:5F:01:06:52:14 Class: 0x0008041c Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [55B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [91B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [117B blob data] Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# -e power on Mar 31 09:56:19 volumio volumiobt[1776]: Invalid command in menu main: -e Mar 31 09:56:19 volumio volumiobt[1776]: Mar 31 09:56:19 volumio volumiobt[1776]: Use "help" for a list of available commands in a menu. Mar 31 09:56:19 volumio volumiobt[1776]: Use "menu " if you want to enter any submenu. Mar 31 09:56:19 volumio volumiobt[1776]: Use "back" if you want to return to menu main. Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# agent on Mar 31 09:56:19 volumio volumiobt[1776]: Agent is already registered Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# discoverable on Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# pairable on Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# agent NoInputNoOutput Mar 31 09:56:19 volumio volumiobt[1776]: Agent is already registered Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# default-agent Mar 31 09:56:19 volumio volumiobt[1776]: [bluetooth]# quit Mar 31 09:56:19 volumio volumiobt[1776]: [59B blob data] Mar 31 09:56:20 volumio volumio[767]: Cannot compose Albumart path Mar 31 09:56:21 volumio volumio[767]: Cannot compose Albumart path Mar 31 09:56:48 volumio dbus-daemon[564]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30004ms) Mar 31 09:56:48 volumio dbus-daemon[564]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30001ms) Mar 31 09:56:52 volumio volumio[767]: Cannot compose Albumart path Mar 31 09:56:56 volumio volumio[767]: Cannot compose Albumart path Mar 31 09:56:58 volumio volumio[767]: Cannot compose Albumart path Mar 31 09:57:09 volumio volumio[767]: Cannot compose Albumart path Mar 31 09:57:14 volumio volumio-remote-updater[557]: [2024-03-31 09:57:14] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 31 09:57:19 volumio volumio-remote-updater[557]: [2024-03-31 09:57:19] [connect] Successful connection Mar 31 09:57:19 volumio volumio[767]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 09:57:19 volumio volumio[767]: Error: write EPIPE Mar 31 09:57:19 volumio volumio[767]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Mar 31 09:57:19 volumio volumio[767]: at writeGeneric (internal/stream_base_commons.js:147:3) Mar 31 09:57:19 volumio volumio[767]: at Socket._writeGeneric (net.js:785:11) Mar 31 09:57:19 volumio volumio[767]: at Socket.connect (net.js:767:12) Mar 31 09:57:19 volumio volumio[767]: at Object.onceWrapper (events.js:421:28) Mar 31 09:57:19 volumio volumio[767]: at Socket.emit (events.js:327:22) Mar 31 09:57:19 volumio volumio[767]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1132:10) { Mar 31 09:57:19 volumio volumio[767]: errno: -32, Mar 31 09:57:19 volumio volumio[767]: code: 'EPIPE', Mar 31 09:57:19 volumio volumio[767]: syscall: 'write' Mar 31 09:57:19 volumio volumio[767]: } Mar 31 09:57:19 volumio volumio[767]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 09:57:20 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 09:56 Mar 31 09:57:20 volumio sudo[1854]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"