blueman
blueman copied to clipboard
Published
20 hours ago •
blueman-project
blueman-project
Preventing to disconnect after drop rfcomm port
| Package Versions: | inf |
|---|---|
| blueman | 2.2.4-1 |
| bluez | 5.64-2 |
| bluez-cups | 5.64-2 |
| bluez-hid2hci | 5.64-2 |
| bluez-libs | 5.64-2 |
| bluez-plugins | 5.64-2 |
| bluez-rfcomm | 5.63-2 |
| bluez-tools | 0.2.0-5 |
| bluez-utils | 5.64-2 |
| manjaro-bluetooth | 20210928-1 |
| pulseaudio-bluetooth | 15.0-4 |
| Distribution: | Info |
|---|---|
| Distributor ID: | ManjaroLinux |
| Description: | Manjaro Linux |
| Release: | 21.2.6 |
| Codename: | Qonos |
| Desktop environment: | |
| XDG_CURRENT_DESKTOP= | X-Cinnamon |
| VTE_VERSION= | 6800 |
I connect serial port to BLE devices(HC-05 | HC-06) using blueman then takes data from rfcomm0 via kind of serial plotting programs. No problem so far, however whenever closing these kind programs (leaving rfcomm0 port) my Bluetooth connection drops. I think the adapter kills the serial port connection somehow. How can I prevent the automatically disconnecting from the device when drop the rfcomm? I don't want disconnection.
Also, want to ensure whether this is deliberately choice for energy saving, etc. or not.
- [x ] I have consulted the Troubleshooting page and done my best effort to follow.
Here output of blueman-applet --loglevel debug
Can you post the blueman-applet log for the whole rfcomm/serial session? The one posted does not show anything like this afaics.
It looks like you had another instance of blueman-applet still running so that the one with debug logging quit immediately.
Anyway, blueman runs a blueman-rfcomm-watcher process (as root) that keeps the device open and should prevent such a disconnect. Do you see such a process?
@infirit Cathing what is different bit hard especially glancing 5 of them, luckily there is vimdiff
So, I cleaned each stage for detection error. Eliminated same lines. In my method, I focused differentiation connection and drop moment but in case of ensure I added a step back and forth. At the after a moment stage, I closed serial plotting script.
This is not specific to the script, it also happens in different serial plotting apps. Such as Serialplot
hc-1_connected
(blueman-applet:327502): Gtk-WARNING **: 18:48:02.869: Theme parsing error: gtk.css:7996:1: '/*' in comment block
INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.56
DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_xxx {'Address': '98:D3:32:xxx', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': True, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
INFO Networking:71 set_nap : set nap False
INFO RecentConns:107 initialize: rebuilding menu
INFO BluezAgent:56 register_agent: Register Agent
INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
INFO ShowConnected:50 enumerate_connections: Found 1 existing connections
ERROR AgentManager:20 on_register_failed: /org/bluez/obex/agent/blueman org.bluez.obex.Error.AlreadyExists Agent already exists
DEBUG BasePlugin:63 _on_plugin_delete: Deleting plugin instance <blueman.plugins.applet.DiscvManager.DiscvManager object at 0x7f0f58267100>
DEBUG SerialManager:50 on_delete : Terminating any running scripts
hc-1_connected_serial_bt_data_stream_on
(blueman-applet:329177): Gtk-WARNING **: 18:57:49.225: Theme parsing error: gtk.css:7996:1: '/*' in comment block
INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.56
DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_xxx {'Address': '98:D3:32:xxx', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': True, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
INFO Networking:71 set_nap : set nap False
INFO RecentConns:107 initialize: rebuilding menu
INFO BluezAgent:56 register_agent: Register Agent
INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
INFO ShowConnected:50 enumerate_connections: Found 1 existing connections
ERROR AgentManager:20 on_register_failed: /org/bluez/obex/agent/blueman org.bluez.obex.Error.AlreadyExists Agent already exists
DEBUG BasePlugin:63 _on_plugin_delete: Deleting plugin instance <blueman.plugins.applet.DiscvManager.DiscvManager object at 0x7f78c83fb100>
DEBUG SerialManager:50 on_delete : Terminating any running scripts
cat hc-1_connected_serial_bt_data_stream_on_disconnected
(blueman-applet:329388): Gtk-WARNING **: 18:59:38.394: Theme parsing error: gtk.css:7996:1: '/*' in comment block
INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.56
DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_xxx {'Address': '98:D3:32:xxx', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': True, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
INFO Networking:71 set_nap : set nap False
INFO RecentConns:107 initialize: rebuilding menu
INFO BluezAgent:56 register_agent: Register Agent
ERROR AgentManager:20 on_register_failed: /org/bluez/obex/agent/blueman org.bluez.obex.Error.AlreadyExists Agent already exists
INFO ShowConnected:50 enumerate_connections: Found 1 existing connections
DEBUG BasePlugin:63 _on_plugin_delete: Deleting plugin instance <blueman.plugins.applet.DiscvManager.DiscvManager object at 0x7f368437b100>
DEBUG SerialManager:50 on_delete : Terminating any running scripts
hc-1_connected_serial_bt_data_stream_on_disconnected_after_amoment
(blueman-applet:329593): Gtk-WARNING **: 19:01:01.203: Theme parsing error: gtk.css:7996:1: '/*' in comment block
INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.56
DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_xxx {'Address': '98:D3:32:xxx', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': True, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
INFO Networking:71 set_nap : set nap False
INFO RecentConns:107 initialize: rebuilding menu
INFO BluezAgent:56 register_agent: Register Agent
ERROR AgentManager:20 on_register_failed: /org/bluez/obex/agent/blueman org.bluez.obex.Error.AlreadyExists Agent already exists
INFO ShowConnected:50 enumerate_connections: Found 1 existing connections
DEBUG BasePlugin:63 _on_plugin_delete: Deleting plugin instance <blueman.plugins.applet.DiscvManager.DiscvManager object at 0x7eff441ef100>
DEBUG SerialManager:50 on_delete : Terminating any running scripts
@cschramm I couln't catch
cat hc-1_connected_serial_bt_data_stream_on_disconnected_after_amoment_2
(blueman-applet:329688): Gtk-WARNING **: 19:01:40.071: Theme parsing error: gtk.css:7996:1: '/*' in comment block
INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.56
DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_xxx {'Address': '98:D3:32:xxx', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
INFO Networking:71 set_nap : set nap False
INFO RecentConns:107 initialize: rebuilding menu
INFO BluezAgent:56 register_agent: Register Agent
INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
INFO ShowConnected:50 enumerate_connections: Found 0 existing connections
ERROR AgentManager:20 on_register_failed: /org/bluez/obex/agent/blueman org.bluez.obex.Error.AlreadyExists Agent already exists
DEBUG BasePlugin:63 _on_plugin_delete: Deleting plugin instance <blueman.plugins.applet.DiscvManager.DiscvManager object at 0x7fb8a43c3100>
DEBUG SerialManager:50 on_delete : Terminating any running scripts
cat hc-1_connected_serial_bt_data_stream_on_disconnected_after_amoment_2_after_awhile
(blueman-applet:332183): Gtk-WARNING **: 19:30:28.215: Theme parsing error: gtk.css:7996:1: '/*' in comment block
INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.56
DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_xxx {'Address': '98:D3:32:xxx', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
INFO Networking:71 set_nap : set nap False
INFO RecentConns:107 initialize: rebuilding menu
INFO BluezAgent:56 register_agent: Register Agent
ERROR AgentManager:20 on_register_failed: /org/bluez/obex/agent/blueman org.bluez.obex.Error.AlreadyExists Agent already exists
INFO ShowConnected:50 enumerate_connections: Found 0 existing connections
DEBUG BasePlugin:63 _on_plugin_delete: Deleting plugin instance <blueman.plugins.applet.DiscvManager.DiscvManager object at 0x7fa061727100>
DEBUG SerialManager:50 on_delete : Terminating any running scripts
@cschramm I could see two of them while the connection active
@dzbyr Just post the full log please. Do something like killall blueman-applet && blueman-applet --loglevel debug and post all the lines it produces while connecting to the serial/rfcomm device and scripts being started and termintaed. We need to see the lines like below.
blueman-applet 20.25.51 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_00_9E_C8_61_C7_7F {'Connected': True}
blueman-applet 20.25.52 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_00_9E_C8_61_C7_7F {'ServicesResolved': True}
blueman-applet 20.25.56 INFO RecentConns:111 notify : <Device.Device object at 0x7fc8991ec4c0 (blueman+bluez+Device+Device at 0x555a29f11160)> 00001101-0000-1000-8000-00805f9b34fb
rfcomm channel: 24
blueman-applet 20.25.56 INFO SerialService:92 connect : Starting rfcomm watcher as root
Again all outputs look like another blueman-applet process was already running, thus the error and immediate termination. A killall blueman-applet before running anything new should help.
I didn't realize the debug season was going on. Sorry for not noticing the debug season for link purpose.
I tried two times same process to showing how repetetive this drop. Disconnection time takes ~80-90 second after closing the serial plotting program.
killall blueman-applet && blueman-applet --loglevel debug
(blueman-applet:2643): Gtk-WARNING **: 11:39:52.128: Theme parsing error: gtk.css:4700:28: The style property GtkRange:slider-width is deprecated and shouldn't be used anymore. It will be removed in a future version
(blueman-applet:2643): Gtk-WARNING **: 11:39:52.128: Theme parsing error: gtk.css:4702:29: The style property GtkRange:trough-border is deprecated and shouldn't be used anymore. It will be removed in a future version
(blueman-applet:2643): Gtk-WARNING **: 11:39:52.133: Theme parsing error: gtk.css:6742:27: The style property GtkPaned:handle-size is deprecated and shouldn't be used anymore. It will be removed in a future version
(blueman-applet:2643): Gtk-WARNING **: 11:39:52.136: Theme parsing error: gtk.css:7996:1: '/*' in comment block
blueman-applet 11.39.52 INFO PluginManager:85 load_plugin: ['DisconnectItems', 'ExitItem', 'AutoConnect', 'ShowConnected', 'Networking', 'Menu', 'KillSwitch', 'NetUsage', 'StandardItems', 'Battery', 'ConnectionNotifier', 'RecentConns', 'GameControllerWakelock', 'DBusService', 'DhcpClient', 'PowerManager', 'NMDUNSupport', 'TransferService', 'PPPSupport', 'StatusIcon', 'AppIndicator', 'AuthAgent', 'SerialManager', 'DiscvManager', 'NMPANSupport']
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.Menu.Menu'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.DisconnectItems.DisconnectItems'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.ExitItem.ExitItem'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.StatusIcon.StatusIcon'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.DBusService.DBusService'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.AutoConnect.AutoConnect'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.ShowConnected.ShowConnected'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.Networking.Networking'>
blueman-applet 11.39.52 INFO Networking:35 load_nap_settings: Loading NAP settings
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.PowerManager.PowerManager'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.KillSwitch.KillSwitch'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.PPPSupport.PPPSupport'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.NetUsage.NetUsage'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.StandardItems.StandardItems'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.Battery.Battery'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.ConnectionNotifier.ConnectionNotifier'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.RecentConns.RecentConns'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.GameControllerWakelock.GameControllerWakelock'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.DhcpClient.DhcpClient'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.TransferService.TransferService'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.AppIndicator.AppIndicator'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.AuthAgent.AuthAgent'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.SerialManager.SerialManager'>
blueman-applet 11.39.52 INFO PluginManager:156 __load_plugin: loading <class 'blueman.plugins.applet.DiscvManager.DiscvManager'>
blueman-applet 11.39.52 INFO KillSwitch:106 io_event : killswitch registered 0
blueman-applet 11.39.52 INFO KillSwitch:122 io_event : State: False
blueman-applet 11.39.52 INFO PowerManager:178 update_power_state: off True | foff False | on True | current state True | new state False
blueman-applet 11.39.52 INFO PowerManager:181 update_power_state: Signalling False
blueman-applet 11.39.52 INFO KillSwitch:85 _on_connman_vanished: net.connman vanished
blueman-applet 11.39.52 INFO Applet:54 _on_dbus_name_appeared: org.bluez :1.21
blueman-applet 11.39.52 INFO Functions:114 launch : Gtk eventtime is 0, not using LaunchContext
blueman-applet 11.39.52 INFO Networking:71 set_nap : set nap False
blueman-applet 11.39.52 INFO BluezAgent:56 register_agent: Register Agent
blueman-applet 11.39.52 WARNING DiscvManager:119 update_menuitems: warning: Adapter is None
blueman-applet 11.39.52 INFO TransferService:234 _on_dbus_name_appeared: org.bluez.obex :1.73
blueman-applet 11.39.52 INFO ShowConnected:50 enumerate_connections: Found 0 existing connections
blueman-applet 11.39.52 INFO AgentManager:17 on_registered: /org/bluez/obex/agent/blueman
(blueman-tray:2651): Gtk-WARNING **: 11:39:52.882: Theme parsing error: gtk.css:4700:28: The style property GtkRange:slider-width is deprecated and shouldn't be used anymore. It will be removed in a future version
(blueman-tray:2651): Gtk-WARNING **: 11:39:52.883: Theme parsing error: gtk.css:4702:29: The style property GtkRange:trough-border is deprecated and shouldn't be used anymore. It will be removed in a future version
(blueman-tray:2651): Gtk-WARNING **: 11:39:52.887: Theme parsing error: gtk.css:6742:27: The style property GtkPaned:handle-size is deprecated and shouldn't be used anymore. It will be removed in a future version
(blueman-tray:2651): Gtk-WARNING **: 11:39:52.890: Theme parsing error: gtk.css:7996:1: '/*' in comment block
(blueman-tray:2651): Gdk-CRITICAL **: 11:39:53.524: gdk_window_thaw_toplevel_updates: assertion 'window->update_and_descendants_freeze_count > 0' failed
blueman-applet 11.40.03 INFO PowerManager:139 request_power_state: Requesting True
blueman-applet 11.40.03 INFO KillSwitch:138 on_power_state_change_requested: True
blueman-applet 11.40.03 DEBUG KillSwitch:151 on_power_state_change_requested: Using mechanism to set state: True
blueman-applet 11.40.03 INFO KillSwitch:112 io_event : killswitch changed 0
blueman-applet 11.40.03 INFO KillSwitch:122 io_event : State: True
blueman-applet 11.40.03 INFO PowerManager:178 update_power_state: off True | foff False | on True | current state False | new state False
blueman-applet 11.40.03 INFO PowerManager:124 check : callbacks done
blueman-applet 11.40.03 INFO PowerManager:95 set_adapter_state: True
blueman-applet 11.40.03 INFO PowerManager:178 update_power_state: off False | foff False | on True | current state False | new state True
blueman-applet 11.40.03 INFO PowerManager:181 update_power_state: Signalling True
blueman-applet 11.40.03 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO KillSwitch:106 io_event : killswitch registered 3
blueman-applet 11.40.04 INFO KillSwitch:122 io_event : State: True
blueman-applet 11.40.04 INFO PowerManager:178 update_power_state: off False | foff False | on True | current state True | new state True
blueman-applet 11.40.04 DEBUG Manager:42 _on_object_added: /org/bluez/hci0
blueman-applet 11.40.04 INFO Applet:76 on_adapter_added: Adapter added /org/bluez/hci0
blueman-applet 11.40.04 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0 {'Address': '5C:C5:D4:XX:XX:XY', 'AddressType': 'public', 'Name': 'axe-20aws2t12n', 'Alias': 'axe-intel7260', 'Class': 7936, 'Powered': False, 'Discoverable': False, 'DiscoverableTimeout': 1800, 'Pairable': True, 'PairableTimeout': 0, 'Discovering': False, 'UUIDs': ['0000110e-0000-1000-8000-00805f9b34fb', '0000111f-0000-1000-8000-00805f9b34fb', '00001200-0000-1000-8000-00805f9b34fb', '00001132-0000-1000-8000-00805f9b34fb', '00001108-0000-1000-8000-00805f9b34fb', '0000110c-0000-1000-8000-00805f9b34fb', '00001800-0000-1000-8000-00805f9b34fb', '00001133-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '00001106-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '00001104-0000-1000-8000-00805f9b34fb', '00001105-0000-1000-8000-00805f9b34fb', '0000112f-0000-1000-8000-00805f9b34fb', '00005005-0000-1000-8000-0002ee000001'], 'Modalias': 'usb:v1D6Bp0246d0540', 'Roles': ['central', 'peripheral']}
blueman-applet 11.40.04 INFO ShowConnected:50 enumerate_connections: Found 0 existing connections
blueman-applet 11.40.04 INFO Networking:71 set_nap : set nap False
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
Traceback (most recent call last):
File "/usr/lib/python3.10/site-packages/blueman/main/Applet.py", line 78, in on_adapter_added
plugin.on_adapter_added(path)
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 167, in on_adapter_added
self.initialize()
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 125, in initialize
self.add_item(item)
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 246, in add_item
self.initialize()
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 125, in initialize
self.add_item(item)
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 246, in add_item
self.initialize()
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 125, in initialize
self.add_item(item)
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 246, in add_item
self.initialize()
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 125, in initialize
self.add_item(item)
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/RecentConns.py", line 245, in add_item
self.items.remove(item)
ValueError: list.remove(x): x not in list
blueman-applet 11.40.04 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_98_D3_31_SS_SS_SS
blueman-applet 11.40.04 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_98_D3_31_SS_SS_SS
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_31_SS_SS_SS {'Address': '98:D3:31:SS:SS:SS', 'AddressType': 'public', 'Name': 'HC55_3', 'Alias': 'HC3', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.04 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_98_D3_32_MM_MM_MM
blueman-applet 11.40.04 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_98_D3_32_MM_MM_MM
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_MM_MM_MM {'Address': '98:D3:32:MM:MM:MM', 'AddressType': 'public', 'Name': 'HC55_1', 'Alias': 'HC55_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.04 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_98_D3_32_LL_LL_LL
blueman-applet 11.40.04 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_98_D3_32_LL_LL_LL
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_LL_LL_LL {'Address': '98:D3:32:LL:LL:LL', 'AddressType': 'public', 'Name': 'HC55_2', 'Alias': 'HC55_2', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.04 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_98_D3_31_DD_DD_DD
blueman-applet 11.40.04 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_98_D3_31_DD_DD_DD
blueman-applet 11.40.04 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.04 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_31_DD_DD_DD {'Address': '98:D3:31:DD_DD_DD', 'AddressType': 'public', 'Name': 'HC66_1', 'Alias': 'HC66_1', 'Class': 7936, 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.05 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_70_77_81_38_19_5E
blueman-applet 11.40.05 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_70_77_81_38_19_5E
blueman-applet 11.40.05 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_70_77_81_38_19_5E {'Address': '70:77:81:38:19:5E', 'AddressType': 'public', 'Name': 'Tableto', 'Alias': 'Tableto', 'Class': 4849920, 'Icon': 'computer', 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001105-0000-1000-8000-00805f9b34fb', '0000110a-0000-1000-8000-00805f9b34fb', '0000110c-0000-1000-8000-00805f9b34fb', '0000110f-0000-1000-8000-00805f9b34fb', '00001112-0000-1000-8000-00805f9b34fb', '00001115-0000-1000-8000-00805f9b34fb', '00001116-0000-1000-8000-00805f9b34fb', '0000111f-0000-1000-8000-00805f9b34fb', '0000112f-0000-1000-8000-00805f9b34fb', '00001132-0000-1000-8000-00805f9b34fb', '00001200-0000-1000-8000-00805f9b34fb', '00001800-0000-1000-8000-00805f9b34fb'], 'Modalias': 'bluetooth:v0046p0802d0903', 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.05 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_1C_DE_57_PP_PP_PP
blueman-applet 11.40.05 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_1C_DE_57_PP_PP_PP
blueman-applet 11.40.05 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_1C_DE_57_PP_PP_PP {'Address': '1C:DE:57:PP:PP:PP', 'AddressType': 'public', 'Name': 'iSpeaker', 'Alias': 'iSpeaker', 'Class': 2360324, 'Icon': 'audio-headset', 'Paired': True, 'Trusted': False, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00001101-0000-1000-8000-00805f9b34fb', '0000110b-0000-1000-8000-00805f9b34fb', '0000110c-0000-1000-8000-00805f9b34fb', '0000110e-0000-1000-8000-00805f9b34fb', '0000111e-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.05 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_70_BF_92_JJ_JJ_JJ
blueman-applet 11.40.05 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_70_BF_92_JJ_JJ_JJ
blueman-applet 11.40.05 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_70_BF_92_JJ_JJ_JJ {'Address': '70:BF:92:JJ:JJ:JJ', 'AddressType': 'public', 'Name': 'Jabra Elite 65t', 'Alias': 'Jabra Elite 65t', 'Class': 2360324, 'Icon': 'audio-headset', 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['00000000-deca-fade-deca-deafdecacaff', '00001101-0000-1000-8000-00805f9b34fb', '00001108-0000-1000-8000-00805f9b34fb', '0000110b-0000-1000-8000-00805f9b34fb', '0000110c-0000-1000-8000-00805f9b34fb', '0000110d-0000-1000-8000-00805f9b34fb', '0000110e-0000-1000-8000-00805f9b34fb', '0000111e-0000-1000-8000-00805f9b34fb', '0000112f-0000-1000-8000-00805f9b34fb', '00001200-0000-1000-8000-00805f9b34fb'], 'Modalias': 'bluetooth:v0067p247Ed0222', 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.05 DEBUG Manager:47 _on_object_added: /org/bluez/hci0/dev_5E_60_DB_55_CF_9C
blueman-applet 11.40.05 INFO Applet:86 on_device_created: Device created /org/bluez/hci0/dev_5E_60_DB_55_CF_9C
blueman-applet 11.40.05 INFO RecentConns:107 initialize: rebuilding menu
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_5E_60_DB_55_CF_9C {'Address': '5E:60:DB:55:CF:9C', 'AddressType': 'public', 'Name': 'Nutella Speaker', 'Alias': 'Nutella Speaker', 'Class': 2360328, 'Icon': 'audio-headset', 'Paired': True, 'Trusted': True, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['0000110b-0000-1000-8000-00805f9b34fb', '0000110c-0000-1000-8000-00805f9b34fb', '0000110d-0000-1000-8000-00805f9b34fb', '0000110e-0000-1000-8000-00805f9b34fb', '0000111e-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0 {'UUIDs': ['00001133-0000-1000-8000-00805f9b34fb', '0000110e-0000-1000-8000-00805f9b34fb', '00001105-0000-1000-8000-00805f9b34fb', '00001132-0000-1000-8000-00805f9b34fb', '00001200-0000-1000-8000-00805f9b34fb', '00001104-0000-1000-8000-00805f9b34fb', '00005005-0000-1000-8000-0002ee000001', '00001108-0000-1000-8000-00805f9b34fb', '0000110c-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000112f-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '0000110b-0000-1000-8000-00805f9b34fb', '00001800-0000-1000-8000-00805f9b34fb', '0000111f-0000-1000-8000-00805f9b34fb', '0000110a-0000-1000-8000-00805f9b34fb', '00001106-0000-1000-8000-00805f9b34fb']}
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0 {'Class': 8126732}
blueman-applet 11.40.05 INFO PowerManager:178 update_power_state: off False | foff False | on True | current state True | new state True
blueman-applet 11.40.05 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0 {'Powered': True}
blueman-applet 11.40.12 INFO RecentConns:214 on_item_activated: Connect 98:D3:32:MM:MM:MM 00001101-0000-1000-8000-00805f9b34fb
blueman-applet 11.40.12 INFO RecentConns:179 notify : <Device.Device object at 0x7f388bf4f240 (blueman+bluez+Device+Device at 0x562a75cbe1c0)> 00001101-0000-1000-8000-00805f9b34fb
blueman-applet 11.40.12 INFO RecentConns:107 initialize: rebuilding menu
rfcomm channel: 1
blueman-applet 11.40.16 INFO SerialService:92 connect : Starting rfcomm watcher as root
blueman-applet 11.40.17 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_MM_MM_MM {'Connected': True}
blueman-applet 11.43.47 INFO SerialManager:138 on_device_disconnect: Disconnecting /dev/rfcomm0
blueman-applet 11.43.47 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_MM_MM_MM {'Connected': False}
blueman-applet 11.44.01 INFO RecentConns:179 notify : <Device.Device object at 0x7f388bf4f240 (blueman+bluez+Device+Device at 0x562a75cbe1c0)> 00001101-0000-1000-8000-00805f9b34fb
blueman-applet 11.44.01 INFO RecentConns:107 initialize: rebuilding menu
Failed to connect to sdp
blueman-applet 11.44.10 INFO RecentConns:179 notify : <Device.Device object at 0x7f388bf4f240 (blueman+bluez+Device+Device at 0x562a75cbe1c0)> 00001101-0000-1000-8000-00805f9b34fb
blueman-applet 11.44.10 INFO RecentConns:107 initialize: rebuilding menu
rfcomm channel: 1
blueman-applet 11.44.15 INFO SerialService:92 connect : Starting rfcomm watcher as root
blueman-applet 11.44.15 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_MM_MM_MM {'Connected': True}
blueman-applet 11.47.30 INFO SerialManager:138 on_device_disconnect: Disconnecting /dev/rfcomm0
blueman-applet 11.47.30 DEBUG Base:60 do_g_properties_changed: /org/bluez/hci0/dev_98_D3_32_MM_MM_MM {'Connected': False}
I don't se anything out of the ordinary. If there is a watcher having the rfcomm device open the connection should not drop from the adapter's side.
Unfortunately we can't see over dbus which side disconnects. This may show if you run bluetoothd in debug mode, see https://github.com/blueman-project/blueman/wiki/Troubleshooting#debugging-bluez
Did you see anything in bluetoothd debug output that suggest what is causing the disconnect?
Yes, I'm trying to find a solution to that because that is looks like a common issue, but couldn't yet.
bluetoothd[36951]: src/adapter.c:connected_callback() hci0 device 98:D3:ccc:B5:19 connected eir_len 8
bluetoothd[36951]: src/adapter.c:dev_disconnected() Device 98:D3:ccc:B5:19 disconnected, reason 0
bluetoothd[36951]: src/adapter.c:adapter_remove_connection()
bluetoothd[36951]: plugins/policy.c:disconnect_cb() reason 0
bluetoothd[36951]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 98:D3:ccc:B5:19 type 0 status 0xe
bluetoothd[36951]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
bluetoothd[36951]: src/device.c:device_bonding_failed() status 14
bluetoothd[36951]: src/adapter.c:resume_discovery()
I found ertm is first doubt point.However there is a weird thing about ertm that I couldn't prevent to set ertm. I think
sudo echo 1 > /sys/module/bluetooth/parameters/disable_ertm
bash: /sys/module/bluetooth/parameters/disable_ertm: Permission denied
or doing with another way that addin a bluetooth.conf file to the /etc/modprobe.d
options bluetooth disable_ertm=1
Does the following error could be related with this drop?
Connection Failed: File "/usr/lib/python3.10/site-packages/blueman/main/DbusService.py", line 124, in _handle_method_call
method(*(args + (ok, lambda exception: self._return_dbus_error(invocation, exception))))
File "/usr/lib/python3.10/site-packages/blueman/plugins/applet/DBusService.py", line 87, in connect_service
service.connect(reply_handler=lambda port: ok(), error_handler=err)
File "/usr/lib/python3.10/site-packages/blueman/services/meta/SerialService.py", line 93, in connect
Mechanism().OpenRFCOMM('(d)', port_id)
File "/usr/lib/python3.10/site-packages/gi/overrides/Gio.py", line 349, in __call__
result = self.dbus_proxy.call_sync(self.method_name, arg_variant,
gi.repository.GLib.GError: g-dbus-error-quark: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.100 was not provided by any .service files (2)
Oh hello. Yes, definitely. Looks like another manifestation of #1759.
Can you check journalctl -u blueman-mechanism.service? It looks like the D-Bus proxy invocation of blueman-mechanism succeeds but for some reason the process is already gone again when the client commits the immediately following method call.
It seems so Didn't touch anything after above error, so the latest report series should be exactly related it.
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 130650 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 24 11:06:55 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
longer list
-- Boot a47ec1752a4649e195cfdf0c86776b47 --
Tem 23 10:22:54 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 10:22:54 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 10:23:26 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 10:45:01 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 10:45:01 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 10:45:31 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 12:00:47 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 12:00:47 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 12:01:18 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 12:01:18 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Unit process 13069 (blueman-rfcomm-) remains running after unit stopped.
Tem 23 12:04:28 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 13069 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 23 12:04:28 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 23 12:04:28 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 12:04:29 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 12:04:59 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 12:16:03 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 12:16:03 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 12:16:33 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 12:16:33 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Unit process 14717 (blueman-rfcomm-) remains running after unit stopped.
Tem 23 12:18:25 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 14717 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 23 12:18:25 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 23 12:18:25 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 12:18:25 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 12:18:55 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 12:42:38 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 12:42:38 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 12:43:08 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 12:43:08 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Unit process 18063 (blueman-rfcomm-) remains running after unit stopped.
Tem 23 13:29:34 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 18063 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 23 13:29:34 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 23 13:29:34 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 13:29:34 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 13:30:04 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 17:43:02 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 17:43:03 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 17:43:33 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 23 17:43:33 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Unit process 43857 (blueman-rfcomm-) remains running after unit stopped.
Tem 23 17:57:43 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 43857 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 23 17:57:43 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 23 17:57:43 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 23 17:57:44 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 23 17:58:14 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 24 08:35:30 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 24 08:35:30 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 24 08:36:00 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 24 10:05:28 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 24 10:05:28 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 24 10:05:58 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 24 10:11:03 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 24 10:11:03 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 24 10:11:33 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 24 10:11:33 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Unit process 130251 (blueman-rfcomm-) remains running after unit stopped.
Tem 24 10:14:24 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 130251 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 24 10:14:24 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 24 10:14:24 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 24 10:14:24 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 24 10:14:54 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
Tem 24 10:14:54 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Unit process 130650 (blueman-rfcomm-) remains running after unit stopped.
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Found left-over process 130650 (blueman-rfcomm-) in control group while starting unit. Ignoring.
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: Starting Bluetooth management mechanism...
Tem 24 11:06:25 axe-20aws2t12n systemd[1]: Started Bluetooth management mechanism.
Tem 24 11:06:55 axe-20aws2t12n systemd[1]: blueman-mechanism.service: Deactivated successfully.
~~interesting. Looks like it sometimes starts, ignores the timer and exists immediately~~ I misread :eyes:
I don't see it dying, it ends normally after ~30 seconds and nothing stand out to me.
Hm, looks unsuspicious, yes. Do you have something from blueman-mechanism.service as well? Do you know the time of the error (There might be something in ~/.xsession-errors)? Can you replicate it more or less reliably?
One thing that could hit us here - but chances are minimal and it's definitely not the cause in #1759 - is a small race condition: When the proxy object gets created, systemd might find the service running but it then exists before the method call arrives as it just ran into its timeout.
I'm a bit confused what the Gio proxy actually does. Its documentation says
The unique name owner of the proxy’s name is tracked
calls are always sent to the current name owner
if no name owner currently exists, then calls will be sent to the well-known name which may result in the message bus launching an owner (unless
G_DBUS_PROXY_FLAGS_DO_NOT_AUTO_STARTis set)
so I would not expect the proxy to send calls to a name owner that's gone. :confused:
This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.
![github-actions[bot] avatar](https://avatars.githubusercontent.com/in/15368?v=4 "Published by a pub.dev verified publisher"){kind=small}