-- 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"