Jan 27 23:07:00 volumio volumio[1188]: info: MyVolumio Plan changed: virtuoso Jan 27 23:07:00 volumio volumio[1188]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso Jan 27 23:07:00 volumio volumio[1188]: info: Removing browser output: myVolumio user plan is not superstar Jan 27 23:07:00 volumio volumio[1188]: info: Removing audio output: Jan 27 23:07:00 volumio volumio[1188]: info: MYVOLUMIO: Adding device Jan 27 23:07:00 volumio volumio[1188]: info: MYVOLUMIO: Evaluating Server Jan 27 23:07:00 volumio volumio[1188]: info: Setting Geolocation for MyVolumio to us1 Jan 27 23:07:00 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:00 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:00 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:01 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:02 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:02 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 23:07:02 volumio volumio[1188]: info: Updating MyVolumio device info Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:02 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:02 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:02 volumio volumio[1188]: info: Successfully Added MyVolumio device Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:02 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Jan 27 23:07:02 volumio volumio[1188]: info: Setting Geolocation for MyVolumio to us1 Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:02 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:02 volumio volumio[1188]: info: Remote config written successfully Jan 27 23:07:02 volumio volumio[1188]: info: Starting Tunnel 1 Jan 27 23:07:02 volumio volumio[1188]: info: Starting Tunnel Connection Checker Jan 27 23:07:02 volumio volumio[1188]: info: Completed starting MyVolumio Plugin Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 23:07:02 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 23:07:02 volumio volumio[1188]: info: MYVolumio Device enabled Jan 27 23:07:02 volumio volumio[1188]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 27 23:07:02 volumio volumio[1188]: info: MyVolumio status changed Jan 27 23:07:02 volumio volumio[1188]: info: Streaming services startup Jan 27 23:07:02 volumio volumio[1188]: info: Starting Streaming Daemon Jan 27 23:07:03 volumio sudo[2000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 27 23:07:03 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:03 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:03 volumio sudo[2000]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:03 volumio volumio[1188]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 27 23:07:03 volumio volumio[1188]: error: Cannot start Volumio Streaming Daemon Jan 27 23:07:03 volumio volumio[1188]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 27 23:07:03 volumio volumio[1188]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 27 23:07:03 volumio volumio[1188]: info: Successfully Updated MyVolumio device Jan 27 23:07:03 volumio volumio[1188]: verbose: New Socket.io Connection to 192.168.50.219:3000 from 192.168.50.83 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 27 23:07:03 volumio kernel: hwmon hwmon1: Voltage normalised Jan 27 23:07:03 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:03 volumio volumio[1188]: info: Updating MyVolumio device info Jan 27 23:07:03 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:03 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:03 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:04 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 23:07:04 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 23:07:04 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 27 23:07:04 volumio volumio[1188]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 27 23:07:05 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jan 27 23:07:05 volumio volumio[1188]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 27 23:07:05 volumio volumio[1188]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 27 23:07:05 volumio volumio[1188]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 27 23:07:06 volumio volumio[1188]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 27 23:07:06 volumio volumio[1188]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 27 23:07:06 volumio volumio[1188]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 27 23:07:06 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jan 27 23:07:06 volumio volumio[1188]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jan 27 23:07:06 volumio volumio[1188]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jan 27 23:07:06 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jan 27 23:07:06 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jan 27 23:07:06 volumio volumio[1188]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 27 23:07:06 volumio volumio[1188]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 27 23:07:06 volumio volumio[1188]: info: Preparing CD Folders Jan 27 23:07:06 volumio volumio[1188]: info: Adding CD REST API Endpoints Jan 27 23:07:06 volumio volumio[1188]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 27 23:07:06 volumio volumio[1188]: info: Starting UDEV Watcher for CD Jan 27 23:07:06 volumio volumio[1188]: info: Detecting CD presence with UDEV Jan 27 23:07:06 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 27 23:07:11 volumio volumio[1188]: warn: [cd-plugin] cdspeedctl: device or media not ready Jan 27 23:07:11 volumio volumio[1188]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 27 23:07:11 volumio volumio[1188]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 27 23:07:11 volumio volumio[1188]: info: Refreshing QOBUZ token Jan 27 23:07:11 volumio volumio[1188]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 27 23:07:11 volumio volumio[1188]: info: Adding TIDAL REST API Endpoints Jan 27 23:07:11 volumio sudo[2028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 23:07:11 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 23:07:11 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 23:07:11 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:11 volumio volumio[1188]: info: Successfully Added MyVolumio device Jan 27 23:07:11 volumio volumio[1188]: info: Successfully Updated MyVolumio device Jan 27 23:07:12 volumio volumio[1188]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Jan 27 23:07:12 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:12 volumio sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 27 23:07:12 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:12 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jan 27 23:07:12 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:12 volumio volumiobt[2037]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jan 27 23:07:12 volumio sudo[2038]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jan 27 23:07:12 volumio sudo[2038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:12 volumio sudo[2038]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:12 volumio sudo[2040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jan 27 23:07:12 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:12 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:12 volumio volumiobt[2042]: INFO [BTSTART] Powering on Bluetooth if needed... Jan 27 23:07:12 volumio volumiobt[2045]: No default controller available Jan 27 23:07:12 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:13 volumio volumio[1188]: verbose: New Socket.io Connection to 192.168.50.219:3000 from 192.168.50.83 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 27 23:07:13 volumio volumio[1188]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jan 27 23:07:13 volumio volumio[1188]: info: CoreCommandRouter::volumioStop Jan 27 23:07:13 volumio volumio[1188]: info: CoreStateMachine::stop Jan 27 23:07:13 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:13 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jan 27 23:07:13 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:13 volumio volumio[1188]: info: Access Token successfully retrieved Jan 27 23:07:13 volumio volumio[1188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 23:07:13 volumio volumio[1188]: info: [1769573233674] CoreMusicLibrary::Adding element QOBUZ Jan 27 23:07:13 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 23:07:13 volumio volumio[1188]: Cannot find translation for source QOBUZ Jan 27 23:07:13 volumio volumiobt[2054]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jan 27 23:07:13 volumio volumio[1188]: info: Stopping AccessToken refresher cron for QOBUZ Jan 27 23:07:13 volumio volumiobt[2055]: [83B blob data] Jan 27 23:07:13 volumio volumiobt[2055]: No default controller available Jan 27 23:07:13 volumio volumiobt[2055]: [bluetoothctl]> pairable on Jan 27 23:07:13 volumio volumiobt[2055]: No default controller available Jan 27 23:07:13 volumio volumiobt[2055]: [113B blob data] Jan 27 23:07:13 volumio volumiobt[2055]: [bluetoothctl]> Jan 27 23:07:13 volumio volumiobt[2056]: INFO [BTSTART] Registering Bluetooth agent... Jan 27 23:07:13 volumio volumiobt[2058]: No agent is registered Jan 27 23:07:14 volumio volumiobt[2059]: INFO [BTSTART] Agent registered successfully. Jan 27 23:07:14 volumio volumiobt[2060]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jan 27 23:07:14 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 27 23:07:14 volumio volumio[1188]: info: AccessToken refresher cron started for QOBUZ Jan 27 23:07:14 volumio volumio[1188]: info: Adding QOBUZ REST API Endpoints Jan 27 23:07:15 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:15 volumio volumiobt[2061]: INFO:a2dp-agent:Connecting to system bus. Jan 27 23:07:16 volumio volumiobt[2061]: ERROR:a2dp-agent:No Bluetooth adapter found: Bluetooth adapter not found Jan 27 23:07:16 volumio volumio[1188]: verbose: New Socket.io Connection to 192.168.50.219:3000 from 192.168.50.83 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 27 23:07:16 volumio volumiobt[2066]: INFO [BTSTART] Disconnecting all Bluetooth devices... Jan 27 23:07:16 volumio volumio[1188]: info: TidalConnect service stoped! Jan 27 23:07:16 volumio volumiobt[2073]: INFO [BTSTART] Unregistering Bluetooth agent... Jan 27 23:07:16 volumio volumiobt[2077]: No agent is registered Jan 27 23:07:16 volumio volumiobt[2082]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Jan 27 23:07:16 volumio sudo[2072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 27 23:07:16 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:16 volumio volumiobt[2085]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Jan 27 23:07:16 volumio systemd[1]: volumiobt.service: Deactivated successfully. Jan 27 23:07:16 volumio systemd[1]: volumiobt.service: Consumed 2.371s CPU time. Jan 27 23:07:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 27 23:07:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 27 23:07:16 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 27 23:07:17 volumio sudo[2072]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:17 volumio autossh[2098]: port set to 0, monitoring disabled Jan 27 23:07:17 volumio autossh[2098]: starting ssh (count 1) Jan 27 23:07:17 volumio autossh[2098]: ssh child pid is 2101 Jan 27 23:07:17 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:17 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:17 volumio volumio[1188]: info: Remote SSH Started Jan 27 23:07:17 volumio volumiossh-tunnel[2101]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 27 23:07:19 volumio volumio[1188]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 27 23:07:19 volumio volumio[1188]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 27 23:07:19 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:19 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:19 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:19 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:19 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:19 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:19 volumio volumio[1188]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 27 23:07:19 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:20 volumio sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 27 23:07:20 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:20 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:20 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 27 23:07:20 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:20 volumio kernel: hwmon hwmon1: Voltage normalised Jan 27 23:07:20 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:20 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:20 volumio volumio[1188]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.hxSfk4EvSxV4PR58VM8WpGvkrvg2.587163c73ccbecf74eebfeb8259d8c28.state.status' Jan 27 23:07:20 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:21 volumio volumio[1188]: info: Executing endpoint tc_getconfig Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 27 23:07:21 volumio vtcs[2117]: STARTING TidalConnect services, version: 1.5.2.56 Jan 27 23:07:21 volumio vtcs[2117]: STARTED TidalConnect services. Jan 27 23:07:21 volumio volumio[1188]: info: Executing endpoint tc_connect Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 27 23:07:21 volumio volumio[1188]: info: Connecting to TidalConnect Jan 27 23:07:21 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:21 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:21 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Jan 27 23:07:21 volumio volumio[1188]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:21 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:21 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:22 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Jan 27 23:07:22 volumio volumio[1188]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Jan 27 23:07:22 volumio volumio[1188]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.hxSfk4EvSxV4PR58VM8WpGvkrvg2.587163c73ccbecf74eebfeb8259d8c28.state.status' Jan 27 23:07:22 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:22 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:22 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:23 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:23 volumio volumio[1188]: info: TidalConnect service started! Jan 27 23:07:23 volumio volumio[1188]: [Metrics] CommandRouter: 86s 755.29ms Jan 27 23:07:23 volumio volumio[1188]: info: CoreCommandRouter::volumiosetStartupVolume Jan 27 23:07:23 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 23:07:23 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 23:07:23 volumio sudo[2151]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 23:07:23 volumio volumio[1188]: info: CoreCommandRouter::Close All Modals sent Jan 27 23:07:23 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:23 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:23 volumio sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 23:07:23 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:23 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:23 volumio volumio[1188]: info: CoreCommandRouter::Close All Modals sent Jan 27 23:07:23 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:23 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:24 volumio volumio[1188]: verbose: New Socket.io Connection to 192.168.50.219 from 192.168.50.83 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 11 Jan 27 23:07:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 27 23:07:24 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 27 23:07:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 23:07:25 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 27 23:07:25 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:25 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:25 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:26 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:26 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:26 volumio sudo[2177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 23:07:26 volumio sudo[2177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:26 volumio sudo[2177]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:26 volumio sudo[2180]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 23:07:26 volumio sudo[2180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:07:26 volumio sudo[2180]: pam_unix(sudo:session): session closed for user root Jan 27 23:07:26 volumio volumio[1188]: verbose: New Socket.io Connection to 192.168.50.219 from 192.168.50.83 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 12 Jan 27 23:07:26 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 27 23:07:27 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::volumioGetVisibleSources Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 27 23:07:27 volumio volumio[1188]: info: Received Get System Info Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:27 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:27 volumio volumio[1188]: info: Listing playlists Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 27 23:07:27 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 23:07:28 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:28 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:28 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 23:07:28 volumio volumio[1188]: info: Received Get System Info Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:28 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 23:07:28 volumio volumio[1188]: info: Received Get System Info Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:28 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:28 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:28 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:28 volumio kernel: hwmon hwmon1: Voltage normalised Jan 27 23:07:28 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:29 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:29 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:29 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 27 23:07:29 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:29 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:29 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: info: BOOT COMPLETED Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:30 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:31 volumio volumio[1188]: info: CoreCommandRouter::volumioGetQueue Jan 27 23:07:31 volumio volumio[1188]: info: CoreStateMachine::getQueue Jan 27 23:07:31 volumio volumio[1188]: info: CorePlayQueue::getQueue Jan 27 23:07:31 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:31 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:31 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:31 volumio volumio[1188]: error: touch_display: Connecting to the Xserver failed. Jan 27 23:07:32 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:32 volumio volumio[1188]: error: touch_display: Xserver unix domain socket cannot be determined. Jan 27 23:07:32 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Jan 27 23:07:33 volumio volumio[1188]: verbose: UNSET VOLATILE: Service: undefined Jan 27 23:07:33 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jan 27 23:07:33 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jan 27 23:07:33 volumio volumio[1188]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::play index 1 Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:33 volumio volumio[1188]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::stop Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::resetVolumioState Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::getcurrentVolume Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::play index undefined Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::volumioStop Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::stop Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:33 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Jan 27 23:07:33 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:33 volumio volumio[1188]: info: [1769573253401] ControllerQobuz::clearAddPlayTrack Jan 27 23:07:33 volumio volumio[1188]: info: VolumeController:: Volume=100 Mute =false Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:33 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:33 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:33 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Jan 27 23:07:33 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Jan 27 23:07:34 volumio volumio[1188]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 23:07:34 volumio volumio[1188]: info: getStreamUrl took 1122 milliseconds Jan 27 23:07:34 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:07:34 volumio volumio[1188]: info: sendMpdCommand stop took 141 milliseconds Jan 27 23:07:34 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 23:07:34 volumio volumio[1188]: info: VolumeController:: Volume=100 Mute =false Jan 27 23:07:34 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:34 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:34 volumio upmpdcli[1634]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 27 23:07:34 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:34 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:34 volumio volumio[1188]: info: Jan 27 23:07:34 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:34 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:34 volumio volumio[1188]: info: sendMpdCommand clear took 240 milliseconds Jan 27 23:07:34 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ" Jan 27 23:07:34 volumio volumio[1188]: info: Jan 27 23:07:34 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:34 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:34 volumio volumio[1188]: info: Jan 27 23:07:34 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:34 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:35 volumio volumio[1188]: error: updateQueue error: null Jan 27 23:07:35 volumio volumio[1188]: info: ------------------------------ 194ms Jan 27 23:07:36 volumio volumio[1188]: error: updateQueue error: null Jan 27 23:07:36 volumio volumio[1188]: error: updateQueue error: null Jan 27 23:07:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ" Jan 27 23:07:36 volumio volumio[1188]: info: ------------------------------ 1306ms Jan 27 23:07:36 volumio volumio[1188]: info: ------------------------------ 1304ms Jan 27 23:07:36 volumio volumio[1188]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ" took 4 milliseconds Jan 27 23:07:36 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:07:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Jan 27 23:07:36 volumio volumio[1188]: info: Jan 27 23:07:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:36 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:36 volumio volumio[1188]: info: sendMpdCommand play took 10 milliseconds Jan 27 23:07:36 volumio volumio[1188]: info: Jan 27 23:07:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:36 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:36 volumio volumio[1188]: info: Jan 27 23:07:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:36 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:36 volumio volumio[1188]: info: ------------------------------ 62ms Jan 27 23:07:36 volumio volumio[1188]: info: ------------------------------ 61ms Jan 27 23:07:36 volumio volumio[1188]: info: ------------------------------ 26ms Jan 27 23:07:36 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 27 23:07:36 volumio volumio[1188]: info: Jan 27 23:07:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:36 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:36 volumio volumio[1188]: info: Jan 27 23:07:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:36 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:37 volumio volumio[1188]: info: Jan 27 23:07:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:37 volumio volumio[1188]: info: Jan 27 23:07:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:37 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand status took 151 milliseconds Jan 27 23:07:37 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand status took 214 milliseconds Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:37 volumio volumio[1188]: info: Jan 27 23:07:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:37 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:37 volumio volumio[1188]: info: Jan 27 23:07:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:37 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand status took 269 milliseconds Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand status took 119 milliseconds Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 27 23:07:37 volumio volumio[1188]: info: sendMpdCommand status took 53 milliseconds Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:37 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":410,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","trackType":"qobuz"} Jan 27 23:07:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Jan 27 23:07:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Jan 27 23:07:37 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":410,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","trackType":"qobuz"} Jan 27 23:07:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Jan 27 23:07:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:37 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:37 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:37 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:37 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:37 volumio volumio[1188]: info: ------------------------------ 968ms Jan 27 23:07:38 volumio volumio[1188]: info: ------------------------------ 1096ms Jan 27 23:07:38 volumio volumio[1188]: info: sendMpdCommand status took 793 milliseconds Jan 27 23:07:38 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 758 milliseconds Jan 27 23:07:38 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 762 milliseconds Jan 27 23:07:38 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 626 milliseconds Jan 27 23:07:38 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:38 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:38 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:38 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:38 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:38 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:38 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:38 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":410,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"923 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","trackType":"qobuz"} Jan 27 23:07:38 volumio volumio[1188]: verbose: CURRENT POSITION 1 Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:38 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:38 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:38 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:38 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":410,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"923 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","trackType":"qobuz"} Jan 27 23:07:38 volumio volumio[1188]: verbose: CURRENT POSITION 1 Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:38 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:38 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:38 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:38 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":410,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"923 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","trackType":"qobuz"} Jan 27 23:07:38 volumio volumio[1188]: verbose: CURRENT POSITION 1 Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:38 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:38 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:38 volumio volumio[1188]: info: ------------------------------ 1928ms Jan 27 23:07:38 volumio volumio[1188]: info: ------------------------------ 1931ms Jan 27 23:07:38 volumio volumio[1188]: info: ------------------------------ 1700ms Jan 27 23:07:40 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 2323 milliseconds Jan 27 23:07:40 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:40 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:40 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:40 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:40 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":410,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"923 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=16304049&fmt=6&profile=raw&app_id=539451548&cid=1190793&etsp=1769576853&hmac=E4gnBBWbCsDyRF5TO0VRopfB1OQ","trackType":"qobuz"} Jan 27 23:07:40 volumio volumio[1188]: verbose: CURRENT POSITION 1 Jan 27 23:07:40 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:40 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:40 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:40 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:40 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:40 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:40 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:40 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:40 volumio volumio[1188]: info: ------------------------------ 3274ms Jan 27 23:07:44 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:44 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jan 27 23:07:44 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::play index 3 Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::stop Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Jan 27 23:07:44 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:44 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:44 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:44 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::serviceStop Jan 27 23:07:44 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Jan 27 23:07:44 volumio volumio[1188]: info: CoreCommandRouter::serviceStop Jan 27 23:07:44 volumio volumio[1188]: info: [1769573264391] ControllerQobuz::stop Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:07:44 volumio volumio[1188]: info: ControllerMpd::stop Jan 27 23:07:44 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:07:44 volumio volumio[1188]: info: Jan 27 23:07:44 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:44 volumio volumio[1188]: info: sendMpdCommand stop took 321 milliseconds Jan 27 23:07:44 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:44 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::play index undefined Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 23:07:44 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:44 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Jan 27 23:07:44 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:44 volumio volumio[1188]: info: [1769573264791] ControllerQobuz::clearAddPlayTrack Jan 27 23:07:44 volumio volumio[1188]: info: sendMpdCommand status took 179 milliseconds Jan 27 23:07:44 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:44 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:44 volumio volumio[1188]: info: Jan 27 23:07:44 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:44 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:44 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:44 volumio volumio[1188]: info: Jan 27 23:07:44 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:44 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:44 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:45 volumio volumio[1188]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Jan 27 23:07:45 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 244 milliseconds Jan 27 23:07:45 volumio volumio[1188]: info: sendMpdCommand status took 165 milliseconds Jan 27 23:07:45 volumio volumio[1188]: info: sendMpdCommand status took 146 milliseconds Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:45 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:45 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:07:45 volumio volumio[1188]: info: ------------------------------ 622ms Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 23:07:45 volumio volumio[1188]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 27 23:07:45 volumio volumio[1188]: info: Received Get System Version Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 23:07:45 volumio volumio[1188]: info: Received Get System Info Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 23:07:45 volumio volumio[1188]: info: Discovery: Getting this device information Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 23:07:45 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 547 milliseconds Jan 27 23:07:45 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 587 milliseconds Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:45 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:45 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:45 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:07:45 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:45 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:45 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:45 volumio volumio[1188]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 27 23:07:45 volumio volumio[1188]: info: ------------------------------ 984ms Jan 27 23:07:45 volumio volumio[1188]: info: ------------------------------ 966ms Jan 27 23:07:46 volumio volumio[1188]: info: getStreamUrl took 1513 milliseconds Jan 27 23:07:46 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 23:07:46 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Jan 27 23:07:46 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 23:07:46 volumio volumio[1188]: info: Jan 27 23:07:46 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:46 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:46 volumio volumio[1188]: info: sendMpdCommand clear took 18 milliseconds Jan 27 23:07:46 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s" Jan 27 23:07:46 volumio volumio[1188]: info: Jan 27 23:07:46 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:46 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:46 volumio volumio[1188]: info: Jan 27 23:07:46 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:46 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:46 volumio volumio[1188]: error: updateQueue error: null Jan 27 23:07:46 volumio volumio[1188]: info: ------------------------------ 30ms Jan 27 23:07:47 volumio kernel: hwmon hwmon1: Voltage normalised Jan 27 23:07:47 volumio volumio[1188]: error: updateQueue error: null Jan 27 23:07:47 volumio volumio[1188]: error: updateQueue error: null Jan 27 23:07:47 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s" Jan 27 23:07:47 volumio volumio[1188]: info: ------------------------------ 1252ms Jan 27 23:07:47 volumio volumio[1188]: info: ------------------------------ 1250ms Jan 27 23:07:47 volumio volumio[1188]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s" took 18 milliseconds Jan 27 23:07:47 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:07:47 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Jan 27 23:07:47 volumio volumio[1188]: info: Jan 27 23:07:47 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:47 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:47 volumio volumio[1188]: info: Jan 27 23:07:47 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:47 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:47 volumio volumio[1188]: info: Jan 27 23:07:47 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Jan 27 23:07:47 volumio volumio[1188]: info: Ignoring MPD Status Update Jan 27 23:07:47 volumio volumio[1188]: info: sendMpdCommand play took 69 milliseconds Jan 27 23:07:47 volumio volumio[1188]: info: ------------------------------ 83ms Jan 27 23:07:47 volumio volumio[1188]: info: ------------------------------ 58ms Jan 27 23:07:47 volumio volumio[1188]: info: ------------------------------ 56ms Jan 27 23:07:48 volumio volumio[1188]: info: Jan 27 23:07:48 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:48 volumio volumio[1188]: info: Jan 27 23:07:48 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:48 volumio volumio[1188]: info: Jan 27 23:07:48 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:48 volumio volumio[1188]: info: Jan 27 23:07:48 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand status took 110 milliseconds Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:48 volumio volumio[1188]: info: Jan 27 23:07:48 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:48 volumio volumio[1188]: info: Jan 27 23:07:48 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand status took 284 milliseconds Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand status took 206 milliseconds Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand status took 213 milliseconds Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 182 milliseconds Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand status took 116 milliseconds Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:48 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:48 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:48 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:48 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:48 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:48 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:48 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Jan 27 23:07:48 volumio volumio[1188]: info: ------------------------------ 517ms Jan 27 23:07:48 volumio volumio[1188]: info: sendMpdCommand status took 399 milliseconds Jan 27 23:07:49 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 339 milliseconds Jan 27 23:07:49 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 286 milliseconds Jan 27 23:07:49 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 256 milliseconds Jan 27 23:07:49 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 254 milliseconds Jan 27 23:07:49 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:49 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:49 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:49 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:49 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:49 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:49 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:49 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:49 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:49 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:49 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:49 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:49 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:49 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:49 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:49 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:49 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:49 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 27 23:07:49 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:49 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:50 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:50 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:50 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2374 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:50 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:50 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:50 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:50 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:50 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2374 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:50 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:50 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:50 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:50 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:50 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2419 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:50 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:50 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:50 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:53 volumio volumio[1188]: info: ------------------------------ 4769ms Jan 27 23:07:53 volumio volumio[1188]: info: ------------------------------ 4802ms Jan 27 23:07:53 volumio volumio[1188]: info: ------------------------------ 4797ms Jan 27 23:07:53 volumio volumio[1188]: info: ------------------------------ 4719ms Jan 27 23:07:53 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4166 milliseconds Jan 27 23:07:53 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:53 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:53 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:53 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:53 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2419 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:53 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:53 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Jan 27 23:07:53 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Jan 27 23:07:53 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Jan 27 23:07:53 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:53 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:53 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:53 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:53 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:54 volumio volumio[1188]: info: ------------------------------ 6335ms Jan 27 23:07:59 volumio volumio[1188]: info: CoreCommandRouter::volumioPause Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::pause Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::servicePause Jan 27 23:07:59 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:59 volumio volumio[1188]: info: CoreCommandRouter::servicePause Jan 27 23:07:59 volumio volumio[1188]: info: [1769573279105] ControllerQobuz::pause Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 23:07:59 volumio volumio[1188]: info: ControllerMpd::pause Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand pause Jan 27 23:07:59 volumio volumio[1188]: info: Jan 27 23:07:59 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand pause took 131 milliseconds Jan 27 23:07:59 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:59 volumio volumio[1188]: info: Jan 27 23:07:59 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:59 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:59 volumio volumio[1188]: info: Jan 27 23:07:59 volumio volumio[1188]: ---------------------------- MPD announces state update: player Jan 27 23:07:59 volumio volumio[1188]: info: ControllerMpd::getState Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand status took 146 milliseconds Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand status took 100 milliseconds Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand status took 59 milliseconds Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::parseState Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 177 milliseconds Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 132 milliseconds Jan 27 23:07:59 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 92 milliseconds Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:59 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Jan 27 23:07:59 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:07:59 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:07:59 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:07:59 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11615,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2440 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:07:59 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus pause Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:07:59 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 23:07:59 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:07:59 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:08:00 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:08:00 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:08:00 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:08:00 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11615,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2440 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:08:00 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus pause Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:08:00 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:08:00 volumio volumio[1188]: info: ControllerMpd::pushState Jan 27 23:08:00 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Jan 27 23:08:00 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Jan 27 23:08:00 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11615,"duration":228,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2440 Kbps","isStreaming":false,"title":"file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1616780&eid=58629988&fmt=7&profile=raw&app_id=539451548&cid=1190793&etsp=1769576866&hmac=LWa9shf9Zk2ITGO6rJg8BfSVK6s","trackType":"qobuz"} Jan 27 23:08:00 volumio volumio[1188]: verbose: CURRENT POSITION 3 Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus pause Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::pushState Jan 27 23:08:00 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Jan 27 23:08:00 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Jan 27 23:08:01 volumio volumio[1188]: info: ------------------------------ 2738ms Jan 27 23:08:01 volumio volumio[1188]: info: ------------------------------ 2723ms Jan 27 23:08:01 volumio volumio[1188]: info: ------------------------------ 2678ms Jan 27 23:08:02 volumio volumio[1188]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Jan 27 23:08:02 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Jan 27 23:08:06 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 23:08:06 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 27 23:08:12 volumio kernel: hwmon hwmon1: Voltage normalised Jan 27 23:08:15 volumio volumio[1188]: info: Starting Uninstall of plugin user_interface - touch_display Jan 27 23:08:15 volumio volumio[1188]: info: Uninstalling plugin touch_display Jan 27 23:08:16 volumio sudo[2324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 27 23:08:16 volumio sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jan 27 23:08:16 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:08:16 volumio sudo[2326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jan 27 23:08:16 volumio sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:08:16 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:08:16 volumio sudo[2324]: pam_unix(sudo:session): session closed for user root Jan 27 23:08:16 volumio volumio[1188]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 27 23:08:16 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 27 23:08:16 volumio sudo[2328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jan 27 23:08:16 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 23:08:16 volumio volumio[1188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 23:08:16 volumio volumio[1188]: TypeError: Cannot read properties of undefined (reading 'split') Jan 27 23:08:16 volumio volumio[1188]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Jan 27 23:08:16 volumio volumio[1188]: at ChildProcess.exithandler (node:child_process:413:7) Jan 27 23:08:16 volumio volumio[1188]: at ChildProcess.emit (node:events:514:28) Jan 27 23:08:16 volumio volumio[1188]: at maybeClose (node:internal/child_process:1105:16) Jan 27 23:08:16 volumio volumio[1188]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 27 23:08:17 volumio volumio[1188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 23:08:17 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 27 23:08:17 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 27 23:08:17 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root Jan 27 23:08:17 volumio systemd[1]: Reloading. Jan 27 23:08:19 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 27 23:08:19 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 27 23:08:19 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Jan 27 23:08:20 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Jan 27 23:08:20 volumio kernel: hwmon hwmon1: Voltage normalised Jan 27 23:08:25 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 23:07' Jan 27 23:08:25 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"