Volume reset to maximum when switching to the "same" profile
PipeWire built from master (35e81fbf).
pavucontrol
shows (among others) the following two profiles:
-
High Fidelity Playback (A2DP Sink, codec SBC)
~a2dp-sink+sbc
-
High Fidelity Playback (A2DP Sink)
~a2dp-sink
If a2dp-sink+sbc
is active, and I select a2dp-sink
, then it immediately goes back to a2dp-sink+sbc
(I believe because a2dp-sink
is an "alias" for best available A2DP codec?). Unfortunately, in the process, the actual volume is set to the maximum.
I was able to reproduce this behaviour every time I tried. I cannot observe this volume change in pavucontrol
or on the GNOME volume slider when this happens.
profile switching
[I][000075448.724524][pulse-server.c:4770 do_set_profile()] pulse-server 0x616000001880: [pavucontrol] SET_CARD_PROFILE tag:621 id:73 name:(null) profile:a2dp-sink
[I][000075448.754178][bluez5-dbus.c:2100 transport_release()] Transport /org/bluez/hci0/dev_XX_XX_XX_XX_XX_XX/sep1/fd0 released
[I][000075448.754427][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-18) running -> suspended
[I][000075448.754749][impl-node.c:1729 pw_impl_node_destroy()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-18) destroy
[I][000075448.755512][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:active node destroy
[I][000075448.757273][impl-node.c:1729 pw_impl_node_destroy()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-0) destroy
[I][000075448.757302][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:active node destroy
[I][000075448.758240][impl-node.c:889 check_properties()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-0) latency:512/48000 ->quantum 512/48000
[I][000075448.758296][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-0) creating -> suspended
[I][000075448.766213][impl-node.c:889 check_properties()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-0) latency:512/48000 ->quantum 512/48000
[I][000075448.766308][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-0) creating -> suspended
[I][000075448.766401][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:register active node
[I][000075448.766425][context.c:1064 pw_context_recalc_graph()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-18) new quantum:1024->512
[I][000075448.767998][impl-node.c:1729 pw_impl_node_destroy()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74) destroy
[I][000075448.768167][impl-link.c:735 pw_impl_link_deactivate()] (76.0 -> 74.0) deactivated
[I][000075448.768194][impl-link.c:107 link_update_state()] (76.0 -> 74.0) active -> paused
[I][000075448.768376][impl-link.c:735 pw_impl_link_deactivate()] (76.1 -> 74.1) deactivated
[I][000075448.768401][impl-link.c:107 link_update_state()] (76.1 -> 74.1) active -> paused
[I][000075448.768542][impl-link.c:107 link_update_state()] (76.0 -> 74.0) paused -> init
[I][000075448.768569][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link unprepared
[I][000075448.768622][impl-link.c:107 link_update_state()] (76.1 -> 74.1) paused -> init
[I][000075448.768634][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link unprepared
[I][000075448.768649][impl-node.c:789 pw_impl_node_set_driver()] (pavucontrol-77) -> change driver (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74 -> pavucontrol-77)
[I][000075448.768917][impl-link.c:735 pw_impl_link_deactivate()] (76.0 -> 77.0) deactivated
[I][000075448.768945][impl-link.c:107 link_update_state()] (76.0 -> 77.0) active -> paused
[I][000075448.769030][impl-link.c:735 pw_impl_link_deactivate()] (76.1 -> 77.1) deactivated
[I][000075448.769044][impl-link.c:107 link_update_state()] (76.1 -> 77.1) active -> paused
[I][000075448.769108][impl-node.c:789 pw_impl_node_set_driver()] (pavucontrol-88) -> change driver (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74 -> pavucontrol-88)
[I][000075448.769272][impl-link.c:735 pw_impl_link_deactivate()] (76.0 -> 88.0) deactivated
[I][000075448.769284][impl-link.c:107 link_update_state()] (76.0 -> 88.0) active -> paused
[I][000075448.769370][impl-link.c:735 pw_impl_link_deactivate()] (76.1 -> 88.1) deactivated
[I][000075448.769382][impl-link.c:107 link_update_state()] (76.1 -> 88.1) active -> paused
[I][000075448.769440][impl-node.c:789 pw_impl_node_set_driver()] (pavucontrol-75) -> change driver (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74 -> pavucontrol-75)
[I][000075448.769532][impl-link.c:735 pw_impl_link_deactivate()] (76.0 -> 75.0) deactivated
[I][000075448.769543][impl-link.c:107 link_update_state()] (76.0 -> 75.0) active -> paused
[I][000075448.769606][impl-link.c:735 pw_impl_link_deactivate()] (76.1 -> 75.1) deactivated
[I][000075448.769617][impl-link.c:107 link_update_state()] (76.1 -> 75.1) active -> paused
[I][000075448.769690][impl-node.c:789 pw_impl_node_set_driver()] (Lollypop-76) -> change driver (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74 -> Lollypop-76)
[I][000075448.769815][impl-link.c:735 pw_impl_link_deactivate()] (76.0 -> 102.0) deactivated
[I][000075448.769835][impl-link.c:107 link_update_state()] (76.0 -> 102.0) active -> paused
[I][000075448.769930][impl-link.c:735 pw_impl_link_deactivate()] (76.1 -> 102.1) deactivated
[I][000075448.769948][impl-link.c:107 link_update_state()] (76.1 -> 102.1) active -> paused
[I][000075448.770026][impl-node.c:789 pw_impl_node_set_driver()] (pavucontrol-102) -> change driver (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74 -> pavucontrol-102)
[I][000075448.770153][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74) running -> suspended
[I][000075448.770234][impl-link.c:1290 pw_impl_link_destroy()] (76.0 -> 74.0) destroy
[I][000075448.770466][impl-link.c:1290 pw_impl_link_destroy()] (76.1 -> 74.1) destroy
[I][000075448.770862][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:active node destroy
[I][000075448.771355][impl-node.c:889 check_properties()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-0) latency:512/48000 ->quantum 512/48000
[I][000075448.772027][media-session.c:2177 core_error()] error id:0 seq:4433 res:-2 (Nincs ilyen fájl vagy könyvtár): unknown resource 15 op:7
[I][000075448.772491][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74) creating -> suspended
[I][000075448.773109][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node activate
[I][000075448.773241][context.c:1064 pw_context_recalc_graph()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74) new quantum:1024->512
[I][000075448.773653][impl-node.c:353 node_update_state()] (Lollypop-76) running -> idle
[I][000075448.773984][impl-node.c:353 node_update_state()] (pavucontrol-77) running -> idle
[I][000075448.774082][impl-node.c:353 node_update_state()] (pavucontrol-88) running -> idle
[I][000075448.774157][impl-node.c:353 node_update_state()] (pavucontrol-75) running -> idle
[I][000075448.774232][impl-node.c:353 node_update_state()] (pavucontrol-102) running -> idle
[I][000075448.774372][impl-node.c:353 node_update_state()] (Lollypop-26) running -> idle
[I][000075448.774429][impl-node.c:353 node_update_state()] (pavucontrol-20) running -> idle
[I][000075448.774454][impl-node.c:353 node_update_state()] (pavucontrol-27) running -> idle
[I][000075448.774472][impl-node.c:353 node_update_state()] (pavucontrol-23) running -> idle
[I][000075448.774489][impl-node.c:353 node_update_state()] (pavucontrol-16) running -> idle
[I][000075448.774962][pulse-server.c:3532 do_get_server_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SERVER_INFO tag:484
[I][000075448.775844][pulse-server.c:3532 do_get_server_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SERVER_INFO tag:535
[I][000075448.777413][default-routes.c:728 handle_route()] device 73: port 'headset-output'
[I][000075448.777543][default-routes.c:547 save_profile()] device 73: profile a2dp-sink-sbc unchanged ([ "headset-output" ])
[I][000075448.777667][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004aca0 00000100/0000070c
[I][000075448.777692][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c000035760 00000100/0000070c
[I][000075448.777715][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c000046020 00000100/0000070c
[I][000075448.777747][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c000049e60 00000100/0000070c
[I][000075448.777767][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004b8a0 00000100/0000070c
[I][000075448.777886][pulse-server.c:360 operation_complete()] pulse-server 0x613000000900: [pavucontrol] tag:621 complete
[I][000075448.778239][policy-node.c:807 rescan_node()] trying to link node 76 exclusive:0 reconnect:1 target:74 follows-default:0
[I][000075448.778271][policy-node.c:206 configure_node()] node 76 monitor:0 channelmix 2->2
[I][000075448.778331][policy-node.c:656 link_nodes()] linking node 76 to node 74
[I][000075448.778398][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_CARD_INFO tag:485 idx:73 name:(null)
[I][000075448.778726][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:486 idx:77 name:(null)
[I][000075448.779025][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:487 idx:88 name:(null)
[I][000075448.779260][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:488 idx:75 name:(null)
[I][000075448.779403][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INPUT_INFO tag:489 idx:76 name:(null)
[I][000075448.779603][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:490 idx:102 name:(null)
[I][000075448.779739][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INFO tag:491 idx:74 name:(null)
[I][000075448.779839][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_INFO tag:492 idx:65610 name:(null)
[I][000075448.779876][pulse-server.c:322 reply_error()] pulse-server 0x613000000ac0: [GNOME Volume Control Media Keys] ERROR command:23 (GET_SOURCE_INFO) tag:492 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.779895][pulse-server.c:3532 do_get_server_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SERVER_INFO tag:493
[I][000075448.782043][impl-link.c:1290 pw_impl_link_destroy()] (76.0 -> 77.0) destroy
[I][000075448.782100][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_CARD_INFO tag:536 idx:73 name:(null)
[I][000075448.782282][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:537 idx:77 name:(null)
[I][000075448.782437][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:538 idx:88 name:(null)
[I][000075448.782571][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:539 idx:75 name:(null)
[I][000075448.782702][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INPUT_INFO tag:540 idx:76 name:(null)
[I][000075448.782902][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:541 idx:102 name:(null)
[I][000075448.783038][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INFO tag:542 idx:74 name:(null)
[I][000075448.783110][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.783135][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_INFO tag:543 idx:65610 name:(null)
[I][000075448.783173][pulse-server.c:322 reply_error()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] ERROR command:23 (GET_SOURCE_INFO) tag:543 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.783193][pulse-server.c:3532 do_get_server_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SERVER_INFO tag:544
[I][000075448.783224][impl-link.c:1290 pw_impl_link_destroy()] (76.0 -> 88.0) destroy
[I][000075448.783389][pulse-server.c:3532 do_get_server_info()] pulse-server 0x616000001880: [pavucontrol] GET_SERVER_INFO tag:622
[I][000075448.783510][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_CARD_INFO tag:623 idx:73 name:(null)
[I][000075448.783597][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.783661][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:624 idx:77 name:(null)
[I][000075448.783680][impl-link.c:1290 pw_impl_link_destroy()] (76.0 -> 75.0) destroy
[I][000075448.783809][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:625 idx:88 name:(null)
[I][000075448.783951][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:626 idx:75 name:(null)
[I][000075448.784080][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INPUT_INFO tag:627 idx:76 name:(null)
[I][000075448.784288][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:628 idx:102 name:(null)
[I][000075448.784457][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INFO tag:629 idx:74 name:(null)
[I][000075448.784620][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_INFO tag:630 idx:65610 name:(null)
[I][000075448.784684][pulse-server.c:322 reply_error()] pulse-server 0x613000000900: [pavucontrol] ERROR command:23 (GET_SOURCE_INFO) tag:630 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.784715][pulse-server.c:3532 do_get_server_info()] pulse-server 0x616000001880: [pavucontrol] GET_SERVER_INFO tag:631
[I][000075448.785207][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.785292][impl-link.c:1290 pw_impl_link_destroy()] (76.0 -> 102.0) destroy
[I][000075448.785612][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.786259][impl-link.c:1290 pw_impl_link_destroy()] (76.1 -> 77.1) destroy
[I][000075448.787195][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.787282][impl-link.c:1290 pw_impl_link_destroy()] (76.1 -> 88.1) destroy
[I][000075448.787618][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.787689][impl-link.c:1290 pw_impl_link_destroy()] (76.1 -> 75.1) destroy
[I][000075448.789204][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.789288][impl-link.c:1290 pw_impl_link_destroy()] (76.1 -> 102.1) destroy
[I][000075448.789604][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link destroy
[I][000075448.792884][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004aca0 00000500/0000070c
[I][000075448.793124][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004aca0 00000200/0000070c
[I][000075448.793238][restore-stream.c:274 handle_props()] stream 76: save props restore.stream.Output/Audio.media.role:Music { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.793613][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INPUT_INFO tag:494 idx:76 name:(null)
[I][000075448.793881][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INPUT_INFO tag:545 idx:76 name:(null)
[I][000075448.794357][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node flags changed
[I][000075448.797103][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node flags changed
[I][000075448.798249][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INFO tag:495 idx:74 name:(null)
[I][000075448.798482][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_INFO tag:496 idx:65610 name:(null)
[I][000075448.798552][pulse-server.c:322 reply_error()] pulse-server 0x613000000ac0: [GNOME Volume Control Media Keys] ERROR command:23 (GET_SOURCE_INFO) tag:496 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.798581][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_CARD_INFO tag:497 idx:73 name:(null)
[I][000075448.798780][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INFO tag:546 idx:74 name:(null)
[I][000075448.798826][policy-node.c:807 rescan_node()] trying to link node 76 exclusive:0 reconnect:1 target:74 follows-default:0
[I][000075448.798857][policy-node.c:206 configure_node()] node 76 monitor:0 channelmix 2->2
[I][000075448.798909][policy-node.c:656 link_nodes()] linking node 76 to node 74
[I][000075448.798954][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_INFO tag:547 idx:65610 name:(null)
[I][000075448.799023][pulse-server.c:322 reply_error()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] ERROR command:23 (GET_SOURCE_INFO) tag:547 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.799061][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_CARD_INFO tag:548 idx:73 name:(null)
[I][000075448.802111][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INPUT_INFO tag:498 idx:76 name:(null)
[I][000075448.802574][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004aca0 00000500/0000070c
[I][000075448.803120][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INPUT_INFO tag:549 idx:76 name:(null)
[I][000075448.803224][impl-link.c:1163 pw_context_create_link()] (76.0 -> 74.0) (Lollypop) -> (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink)
[I][000075448.803961][impl-link.c:1163 pw_context_create_link()] (76.1 -> 74.1) (Lollypop) -> (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink)
[I][000075448.804202][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004aca0 00000200/0000070c
[I][000075448.804247][impl-link.c:107 link_update_state()] (76.0 -> 74.0) init -> negotiating
[I][000075448.804278][restore-stream.c:274 handle_props()] stream 76: save props restore.stream.Output/Audio.media.role:Music { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.804480][impl-link.c:107 link_update_state()] (76.1 -> 74.1) init -> negotiating
[I][000075448.807356][impl-link.c:107 link_update_state()] (76.0 -> 74.0) negotiating -> allocating
[I][000075448.807754][impl-link.c:107 link_update_state()] (76.1 -> 74.1) negotiating -> allocating
[I][000075448.808700][impl-link.c:107 link_update_state()] (76.0 -> 74.0) allocating -> paused
[I][000075448.808763][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link prepared
[I][000075448.808790][impl-node.c:789 pw_impl_node_set_driver()] (Lollypop-76) -> change driver (Lollypop-76 -> bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74)
[I][000075448.809044][impl-link.c:559 pw_impl_link_activate()] (76.0 -> 74.0) activated
[I][000075448.809068][impl-link.c:107 link_update_state()] (76.0 -> 74.0) paused -> active
[I][000075448.809120][impl-link.c:107 link_update_state()] (76.1 -> 74.1) allocating -> paused
[I][000075448.809148][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link prepared
[I][000075448.809188][impl-link.c:559 pw_impl_link_activate()] (76.1 -> 74.1) activated
[I][000075448.809200][impl-link.c:107 link_update_state()] (76.1 -> 74.1) paused -> active
[I][000075448.809444][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74) suspended -> running
[I][000075448.809586][fmtconvert.c:166 setup_convert()] fmtconvert 0x7f1cad1e7d30: Spa:Enum:AudioFormat:F32LE/2@48000->Spa:Enum:AudioFormat:F32P/2@48000
[I][000075448.809693][splitter.c:620 setup_convert()] splitter 0x7f1cad219c80: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/1@48000x2
[I][000075448.809788][channelmix.c:336 setup_convert()] channelmix 0x7f1cad1f1120: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/2@48000 00000018:00000018
[I][000075448.810120][resample.c:150 setup_convert()] resample 0x7f1cad207820: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/2@48000
[I][000075448.810334][merger.c:862 setup_convert()] merger 0x7f31831900a0: Spa:Enum:AudioFormat:F32P/1@48000x2->Spa:Enum:AudioFormat:F32P/2@48000
[I][000075448.810420][fmtconvert.c:166 setup_convert()] fmtconvert 0x7f31833f8890: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:S16LE/2@48000
[I][000075448.810473][channelmix.c:336 setup_convert()] channelmix 0x7f31833d9120: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/2@48000 00000018:00000018
[I][000075448.810728][resample.c:150 setup_convert()] resample 0x7f31833ef820: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/2@48000
[I][000075448.811927][impl-node.c:353 node_update_state()] (Lollypop-26) idle -> running
[I][000075448.812080][impl-node.c:353 node_update_state()] (Lollypop-76) idle -> running
[I][000075448.812958][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INPUT_INFO tag:499 idx:76 name:(null)
[I][000075448.813186][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INFO tag:500 idx:74 name:(null)
[I][000075448.813352][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_INFO tag:501 idx:65610 name:(null)
[I][000075448.813394][pulse-server.c:322 reply_error()] pulse-server 0x613000000ac0: [GNOME Volume Control Media Keys] ERROR command:23 (GET_SOURCE_INFO) tag:501 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.813468][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INPUT_INFO tag:550 idx:76 name:(null)
[I][000075448.813668][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INFO tag:551 idx:74 name:(null)
[I][000075448.813793][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_INFO tag:552 idx:65610 name:(null)
[I][000075448.813851][pulse-server.c:322 reply_error()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] ERROR command:23 (GET_SOURCE_INFO) tag:552 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.813931][pulse-server.c:3262 do_set_port()] pulse-server 0x616000001880: [pavucontrol] SET_SINK_PORT tag:632 index:74 name:(null) port:headset-output
[I][000075448.814036][pulse-server.c:4734 do_extension()] pulse-server 0x616000001880: [pavucontrol] EXTENSION tag:633 id:4294967295 name:module-device-restore
[W][000075448.814063][pulse-server.c:322 reply_error()] pulse-server 0x613000000900: [pavucontrol] ERROR command:87 (EXTENSION) tag:633 error:19 (A művelet nem támogatott)
[I][000075448.814095][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INPUT_INFO tag:634 idx:76 name:(null)
[I][000075448.814269][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INFO tag:635 idx:74 name:(null)
[I][000075448.814429][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_INFO tag:636 idx:65610 name:(null)
[I][000075448.814479][pulse-server.c:322 reply_error()] pulse-server 0x613000000900: [pavucontrol] ERROR command:23 (GET_SOURCE_INFO) tag:636 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.814511][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_CARD_INFO tag:637 idx:73 name:(null)
[I][000075448.814707][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INPUT_INFO tag:638 idx:76 name:(null)
[I][000075448.814900][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INPUT_INFO tag:639 idx:76 name:(null)
[I][000075448.815059][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INFO tag:640 idx:74 name:(null)
[I][000075448.815185][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_INFO tag:641 idx:65610 name:(null)
[I][000075448.815244][pulse-server.c:322 reply_error()] pulse-server 0x613000000900: [pavucontrol] ERROR command:23 (GET_SOURCE_INFO) tag:641 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.819136][a2dp-sink.c:773 do_start()] a2dp-sink 0x626000018128: using A2DP codec SBC, delay:200 ms
[I][000075448.819247][impl-node.c:353 node_update_state()] (bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-18) suspended -> running
[I][000075448.819769][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004aca0 00000300/0000070c
[I][000075448.819848][restore-stream.c:274 handle_props()] stream 76: save props restore.stream.Output/Audio.media.role:Music { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.820582][pulse-server.c:360 operation_complete()] pulse-server 0x613000000900: [pavucontrol] tag:632 complete
[I][000075448.820705][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INFO tag:502 idx:74 name:(null)
[I][000075448.820833][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_INFO tag:503 idx:65610 name:(null)
[I][000075448.820877][pulse-server.c:322 reply_error()] pulse-server 0x613000000ac0: [GNOME Volume Control Media Keys] ERROR command:23 (GET_SOURCE_INFO) tag:503 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.821376][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INFO tag:553 idx:74 name:(null)
[I][000075448.821519][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_INFO tag:554 idx:65610 name:(null)
[I][000075448.821566][pulse-server.c:322 reply_error()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] ERROR command:23 (GET_SOURCE_INFO) tag:554 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.827238][pulse-server.c:2252 do_delete_stream()] pulse-server 0x616000001880: [pavucontrol] DELETE_STREAM tag:642 channel:4
[I][000075448.827291][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node deactivate
[I][000075448.827379][impl-node.c:353 node_update_state()] (pavucontrol-16) idle -> suspended
[I][000075448.827470][impl-node.c:1729 pw_impl_node_destroy()] (pavucontrol-16) destroy
[I][000075448.828289][impl-node.c:1729 pw_impl_node_destroy()] (pavucontrol-0) destroy
[I][000075448.828314][impl-node.c:353 node_update_state()] (pavucontrol-0) creating -> suspended
[I][000075448.828983][pulse-server.c:2050 do_create_record_stream()] pulse-server 0x616000001880: [pavucontrol] CREATE_RECORD_STREAM tag:643 corked:0 source-name:65610 source-index:4294967295
[I][000075448.830428][impl-node.c:353 node_update_state()] (pavucontrol-0) creating -> suspended
[I][000075448.830589][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:register active node
[I][000075448.831513][pulse-server.c:3262 do_set_port()] pulse-server 0x616000001880: [pavucontrol] SET_SINK_PORT tag:644 index:74 name:(null) port:headset-output
[I][000075448.831611][pulse-server.c:3262 do_set_port()] pulse-server 0x616000001880: [pavucontrol] SET_SINK_PORT tag:645 index:74 name:(null) port:headset-output
[I][000075448.831672][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INFO tag:646 idx:74 name:(null)
[I][000075448.831799][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_INFO tag:647 idx:65610 name:(null)
[I][000075448.831845][pulse-server.c:322 reply_error()] pulse-server 0x613000000900: [pavucontrol] ERROR command:23 (GET_SOURCE_INFO) tag:647 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075448.832019][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node deactivate
[I][000075448.832134][impl-node.c:1729 pw_impl_node_destroy()] (pavucontrol-102) destroy
[I][000075448.832177][impl-node.c:353 node_update_state()] (pavucontrol-102) idle -> suspended
[I][000075448.832374][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [Lollypop] GET_SINK_INPUT_INFO tag:1484 idx:76 name:(null)
[I][000075448.832971][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [Lollypop] GET_SINK_INPUT_INFO tag:1485 idx:76 name:(null)
[I][000075448.833171][pulse-server.c:3262 do_set_port()] pulse-server 0x616000001880: [pavucontrol] SET_SINK_PORT tag:648 index:74 name:(null) port:headset-output
[I][000075448.833849][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [Lollypop] GET_SINK_INPUT_INFO tag:1486 idx:76 name:(null)
[I][000075448.833887][conf.c:239 pw_conf_save_state()] config 0x606000006800: saved state '/home/xxxxx/.config/pipewire/media-session.d/restore-stream'
[I][000075448.834007][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [Lollypop] GET_SINK_INPUT_INFO tag:1487 idx:76 name:(null)
[I][000075448.834736][impl-node.c:353 node_update_state()] (pavucontrol-102) creating -> suspended
[I][000075448.835000][pulse-server.c:360 operation_complete()] pulse-server 0x613000000900: [pavucontrol] tag:644 complete
[I][000075448.835032][pulse-server.c:360 operation_complete()] pulse-server 0x613000000900: [pavucontrol] tag:645 complete
[I][000075448.835051][pulse-server.c:360 operation_complete()] pulse-server 0x613000000900: [pavucontrol] tag:648 complete
[I][000075448.835282][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node activate
[I][000075448.836298][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004dd60 00000300/0000030c
[I][000075448.836338][restore-stream.c:302 restore_stream()] stream 102: restore 'restore.stream.Input/Audio.application.name:pavucontrol' to { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.836823][pulse-server.c:1361 stream_control_info()] stream 0x617000004d80: mute changed 0
[I][000075448.836848][pulse-server.c:1357 stream_control_info()] stream 0x617000004d80: volume changed 1.000000
[I][000075448.836936][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:504 idx:102 name:(null)
[I][000075448.838131][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004dd60 00000300/0000030c
[I][000075448.838210][restore-stream.c:274 handle_props()] stream 102: save props restore.stream.Input/Audio.application.name:pavucontrol { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.838231][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:505 idx:102 name:(null)
[I][000075448.838296][policy-node.c:807 rescan_node()] trying to link node 102 exclusive:0 reconnect:0 target:76 follows-default:0
[I][000075448.838316][policy-node.c:206 configure_node()] node 102 monitor:1 channelmix 1->2
[I][000075448.838359][policy-node.c:656 link_nodes()] linking node 76 to node 102
[I][000075448.839459][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node flags changed
[I][000075448.839543][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:649 idx:102 name:(null)
[I][000075448.839753][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:650 idx:102 name:(null)
[I][000075448.841464][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:node flags changed
[I][000075448.842165][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:506 idx:102 name:(null)
[I][000075448.843367][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004dd60 00000500/0000070c
[I][000075448.844426][impl-link.c:1163 pw_context_create_link()] (76.0 -> 102.0) (Lollypop) -> (pavucontrol)
[I][000075448.845454][impl-link.c:1163 pw_context_create_link()] (76.1 -> 102.1) (Lollypop) -> (pavucontrol)
[I][000075448.845664][impl-link.c:107 link_update_state()] (76.0 -> 102.0) init -> negotiating
[I][000075448.845683][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004dd60 00000200/0000070c
[I][000075448.845759][impl-link.c:107 link_update_state()] (76.1 -> 102.1) init -> negotiating
[I][000075448.845776][restore-stream.c:274 handle_props()] stream 102: save props restore.stream.Input/Audio.application.name:pavucontrol { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.847145][impl-link.c:107 link_update_state()] (76.0 -> 102.0) negotiating -> allocating
[I][000075448.847217][impl-link.c:107 link_update_state()] (76.1 -> 102.1) negotiating -> allocating
[I][000075448.847720][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:555 idx:102 name:(null)
[I][000075448.847796][impl-link.c:107 link_update_state()] (76.0 -> 102.0) allocating -> paused
[I][000075448.847856][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link prepared
[I][000075448.847886][impl-node.c:789 pw_impl_node_set_driver()] (pavucontrol-102) -> change driver (pavucontrol-102 -> bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink-74)
[I][000075448.848018][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:556 idx:102 name:(null)
[I][000075448.848114][impl-link.c:559 pw_impl_link_activate()] (76.0 -> 102.0) activated
[I][000075448.848142][impl-link.c:107 link_update_state()] (76.0 -> 102.0) paused -> active
[I][000075448.848193][impl-link.c:107 link_update_state()] (76.1 -> 102.1) allocating -> paused
[I][000075448.848222][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:link prepared
[I][000075448.848235][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:557 idx:102 name:(null)
[I][000075448.848266][impl-link.c:559 pw_impl_link_activate()] (76.1 -> 102.1) activated
[I][000075448.848278][impl-link.c:107 link_update_state()] (76.1 -> 102.1) paused -> active
[I][000075448.848764][pulse-server.c:1232 fix_record_buffer_attr()] pulse-server 0x617000004d80: [pavucontrol] maxlength:4194304 fragsize:4 minfrag:4
[I][000075448.849389][impl-node.c:889 check_properties()] (pavucontrol-16) latency:1/25 ->quantum 1024/48000
[I][000075448.849413][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:quantum changed
[I][000075448.849496][pulse-server.c:1286 reply_create_record_stream()] pulse-server 0x617000004d80: [pavucontrol] reply CREATE_RECORD_STREAM tag:643 latency:1/25
[I][000075448.849639][pulse-server.c:1423 get_buffers_param()] stream 0x617000004d80: stride 4 maxsize 256 size 4 buffers 64
[I][000075448.849745][impl-node.c:889 check_properties()] (pavucontrol-0) latency:1/25 ->quantum 1024/48000
[I][000075448.850148][merger.c:862 setup_convert()] merger 0x7f1cac77f0a0: Spa:Enum:AudioFormat:F32P/1@48000x2->Spa:Enum:AudioFormat:F32P/2@48000
[I][000075448.850251][fmtconvert.c:166 setup_convert()] fmtconvert 0x7f1cac9e7890: Spa:Enum:AudioFormat:F32P/1@25->Spa:Enum:AudioFormat:F32LE/1@25
[I][000075448.850332][channelmix.c:336 setup_convert()] channelmix 0x7f1cac9c8120: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/1@48000 00000018:00000004
[I][000075448.850630][resample.c:150 setup_convert()] resample 0x7f1cac9de820: Spa:Enum:AudioFormat:F32P/1@48000->Spa:Enum:AudioFormat:F32P/1@25
[I][000075448.851104][impl-node.c:353 node_update_state()] (pavucontrol-16) suspended -> running
[I][000075448.851161][impl-node.c:889 check_properties()] (pavucontrol-102) latency:1/25 ->quantum 1024/48000
[I][000075448.851167][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:651 idx:102 name:(null)
[I][000075448.851191][context.c:958 pw_context_recalc_graph()] context 0x618000000480: busy:0 reason:quantum changed
[I][000075448.851702][impl-node.c:353 node_update_state()] (pavucontrol-102) suspended -> running
[I][000075448.852024][restore-stream.c:451 object_update()] restore-stream 0x60e0000009e0: stream 0x60c00004dd60 00000300/0000070c
[I][000075448.852129][restore-stream.c:274 handle_props()] stream 102: save props restore.stream.Input/Audio.application.name:pavucontrol { "volume": 1.000000, "mute": false, "volumes": [ 1.000000, 1.000000 ], "channels": [ "FL", "FR" ] }
[I][000075448.852523][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_OUTPUT_INFO tag:652 idx:102 name:(null)
[I][000075448.852734][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_OUTPUT_INFO tag:507 idx:102 name:(null)
[I][000075448.853060][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_OUTPUT_INFO tag:558 idx:102 name:(null)
[I][000075449.852726][conf.c:239 pw_conf_save_state()] config 0x606000006800: saved state '/home/xxxxx/.config/pipewire/media-session.d/restore-stream'
[I][000075450.670678][module-protocol-native.c:441 client_new()] server 0x612000000c40: security label not available
[I][000075450.671105][module-access.c:179 context_check_access()] client 0x616000036080 has trusted pid 78558
[I][000075450.671165][module-access.c:243 context_check_access()] access 0x60b000026b00: client 0x616000036080 'unrestricted' access granted
[I][000075450.673215][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_CLIENT_INFO tag:508 idx:71 name:(null)
[I][000075450.673323][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_CLIENT_INFO tag:653 idx:71 name:(null)
[I][000075450.673400][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_CLIENT_INFO tag:559 idx:71 name:(null)
[I][000075450.712083][module-protocol-native.c:286 handle_client_error()] protocol-native 0x610000000940: client 0x616000036080 disconnected
adjusting the volume slightly
[I][000075454.955869][pulse-server.c:3133 do_set_volume()] pulse-server 0x616000001880: [GNOME Shell Volume Control] SET_SINK_VOLUME tag:560 index:74 name:(null)
[I][000075454.956018][volume.c:49 volume_compare()] 0: val 0.073934<>0.063858
[I][000075454.956104][pulse-server.c:3133 do_set_volume()] pulse-server 0x616000001880: [GNOME Shell Volume Control] SET_SINK_VOLUME tag:561 index:74 name:(null)
[I][000075454.956176][volume.c:49 volume_compare()] 0: val 0.073934<>0.063858
[I][000075454.959459][default-routes.c:728 handle_route()] device 73: port 'headset-output'
[I][000075454.959639][default-routes.c:503 save_route()] device 73: route properties changed default.route.bluez_card.XX_XX_XX_XX_XX_XX:output:headset-output { "mute": false, "volumes": [ 0.063858, 0.063858 ], "channels": [ "FL", "FR" ], "latencyOffsetNsec": 0 }
[I][000075454.959761][default-routes.c:547 save_profile()] device 73: profile a2dp-sink-sbc unchanged ([ "headset-output" ])
[I][000075454.959862][pulse-server.c:360 operation_complete()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] tag:560 complete
[I][000075454.959977][pulse-server.c:360 operation_complete()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] tag:561 complete
[I][000075454.960630][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SINK_INFO tag:509 idx:74 name:(null)
[I][000075454.960894][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Volume Control Media Keys] GET_SOURCE_INFO tag:510 idx:65610 name:(null)
[I][000075454.960987][pulse-server.c:322 reply_error()] pulse-server 0x613000000ac0: [GNOME Volume Control Media Keys] ERROR command:23 (GET_SOURCE_INFO) tag:510 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075454.961109][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SINK_INFO tag:654 idx:74 name:(null)
[I][000075454.961419][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [pavucontrol] GET_SOURCE_INFO tag:655 idx:65610 name:(null)
[I][000075454.961486][pulse-server.c:322 reply_error()] pulse-server 0x613000000900: [pavucontrol] ERROR command:23 (GET_SOURCE_INFO) tag:655 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075454.961897][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SINK_INFO tag:562 idx:74 name:(null)
[I][000075454.962143][pulse-server.c:4400 do_get_info()] pulse-server 0x616000001880: [GNOME Shell Volume Control] GET_SOURCE_INFO tag:563 idx:65610 name:(null)
[I][000075454.962212][pulse-server.c:322 reply_error()] pulse-server 0x613000004f00: [GNOME Shell Volume Control] ERROR command:23 (GET_SOURCE_INFO) tag:563 error:5 (Nincs ilyen fájl vagy könyvtár)
[I][000075454.963475][pulse-server.c:3262 do_set_port()] pulse-server 0x616000001880: [pavucontrol] SET_SINK_PORT tag:656 index:74 name:(null) port:headset-output
[I][000075454.964303][pulse-server.c:360 operation_complete()] pulse-server 0x613000000900: [pavucontrol] tag:656 complete
[I][000075455.960293][conf.c:239 pw_conf_save_state()] config 0x606000006500: saved state '/home/xxxxx/.config/pipewire/media-session.d/default-routes'
before profile switching
{
"id": 74,
"type": "PipeWire:Interface:Node",
"version": 3,
"permissions": [ "r", "w", "x", "m" ],
"info": {
"max-input-ports": 64,
"max-output-ports": 0,
"change-mask": [ "input-ports", "output-ports", "state", "props", "params" ],
"n-input-ports": 2,
"n-output-ports": 2,
"state": "running",
"error": null,
"props": {
"api.bluez5.transport": "",
"api.bluez5.profile": "a2dp-sink",
"api.bluez5.codec": "sbc",
"api.bluez5.address": "XX:XX:XX:XX:XX:XX",
"device.routes": 1,
"card.profile.device": 1,
"device.form-factor": "headset",
"device.bus": "bluetooth",
"device.id": 73,
"node.description": "DIBT2208",
"node.name": "bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink",
"factory.name": "api.bluez5.a2dp.sink",
"priority.driver": 1010,
"priority.session": 1010,
"device.icon-name": "audio-headset-bluetooth",
"node.pause-on-idle": false,
"factory.id": 8,
"device.api": "bluez5",
"media.class": "Audio/Sink",
"node.driver": true,
"node.latency": "512/48000",
"factory.mode": "merge",
"audio.adapt.follower": "",
"library.name": "audioconvert/libspa-audioconvert",
"object.id": 74,
"client.id": 32
},
"params": {
"EnumFormat": [
{
"mediaType": "audio",
"mediaSubtype": "raw",
"format": "S16LE",
"rate": 48000,
"channels": 2,
"position": [ "FL", "FR" ]
}
],
"PropInfo": [
{
"id": "volume",
"name": "Volume",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 }
},
{
"id": "mute",
"name": "Mute",
"type": {
"default": false,
"alt1": false,
"alt2": true
}
},
{
"id": "channelVolumes",
"name": "Channel Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "channelMap",
"name": "Channel Map",
"type": "",
"container": "Array"
},
{
"id": "softVolumes",
"name": "Soft Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "monitorMute",
"name": "Monitor Mute",
"type": {
"default": false,
"alt1": false,
"alt2": true
}
},
{
"id": "monitorVolumes",
"name": "Monitor Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "minLatency",
"name": "The minimum latency",
"type": { "default": 512, "min": 1, "max": 2147483647 }
},
{
"id": "maxLatency",
"name": "The maximum latency",
"type": { "default": 1024, "min": 1, "max": 2147483647 }
},
{
"id": "latencyOffsetNsec",
"name": "Latency offset (ns)",
"type": { "default": 0, "min": -9223372036854775808, "max": 9223372036854775807 }
}
],
"Props": [
{
"volume": 1.000000,
"mute": false,
"channelVolumes": [ 0.073934, 0.073934 ],
"channelMap": [ "FL", "FR" ],
"softMute": false,
"softVolumes": [ 0.073934, 0.073934 ],
"monitorMute": false,
"monitorVolumes": [ 1.000000, 1.000000 ]
},
{
"minLatency": 512,
"maxLatency": 1024,
"latencyOffsetNsec": 0
}
],
"Format": [
{
"mediaType": "audio",
"mediaSubtype": "raw",
"format": "S16LE",
"rate": 48000,
"channels": 2,
"position": [ "FL", "FR" ]
}
],
"EnumPortConfig": [
{
"direction": "Input",
"mode": "dsp"
},
{
"direction": "Output",
"mode": "dsp"
},
{
"direction": "Input",
"mode": "convert"
},
{
"direction": "Output",
"mode": "convert"
}
],
"PortConfig": [
{
"direction": "Input",
"mode": "dsp"
},
{
"direction": "Output",
"mode": "convert"
}
],
"Invalid": [
]
}
}
},
after profile switching
{
"id": 74,
"type": "PipeWire:Interface:Node",
"version": 3,
"permissions": [ "r", "w", "x", "m" ],
"info": {
"max-input-ports": 64,
"max-output-ports": 0,
"change-mask": [ "input-ports", "output-ports", "state", "props", "params" ],
"n-input-ports": 2,
"n-output-ports": 2,
"state": "running",
"error": null,
"props": {
"api.bluez5.transport": "",
"api.bluez5.profile": "a2dp-sink",
"api.bluez5.codec": "sbc",
"api.bluez5.address": "XX:XX:XX:XX:XX:XX",
"device.routes": 1,
"card.profile.device": 1,
"device.form-factor": "headset",
"device.bus": "bluetooth",
"device.id": 73,
"node.description": "DIBT2208",
"node.name": "bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink",
"factory.name": "api.bluez5.a2dp.sink",
"priority.driver": 1010,
"priority.session": 1010,
"device.icon-name": "audio-headset-bluetooth",
"node.pause-on-idle": false,
"factory.id": 8,
"device.api": "bluez5",
"media.class": "Audio/Sink",
"node.driver": true,
"node.latency": "512/48000",
"factory.mode": "merge",
"audio.adapt.follower": "",
"library.name": "audioconvert/libspa-audioconvert",
"object.id": 74,
"client.id": 32
},
"params": {
"EnumFormat": [
{
"mediaType": "audio",
"mediaSubtype": "raw",
"format": "S16LE",
"rate": 48000,
"channels": 2,
"position": [ "FL", "FR" ]
}
],
"PropInfo": [
{
"id": "volume",
"name": "Volume",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 }
},
{
"id": "mute",
"name": "Mute",
"type": {
"default": false,
"alt1": false,
"alt2": true
}
},
{
"id": "channelVolumes",
"name": "Channel Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "channelMap",
"name": "Channel Map",
"type": "",
"container": "Array"
},
{
"id": "softVolumes",
"name": "Soft Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "monitorMute",
"name": "Monitor Mute",
"type": {
"default": false,
"alt1": false,
"alt2": true
}
},
{
"id": "monitorVolumes",
"name": "Monitor Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "minLatency",
"name": "The minimum latency",
"type": { "default": 512, "min": 1, "max": 2147483647 }
},
{
"id": "maxLatency",
"name": "The maximum latency",
"type": { "default": 1024, "min": 1, "max": 2147483647 }
},
{
"id": "latencyOffsetNsec",
"name": "Latency offset (ns)",
"type": { "default": 0, "min": -9223372036854775808, "max": 9223372036854775807 }
}
],
"Props": [
{
"volume": 1.000000,
"mute": false,
"channelVolumes": [ 1.000000, 1.000000 ],
"channelMap": [ "FL", "FR" ],
"softMute": false,
"softVolumes": [ 1.000000, 1.000000 ],
"monitorMute": false,
"monitorVolumes": [ 1.000000, 1.000000 ]
},
{
"minLatency": 512,
"maxLatency": 1024,
"latencyOffsetNsec": 0
}
],
"Format": [
{
"mediaType": "audio",
"mediaSubtype": "raw",
"format": "S16LE",
"rate": 48000,
"channels": 2,
"position": [ "FL", "FR" ]
}
],
"EnumPortConfig": [
{
"direction": "Input",
"mode": "dsp"
},
{
"direction": "Output",
"mode": "dsp"
},
{
"direction": "Input",
"mode": "convert"
},
{
"direction": "Output",
"mode": "convert"
}
],
"PortConfig": [
{
"direction": "Input",
"mode": "dsp"
},
{
"direction": "Output",
"mode": "convert"
}
],
"Invalid": [
]
}
}
},
after adjusting the volume slightly
{
"id": 74,
"type": "PipeWire:Interface:Node",
"version": 3,
"permissions": [ "r", "w", "x", "m" ],
"info": {
"max-input-ports": 64,
"max-output-ports": 0,
"change-mask": [ "input-ports", "output-ports", "state", "props", "params" ],
"n-input-ports": 2,
"n-output-ports": 2,
"state": "running",
"error": null,
"props": {
"api.bluez5.transport": "",
"api.bluez5.profile": "a2dp-sink",
"api.bluez5.codec": "sbc",
"api.bluez5.address": "XX:XX:XX:XX:XX:XX",
"device.routes": 1,
"card.profile.device": 1,
"device.form-factor": "headset",
"device.bus": "bluetooth",
"device.id": 73,
"node.description": "DIBT2208",
"node.name": "bluez_output.XX_XX_XX_XX_XX_XX.a2dp-sink",
"factory.name": "api.bluez5.a2dp.sink",
"priority.driver": 1010,
"priority.session": 1010,
"device.icon-name": "audio-headset-bluetooth",
"node.pause-on-idle": false,
"factory.id": 8,
"device.api": "bluez5",
"media.class": "Audio/Sink",
"node.driver": true,
"node.latency": "512/48000",
"factory.mode": "merge",
"audio.adapt.follower": "",
"library.name": "audioconvert/libspa-audioconvert",
"object.id": 74,
"client.id": 32
},
"params": {
"EnumFormat": [
{
"mediaType": "audio",
"mediaSubtype": "raw",
"format": "S16LE",
"rate": 48000,
"channels": 2,
"position": [ "FL", "FR" ]
}
],
"PropInfo": [
{
"id": "volume",
"name": "Volume",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 }
},
{
"id": "mute",
"name": "Mute",
"type": {
"default": false,
"alt1": false,
"alt2": true
}
},
{
"id": "channelVolumes",
"name": "Channel Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "channelMap",
"name": "Channel Map",
"type": "",
"container": "Array"
},
{
"id": "softVolumes",
"name": "Soft Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "monitorMute",
"name": "Monitor Mute",
"type": {
"default": false,
"alt1": false,
"alt2": true
}
},
{
"id": "monitorVolumes",
"name": "Monitor Volumes",
"type": { "default": 1.000000, "min": 0.000000, "max": 10.000000 },
"container": "Array"
},
{
"id": "minLatency",
"name": "The minimum latency",
"type": { "default": 512, "min": 1, "max": 2147483647 }
},
{
"id": "maxLatency",
"name": "The maximum latency",
"type": { "default": 1024, "min": 1, "max": 2147483647 }
},
{
"id": "latencyOffsetNsec",
"name": "Latency offset (ns)",
"type": { "default": 0, "min": -9223372036854775808, "max": 9223372036854775807 }
}
],
"Props": [
{
"volume": 1.000000,
"mute": false,
"channelVolumes": [ 0.063858, 0.063858 ],
"channelMap": [ "FL", "FR" ],
"softMute": false,
"softVolumes": [ 0.063858, 0.063858 ],
"monitorMute": false,
"monitorVolumes": [ 1.000000, 1.000000 ]
},
{
"minLatency": 512,
"maxLatency": 1024,
"latencyOffsetNsec": 0
}
],
"Format": [
{
"mediaType": "audio",
"mediaSubtype": "raw",
"format": "S16LE",
"rate": 48000,
"channels": 2,
"position": [ "FL", "FR" ]
}
],
"EnumPortConfig": [
{
"direction": "Input",
"mode": "dsp"
},
{
"direction": "Output",
"mode": "dsp"
},
{
"direction": "Input",
"mode": "convert"
},
{
"direction": "Output",
"mode": "convert"
}
],
"PortConfig": [
{
"direction": "Input",
"mode": "dsp"
},
{
"direction": "Output",
"mode": "convert"
}
],
"Invalid": [
]
}
}
},
Note that the channelVolumes
and softVolumes
are set to 1.0 after profile switching.
I can provide more information/logs if needed.