pavucontrol needs restart to see newly connected bluetooth device
Hi,
On HEAD (7a82be33), when pavucontrol is running before a bluetooth device connects, the bluetooth device shows up neither under "Output Devices", nor as a choice for a playback stream. I don't think this was "always" the case, but I am not entirely sure.
Restarting pavucontrol fixes the issue.
Log output (level 3, 4 is too verbose) from around the disconnect/connect of the bluetooth device:
[I][000005915.391509][impl-node.c:315 node_update_state()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-18) running -> suspended
[I][000005915.391773][impl-node.c:1666 pw_impl_node_destroy()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-18) destroy
[I][000005915.391935][context.c:885 pw_context_recalc_graph()] context 0x559b76fd8d90: busy:0 reason:active node destroy
[I][000005915.392174][impl-node.c:1666 pw_impl_node_destroy()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-0) destroy
[I][000005915.392250][context.c:885 pw_context_recalc_graph()] context 0x559b76fd8d90: busy:0 reason:active node destroy
[I][000005915.392547][policy-node.c:640 rescan_node()] trying to link node 101 exclusive:0 reconnect:0 target:-1
[I][000005915.392552][impl-node.c:1666 pw_impl_node_destroy()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-123) destroy
[W][000005915.392576][policy-node.c:670 rescan_node()] no node found for 101
[I][000005915.392671][impl-link.c:724 pw_impl_link_deactivate()] (123.0 -> 101.0) deactivated
[I][000005915.392699][impl-link.c:107 link_update_state()] (123.0 -> 101.0) active -> paused
[I][000005915.392759][impl-link.c:724 pw_impl_link_deactivate()] (123.1 -> 101.1) deactivated
[I][000005915.392780][impl-link.c:107 link_update_state()] (123.1 -> 101.1) active -> paused
[I][000005915.392857][impl-link.c:107 link_update_state()] (123.0 -> 101.0) paused -> init
[I][000005915.392878][context.c:885 pw_context_recalc_graph()] context 0x5600ae0262d0: busy:0 reason:link unprepared
[I][000005915.392944][impl-link.c:107 link_update_state()] (123.1 -> 101.1) paused -> init
[I][000005915.392964][context.c:885 pw_context_recalc_graph()] context 0x5600ae0262d0: busy:0 reason:link unprepared
[I][000005915.392984][impl-node.c:751 pw_impl_node_set_driver()] (PulseAudio Volume Control-101) -> change driver (api.bluez5.a2dp.sink.Bose NC 700 Headpho-123 -> PulseAudio Volume Control-101)
[I][000005915.393115][impl-node.c:315 node_update_state()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-123) running -> suspended
[I][000005915.393237][impl-link.c:1263 pw_impl_link_destroy()] (123.0 -> 101.0) destroy
[I][000005915.393321][impl-link.c:1263 pw_impl_link_destroy()] (123.1 -> 101.1) destroy
[I][000005915.393403][context.c:885 pw_context_recalc_graph()] context 0x5600ae0262d0: busy:0 reason:active node destroy
[I][000005915.393549][impl-node.c:1666 pw_impl_node_destroy()] (PulseAudio Volume Control-101) destroy
[I][000005915.393584][impl-node.c:315 node_update_state()] (PulseAudio Volume Control-101) running -> suspended
[I][000005915.393736][context.c:885 pw_context_recalc_graph()] context 0x5600ae0262d0: busy:0 reason:active node destroy
[I][000005915.394464][impl-node.c:315 node_update_state()] (PulseAudio Volume Control-37) running -> suspended
[I][000005915.394572][pulse-server.c:543 send_stream_killed()] pulse-server 0x5574bcff00e0: [PulseAudio Volume Control] RECORD_STREAM_KILLED channel:2
[I][000005915.394685][context.c:885 pw_context_recalc_graph()] context 0x5574bcfa8cb0: busy:0 reason:node deactivate
[I][000005915.394718][impl-node.c:1666 pw_impl_node_destroy()] (PulseAudio Volume Control-37) destroy
[I][000005915.394857][impl-node.c:1666 pw_impl_node_destroy()] (PulseAudio Volume Control-0) destroy
[I][000005921.366342][bluez5-dbus.c:154 endpoint_select_configuration()] 0x559b7702afb8: /MediaEndpoint/A2DPSource/aac select conf 6
[I][000005921.396290][impl-node.c:860 check_properties()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-0) latency:512/48000 ->quantum 512/48000
[I][000005921.396357][impl-node.c:315 node_update_state()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-0) creating -> suspended
[I][000005921.399437][impl-node.c:860 check_properties()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-0) latency:512/48000 ->quantum 512/48000
[I][000005921.399486][impl-node.c:315 node_update_state()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-0) creating -> suspended
[I][000005921.399525][context.c:885 pw_context_recalc_graph()] context 0x559b76fd8d90: busy:0 reason:register active node
[I][000005921.399549][context.c:988 pw_context_recalc_graph()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-18) new quantum:1024->512
[I][000005921.400092][impl-node.c:860 check_properties()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-0) latency:512/48000 ->quantum 512/48000
[I][000005921.400363][impl-node.c:315 node_update_state()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-124) creating -> suspended
[I][000005921.400524][context.c:885 pw_context_recalc_graph()] context 0x5600ae0262d0: busy:0 reason:node activate
[I][000005921.400563][context.c:988 pw_context_recalc_graph()] (api.bluez5.a2dp.sink.Bose NC 700 Headpho-124) new quantum:1024->512
[I][000005921.400997][pulse-server.c:3899 do_get_info()] pulse-server 0x5574bcfc1610: [PulseAudio Volume Control] GET_CARD_INFO tag:36 idx:101 name:alsa_input.usb-BEHRINGER_UMC404HD_192k-00.multichannel-input
[I][000005921.401145][context.c:885 pw_context_recalc_graph()] context 0x559b76fd8d90: busy:0 reason:node flags changed
[I][000005921.401156][pulse-server.c:3899 do_get_info()] pulse-server 0x5574bcfc1610: [PulseAudio Volume Control] GET_SINK_INFO tag:37 idx:124 name:alsa_output.usb-BEHRINGER_UMC404HD_192k-00.analog-surround-40
[I][000005921.401642][context.c:885 pw_context_recalc_graph()] context 0x5600ae0262d0: busy:0 reason:node flags changed
[I][000005921.407454][pulse-server.c:2933 do_set_port()] pulse-server 0x5574bcfc1610: [PulseAudio Volume Control] SET_SINK_PORT tag:38 index:51 name:(null) port:analog-output
Regards,
Andres