[Pkg-utopia-maintainers] Bug#1110731: pipewire-pulse: virtuoso card only front channels work after update to Debian 13.0
Sergey Matsievskiy
matsievskiysv at gmail.com
Sun Aug 10 16:00:24 BST 2025
Package: pipewire-pulse
Version: 1.4.2-1
Severity: important
X-Debbugs-Cc: matsievskiysv at gmail.com
Dear Maintainer,
After the update from Debian 12 to 13 sound comes only from the front speakers
regardless of profile and balance configuration. In another words, when I select
any of the Analog Surround Output profiles, the system behaves as if the Analog
Stereo Output was enabled.
pavucontrol was used for the profile selection and volume adjustment.
I can configure the channels volume via `alsamixer` and hear sound from all the
channels using the command `speaker-test -Dsurround51 -c 6`.
I attach the log from the `pipewire-pulse` daemon with the following actions: change the profile, adjust the balance and play some music.
-- System Information:
Debian Release: 13.0
APT prefers stable-updates
APT policy: (500, 'stable-updates'), (500, 'stable-security'), (500, 'stable-debug'), (500, 'stable')
Architecture: amd64 (x86_64)
Kernel: Linux 6.12.38+deb13-amd64 (SMP w/8 CPU threads; PREEMPT)
Kernel taint flags: TAINT_FIRMWARE_WORKAROUND
Locale: LANG=en_GB.UTF-8, LC_CTYPE=en_GB.UTF-8 (charmap=UTF-8), LANGUAGE=en_GB:en
Shell: /bin/sh linked to /usr/bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled
Versions of packages pipewire-pulse depends on:
ii dconf-gsettings-backend [gsettings-backend] 0.40.0-5
ii init-system-helpers 1.68
ii pipewire 1.4.2-1
Versions of packages pipewire-pulse recommends:
ii wireplumber 0.5.8-2
Versions of packages pipewire-pulse suggests:
ii libspa-0.2-bluetooth 1.4.2-1
ii pulseaudio-utils 17.0+dfsg1-2+b1
-- no debconf information
-------------- next part --------------
[I][17:56:10.888821] pw.conf | [ conf.c: 415 conf_load()] 0x55be6a0d8dc0: loaded config '/usr/share/pipewire/pipewire-pulse.conf' with 9 items
[I][17:56:10.888969] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.properties'
[I][17:56:10.888993] pw.context | [ context.c: 388 pw_context_new()] 0x55be6a0d7550: parsed 0 context.properties items
[I][17:56:10.889058] pw.context | [ context.c: 269 setup_data_loops()] created data loop 'data-loop.0'
[I][17:56:10.889079] pw.context | [ context.c: 272 setup_data_loops()] created 1 data-loops
[I][17:56:10.889553] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.spa-libs'
[I][17:56:10.889629] pw.context | [ context.c: 481 pw_context_new()] 0x55be6a0d7550: parsed 2 context.spa-libs items
[I][17:56:10.889653] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.modules'
[I][17:56:10.889673] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-rt args:{
nice.level = -11
#rt.prio = 83
#rt.time.soft = -1
#rt.time.hard = -1
#uclamp.min = 0
#uclamp.max = 1024
}
[I][17:56:10.889907] mod.rt | [ module-rt.c: 585 check_realtime_privileges()] failed to set realtime policy: Operation not permitted
[I][17:56:10.889934] mod.rt | [ module-rt.c: 550 check_realtime_privileges()] Clamp rtprio 83 to 0
[I][17:56:10.889950] mod.rt | [ module-rt.c: 558 check_realtime_privileges()] Priority max (0) must be at least 11
[I][17:56:10.889964] mod.rt | [ module-rt.c: 594 check_realtime_privileges()] can't set rt prio to 83 (try increasing rlimits)
[I][17:56:10.890806] spa.loop | [ loop.c: 260 loop_create_queue()] 0x55be6a0e6f78 created queue 0x55be6a0e7550 idx:0 0x7fd5fe322740
[I][17:56:10.890837] pw.conf | [ conf.c: 610 load_module()] 0x55be6a0d7550: loaded module libpipewire-module-rt
[I][17:56:10.890856] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-protocol-native args:(null)
[I][17:56:10.891383] pw.conf | [ conf.c: 610 load_module()] 0x55be6a0d7550: loaded module libpipewire-module-protocol-native
[I][17:56:10.891405] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-client-node args:(null)
[I][17:56:10.891595] pw.conf | [ conf.c: 610 load_module()] 0x55be6a0d7550: loaded module libpipewire-module-client-node
[I][17:56:10.891623] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-adapter args:(null)
[I][17:56:10.891713] mod.rt | [ module-rt.c: 634 set_nice()] main thread nice level set to -11
[I][17:56:10.891735] pw.conf | [ conf.c: 610 load_module()] 0x55be6a0d7550: loaded module libpipewire-module-adapter
[I][17:56:10.891759] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-metadata args:(null)
[I][17:56:10.891905] pw.conf | [ conf.c: 610 load_module()] 0x55be6a0d7550: loaded module libpipewire-module-metadata
[I][17:56:10.891932] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-protocol-pulse args:{
# contents of pulse.properties can also be placed here
# to have config per server.
}
[I][17:56:10.893889] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:10.893925] pw.context | [ context.c: 284 data_loop_start()] starting data loop data-loop.0
[I][17:56:10.894022] mod.rt | [ module-rt.c: 821 do_make_realtime()] clamping requested priority 83 for thread 15146 between 1 and 20
[I][17:56:10.894035] spa.loop | [ loop.c: 260 loop_create_queue()] 0x55be6a0de048 created queue 0x55be6a107250 idx:0 0x7fd5fe322740
[I][17:56:10.894062] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2614937485539
[I][17:56:10.894077] mod.rt | [ module-rt.c: 831 do_make_realtime()] acquired realtime priority 20 for thread 15146 using RTKit
[I][17:56:10.894088] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.properties'
[I][17:56:10.894112] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.properties.rules'
[W][17:56:10.894161] mod.protocol-pulse | [ server.c: 639 start_unix_server()] server 0x55be6a106c20: unlinking stale socket '/run/user/1000/pulse/native'
[I][17:56:10.894204] mod.protocol-pulse | [ server.c: 664 start_unix_server()] server 0x55be6a106c20: listening on unix:/run/user/1000/pulse/native
[I][17:56:10.894275] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:10.894295] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2614937855632
[I][17:56:10.895149] mod.protocol-pulse | [ pulse-server.c: 5451 parse_bool()] : defaults: pulse.allow-module-loading = true
[I][17:56:10.895185] mod.protocol-pulse | [ pulse-server.c: 5396 parse_frac()] : defaults: pulse.min.req = 128/48000
[I][17:56:10.895201] mod.protocol-pulse | [ pulse-server.c: 5396 parse_frac()] : defaults: pulse.default.req = 960/48000
[I][17:56:10.895214] mod.protocol-pulse | [ pulse-server.c: 5396 parse_frac()] : defaults: pulse.min.frag = 128/48000
[I][17:56:10.895224] mod.protocol-pulse | [ pulse-server.c: 5396 parse_frac()] : defaults: pulse.default.frag = 96000/48000
[I][17:56:10.895235] mod.protocol-pulse | [ pulse-server.c: 5396 parse_frac()] : defaults: pulse.default.tlength = 96000/48000
[I][17:56:10.895244] mod.protocol-pulse | [ pulse-server.c: 5396 parse_frac()] : defaults: pulse.min.quantum = 128/48000
[I][17:56:10.895256] mod.protocol-pulse | [ pulse-server.c: 5426 parse_format()] : defaults: pulse.default.format = F32LE
[I][17:56:10.895268] mod.protocol-pulse | [ pulse-server.c: 5411 parse_position()] : defaults: pulse.default.position = [ FL FR ]
[I][17:56:10.895278] mod.protocol-pulse | [ pulse-server.c: 5440 parse_uint32()] : defaults: pulse.idle.timeout = 0
[I][17:56:10.895289] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.cmd'
[I][17:56:10.895310] mod.protocol-pulse | [ module.c: 69 module_load()] load module index:536870912 name:module-always-sink
[I][17:56:10.895329] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x55be6a0d7550: name:libpipewire-module-fallback-sink args:{ }
[I][17:56:10.895512] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:10.895555] mod.protocol-pulse | [ module.c: 69 module_load()] load module index:536870913 name:module-device-manager
[I][17:56:10.895573] mod.protocol-pulse | [ module.c: 69 module_load()] load module index:536870914 name:module-device-restore
[I][17:56:10.895592] mod.protocol-pulse | [ module.c: 69 module_load()] load module index:536870915 name:module-stream-restore
[I][17:56:10.895610] pw.conf | [ conf.c: 610 load_module()] 0x55be6a0d7550: loaded module libpipewire-module-protocol-pulse
[I][17:56:10.895634] pw.context | [ context.c: 485 pw_context_new()] 0x55be6a0d7550: parsed 6 context.modules items
[I][17:56:10.895644] pw.context | [ context.c: 490 pw_context_new()] 0x55be6a0d7550: parsed 0 context.objects items
[I][17:56:10.895654] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.exec'
[I][17:56:10.895667] pw.context | [ context.c: 493 pw_context_new()] 0x55be6a0d7550: parsed 0 context.exec items
[I][17:56:10.895683] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:log.level type: value:3
[I][17:56:10.895695] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.rate type: value:48000
[I][17:56:10.895706] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.allowed-rates type: value:[ 48000 ]
[I][17:56:10.895717] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.quantum type: value:1024
[I][17:56:10.895727] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.min-quantum type: value:32
[I][17:56:10.895737] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.max-quantum type: value:2048
[I][17:56:10.895748] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.force-quantum type: value:0
[I][17:56:10.895759] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x55be6a113fa0: add id:0 key:clock.force-rate type: value:0
[I][17:56:10.895771] default | [ pipewire.c: 129 main()] start main loop
[I][17:56:14.660391] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a114540 AUTH tag:0 version:35
[I][17:56:14.660600] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:14.660638] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:14.660700] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:14.662656] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:88 index:1415
[I][17:56:14.662818] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:14.662946] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a114540 [pactl] disconnected
[I][17:56:14.669433] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a114540 AUTH tag:0 version:35
[I][17:56:14.669603] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:14.669636] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:14.669678] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:14.671424] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:88 index:1416
[I][17:56:14.671518] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:14.671928] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a114540 [pactl] disconnected
[I][17:56:19.209130] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a114540 AUTH tag:0 version:35
[I][17:56:19.234488] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:19.234573] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [PulseAudio Volume Control] SET_CLIENT_NAME tag:1
[I][17:56:19.234632] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:19.236242] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [PulseAudio Volume Control] reply tag:1 id:88 index:1417
[I][17:56:19.243221] mod.protocol-pulse | [ pulse-server.c: 1062 do_subscribe()] [PulseAudio Volume Control] SUBSCRIBE tag:2 mask:000002af
[I][17:56:19.243273] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:3
[I][17:56:19.243332] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [PulseAudio Volume Control] GET_CLIENT_INFO_LIST tag:4
[I][17:56:19.243377] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [PulseAudio Volume Control] GET_CARD_INFO_LIST tag:5
[I][17:56:19.243532] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [PulseAudio Volume Control] GET_SINK_INFO_LIST tag:6
[I][17:56:19.243683] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [PulseAudio Volume Control] GET_SOURCE_INFO_LIST tag:7
[I][17:56:19.243806] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO_LIST tag:8
[I][17:56:19.243827] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO_LIST tag:9
[I][17:56:19.243840] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:10 index:4294967295 name:module-stream-restore
[I][17:56:19.243850] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-stream-restore READ tag:10
[I][17:56:19.243875] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:11 index:4294967295 name:module-stream-restore
[I][17:56:19.243885] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-stream-restore SUBSCRIBE tag:11
[I][17:56:19.243900] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:12 index:4294967295 name:module-device-restore
[I][17:56:19.243907] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS_ALL tag:12
[I][17:56:19.243926] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:13 index:4294967295 name:module-device-restore
[I][17:56:19.243934] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore SUBSCRIBE tag:13
[I][17:56:19.243953] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:14 index:4294967295 name:module-device-manager
[I][17:56:19.243964] mod.protocol-pulse | [ reply.c: 54 reply_error()] client 0x55be6a114540 [PulseAudio Volume Control]: ERROR command:87 (EXTENSION) tag:14 error:19 (Operation not supported)
[I][17:56:19.243979] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:15 index:4294967295 name:module-device-manager
[I][17:56:19.243989] mod.protocol-pulse | [ reply.c: 54 reply_error()] client 0x55be6a114540 [PulseAudio Volume Control]: ERROR command:87 (EXTENSION) tag:15 error:19 (Operation not supported)
[I][17:56:19.255676] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:16 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:19.255738] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:17 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:19.255753] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:18 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:19.265562] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:19 index:4294967295 name:module-device-restore
[I][17:56:19.265597] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:19
[I][17:56:19.265634] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:20 index:4294967295 name:module-device-restore
[I][17:56:19.265644] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:20
[I][17:56:19.275685] mod.protocol-pulse | [ pulse-server.c: 1869 do_create_record_stream()] [PulseAudio Volume Control] CREATE_RECORD_STREAM tag:21 corked:0 source-name:57 source-index:4294967295
[I][17:56:19.275730] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:19.275740] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:19.275755] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:19.275805] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:19.275817] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623319377380
[I][17:56:19.275825] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2623319377380
[I][17:56:19.275844] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.275852] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623319413058
[I][17:56:19.276062] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a1a3b18: loaded converter audio.convert, hnd 0x55be6a1a40a0, convert 0x55be6a1a40b8
[I][17:56:19.276085] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a19baa0: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:19.276106] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.276120] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623319680570
[I][17:56:19.276160] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a1ca9f0: sync group '(null)'->'group.sync.0'
[I][17:56:19.276176] pw.node | [ impl-node.c: 1266 check_properties()] (PulseAudio Volume Control-0) latency:0/0 -> 1/144
[I][17:56:19.276187] pw.node | [ impl-node.c: 1297 check_properties()] (PulseAudio Volume Control-0) rate:0/0 -> 1/144
[I][17:56:19.276210] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-0) creating -> suspended
[I][17:56:19.276334] mod.protocol-pulse | [ pulse-server.c: 1869 do_create_record_stream()] [PulseAudio Volume Control] CREATE_RECORD_STREAM tag:22 corked:0 source-name:58 source-index:4294967295
[I][17:56:19.276362] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:19.276375] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:19.276396] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:19.276456] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:19.276473] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623320033985
[I][17:56:19.276486] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2623320033985
[I][17:56:19.276499] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.276510] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623320071066
[I][17:56:19.276621] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a1d1ac8: loaded converter audio.convert, hnd 0x55be6a1d2050, convert 0x55be6a1d2068
[I][17:56:19.276639] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a1cd630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:19.276658] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.276682] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623320240195
[I][17:56:19.276740] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a1f89a0: sync group '(null)'->'group.sync.0'
[I][17:56:19.276757] pw.node | [ impl-node.c: 1266 check_properties()] (PulseAudio Volume Control-0) latency:0/0 -> 1/144
[I][17:56:19.276771] pw.node | [ impl-node.c: 1297 check_properties()] (PulseAudio Volume Control-0) rate:0/0 -> 1/144
[I][17:56:19.276797] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-0) creating -> suspended
[I][17:56:19.283090] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6a19b740: mute changed 0
[I][17:56:19.283132] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a19b740: volume changed 1.000000
[I][17:56:19.283143] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:19.283296] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a19baa0: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:19.285146] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:23 index:1418 name:(null)
[I][17:56:19.285198] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:24 index:1419 name:(null)
[I][17:56:19.285231] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:25 index:1418 name:(null)
[I][17:56:19.285328] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.285348] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623328908543
[I][17:56:19.285567] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.285599] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623329159205
[I][17:56:19.286342] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [PulseAudio Volume Control] got format:F32LE rate:144 channels:1
[I][17:56:19.286393] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:19.286410] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:19.286452] mod.protocol-pulse | [ pulse-server.c: 729 reply_create_record_stream()] [PulseAudio Volume Control] reply CREATE_RECORD_STREAM tag:21 index:1418 latency:6944
[I][17:56:19.286470] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [PulseAudio Volume Control] stride 4 size 32768
[I][17:56:19.286629] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6a1a40a0: Spa:Enum:AudioFormat:F32P/2 at 48000->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:19.286655] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:19.286669] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out MONO (0000000000000004)
[I][17:56:19.286680] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6a1a40a0: Spa:Enum:AudioFormat:F32P/2 at 48000->Spa:Enum:AudioFormat:F32P/1 at 48000 00000018:00000004
[I][17:56:19.286699][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000006 00000000
[I][17:56:19.286709][channelmix-ops.c: 314 make_matrix()] assign STEREO to MONO (1.000000)
[I][17:56:19.286723][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:19.286735][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:19.286745][channelmix-ops.c: 662 make_matrix()] MONO 1.000 1.000 2.000000
[I][17:56:19.286765][channelmix-ops.c: 681 make_matrix()] normalize 2.000000
[I][17:56:19.286778] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6a1a40a0: Spa:Enum:AudioFormat:F32P/1 at 48000->Spa:Enum:AudioFormat:F32P/1 at 144
[I][17:56:19.286790] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6a1a40a0: Spa:Enum:AudioFormat:F32P/1 at 144->Spa:Enum:AudioFormat:F32LE/1 at 144
[I][17:56:19.286924] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-94) suspended -> running
[I][17:56:19.327320] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6a1ccd00: mute changed 0
[I][17:56:19.327352] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a1ccd00: volume changed 1.000000
[I][17:56:19.327370] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:19.327787] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a1cd630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:19.332573] spa.loop | [ loop.c: 260 loop_create_queue()] 0x55be6a0d6e78 created queue 0x7fd5f0000b70 idx:0 0x7fd5fce646c0
[I][17:56:19.332957] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:26 index:57 name:(null)
[I][17:56:19.333051] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:27 index:57 name:(null)
[I][17:56:19.333108] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:28 index:48 name:(null)
[I][17:56:19.333157] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:29 index:1418 name:(null)
[I][17:56:19.333183] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:30 index:1419 name:(null)
[I][17:56:19.333289] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.333308] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623376868341
[I][17:56:19.333382] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.333404] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623376964360
[I][17:56:19.333472] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.333486] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623377046503
[I][17:56:19.333545] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.333559] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623377119544
[I][17:56:19.333611] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.333624] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623377184099
[I][17:56:19.333677] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:19.333690] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2623377250464
[I][17:56:19.335096] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [PulseAudio Volume Control] got format:F32LE rate:144 channels:1
[I][17:56:19.335150] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:19.335167] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:19.335209] mod.protocol-pulse | [ pulse-server.c: 729 reply_create_record_stream()] [PulseAudio Volume Control] reply CREATE_RECORD_STREAM tag:22 index:1419 latency:6944
[I][17:56:19.335228] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [PulseAudio Volume Control] stride 4 size 32768
[I][17:56:19.335368] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6a1d2050: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:19.335396] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR RL RR FC LFE (0000000000003078)
[I][17:56:19.335410] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out MONO (0000000000000004)
[I][17:56:19.335421] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6a1d2050: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/1 at 48000 00003078:00000004
[I][17:56:19.335434][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000c1e 00000000
[I][17:56:19.335446][channelmix-ops.c: 294 make_matrix()] assign FC to MONO (1.000000)
[I][17:56:19.335458][channelmix-ops.c: 314 make_matrix()] assign STEREO to MONO (1.000000)
[I][17:56:19.335468][channelmix-ops.c: 399 make_matrix()] assign RL+RR to MONO (1.000000)
[I][17:56:19.335478][channelmix-ops.c: 493 make_matrix()] assign LFE to MONO (1.000000)
[I][17:56:19.335489][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:19.335503][channelmix-ops.c: 660 make_matrix()] FL FR FC LFE RL RR
[I][17:56:19.335513][channelmix-ops.c: 662 make_matrix()] MONO 1.000 1.000 1.000 1.000 1.000 1.000 6.000000
[I][17:56:19.335525][channelmix-ops.c: 681 make_matrix()] normalize 6.000000
[I][17:56:19.335537] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6a1d2050: Spa:Enum:AudioFormat:F32P/1 at 48000->Spa:Enum:AudioFormat:F32P/1 at 144
[I][17:56:19.335549] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6a1d2050: Spa:Enum:AudioFormat:F32P/1 at 144->Spa:Enum:AudioFormat:F32LE/1 at 144
[I][17:56:19.335694] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-110) suspended -> running
[I][17:56:19.336284] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:31 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:19.339277] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:32 index:58 name:(null)
[I][17:56:19.339382] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:33 index:58 name:(null)
[I][17:56:19.339456] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:34 index:50 name:(null)
[I][17:56:19.339534] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:35 index:1419 name:(null)
[I][17:56:19.344003] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:36 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:19.658948] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a1cef80 AUTH tag:0 version:35
[I][17:56:19.659175] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:19.659242] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:19.659356] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:19.660460] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:37 index:1444 name:(null)
[I][17:56:19.661725] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:70 index:1444
[I][17:56:19.661895] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:19.662044] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a1cef80 [pactl] disconnected
[I][17:56:19.668082] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a1cef80 AUTH tag:0 version:35
[I][17:56:19.668227] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:19.668274] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:19.668318] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:19.669245] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:38 index:1445 name:(null)
[I][17:56:19.670414] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:70 index:1445
[I][17:56:19.670542] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:19.670941] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a1cef80 [pactl] disconnected
[I][17:56:24.660185] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a19d010 AUTH tag:0 version:35
[I][17:56:24.660351] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:24.660387] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:24.660435] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:24.661455] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:39 index:1446 name:(null)
[I][17:56:24.662574] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:70 index:1446
[I][17:56:24.662745] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:24.662897] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a19d010 [pactl] disconnected
[I][17:56:24.669055] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a123b00 AUTH tag:0 version:35
[I][17:56:24.669243] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:24.669320] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:24.669416] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:24.670391] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:40 index:1447 name:(null)
[I][17:56:24.671365] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:70 index:1447
[I][17:56:24.671530] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:24.671959] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a123b00 [pactl] disconnected
[I][17:56:26.272957] mod.protocol-pulse | [ pulse-server.c: 4600 do_set_profile()] [PulseAudio Volume Control] SET_CARD_PROFILE tag:41 index:50 name:(null) profile:output:analog-stereo
[I][17:56:26.279471] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-110) running -> idle
[I][17:56:26.285474] mod.protocol-pulse | [ pulse-server.c: 2105 do_delete_stream()] [PulseAudio Volume Control] DELETE_STREAM tag:42 channel:1
[I][17:56:26.285820] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-110) idle -> suspended
[I][17:56:26.285871] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (PulseAudio Volume Control-110) destroy
[I][17:56:26.286090] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2623377250464
[I][17:56:26.286213] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:43
[I][17:56:26.290607] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:41 complete
[I][17:56:26.290683] mod.device-restore | [module-device-re: 122 emit_event()] [PulseAudio Volume Control] EVENT index:536870914 name:module-device-restore 0/1448
[I][17:56:26.290792] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:44 index:50 name:(null)
[I][17:56:26.290851] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:45 index:1448 name:(null)
[I][17:56:26.290916] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:46 index:1448 name:(null)
[I][17:56:26.290983] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:47 index:4294967295 name:module-device-restore
[I][17:56:26.291002] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:47
[I][17:56:26.294815] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:48 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:26.299418] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:49 index:4294967295 name:module-device-restore
[I][17:56:26.299446] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:49
[I][17:56:26.306021] mod.protocol-pulse | [ pulse-server.c: 1869 do_create_record_stream()] [PulseAudio Volume Control] CREATE_RECORD_STREAM tag:50 corked:0 source-name:1448 source-index:4294967295
[I][17:56:26.306058] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:26.306073] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:26.306097] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:26.306162] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:26.306178] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2630349738206
[I][17:56:26.306189] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2630349738206
[I][17:56:26.306206] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:26.306217] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2630349777197
[I][17:56:26.306325] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a38d9e8: loaded converter audio.convert, hnd 0x55be6a39edc0, convert 0x55be6a39edd8
[I][17:56:26.306346] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a1cd630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:26.306368] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:26.306380] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2630349940410
[I][17:56:26.306438] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a3930f0: sync group '(null)'->'group.sync.0'
[I][17:56:26.306457] pw.node | [ impl-node.c: 1266 check_properties()] (PulseAudio Volume Control-0) latency:0/0 -> 1/144
[I][17:56:26.306470] pw.node | [ impl-node.c: 1297 check_properties()] (PulseAudio Volume Control-0) rate:0/0 -> 1/144
[I][17:56:26.306498] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-0) creating -> suspended
[I][17:56:26.306607] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:51 index:1448 name:(null)
[I][17:56:26.306709] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:52 index:1448 name:(null)
[I][17:56:26.306825] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:53 index:50 name:(null)
[I][17:56:26.315159] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:54 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:26.315339] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:55 index:1453 name:(null)
[I][17:56:26.315392] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:56
[I][17:56:26.317308] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6a36f850: mute changed 0
[I][17:56:26.317333] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a36f850: volume changed 1.000000
[I][17:56:26.317344] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:26.317480] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a1cd630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:26.318174] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:57 index:1453 name:(null)
[I][17:56:26.319368] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:26.319388] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2630362948421
[I][17:56:26.319452] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:26.319467] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2630363027699
[I][17:56:26.320095] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [PulseAudio Volume Control] got format:F32LE rate:144 channels:1
[I][17:56:26.320134] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:26.320144] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:26.320182] mod.protocol-pulse | [ pulse-server.c: 729 reply_create_record_stream()] [PulseAudio Volume Control] reply CREATE_RECORD_STREAM tag:50 index:1453 latency:6944
[I][17:56:26.320203] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [PulseAudio Volume Control] stride 4 size 32768
[I][17:56:26.320300] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/2 at 48000->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:26.320316] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:26.320324] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out MONO (0000000000000004)
[I][17:56:26.320330] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/2 at 48000->Spa:Enum:AudioFormat:F32P/1 at 48000 00000018:00000004
[I][17:56:26.320338][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000006 00000000
[I][17:56:26.320345][channelmix-ops.c: 314 make_matrix()] assign STEREO to MONO (1.000000)
[I][17:56:26.320352][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:26.320359][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:26.320365][channelmix-ops.c: 662 make_matrix()] MONO 1.000 1.000 2.000000
[I][17:56:26.320371][channelmix-ops.c: 681 make_matrix()] normalize 2.000000
[I][17:56:26.320387] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/1 at 48000->Spa:Enum:AudioFormat:F32P/1 at 144
[I][17:56:26.320394] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/1 at 144->Spa:Enum:AudioFormat:F32LE/1 at 144
[I][17:56:26.320501] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-106) suspended -> running
[I][17:56:26.321912] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:58 index:1448 name:(null)
[I][17:56:26.321979] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:59 index:1448 name:(null)
[I][17:56:26.322029] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:60 index:1453 name:(null)
[I][17:56:29.662902] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a298f90 AUTH tag:0 version:35
[I][17:56:29.663033] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:29.663073] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:29.663138] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:29.663982] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:61 index:1460 name:(null)
[I][17:56:29.665062] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:109 index:1460
[I][17:56:29.665223] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:29.665345] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a298f90 [pactl] disconnected
[I][17:56:29.671486] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a298f90 AUTH tag:0 version:35
[I][17:56:29.671675] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:29.671732] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:29.671807] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:29.672721] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:62 index:1461 name:(null)
[I][17:56:29.673756] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:109 index:1461
[I][17:56:29.673905] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:29.674483] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a298f90 [pactl] disconnected
[I][17:56:32.256318] mod.protocol-pulse | [ pulse-server.c: 4600 do_set_profile()] [PulseAudio Volume Control] SET_CARD_PROFILE tag:63 index:50 name:(null) profile:output:analog-surround-71
[I][17:56:32.264466] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-106) running -> idle
[I][17:56:32.267266] mod.protocol-pulse | [ pulse-server.c: 2105 do_delete_stream()] [PulseAudio Volume Control] DELETE_STREAM tag:64 channel:1
[I][17:56:32.267485] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-106) idle -> suspended
[I][17:56:32.267547] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (PulseAudio Volume Control-106) destroy
[I][17:56:32.267695] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2630363027699
[I][17:56:32.267798] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:65
[I][17:56:32.274222] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:63 complete
[I][17:56:32.274295] mod.device-restore | [module-device-re: 122 emit_event()] [PulseAudio Volume Control] EVENT index:536870914 name:module-device-restore 0/1462
[I][17:56:32.274392] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:66 index:50 name:(null)
[I][17:56:32.274494] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:67 index:1462 name:(null)
[I][17:56:32.274553] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:68 index:1462 name:(null)
[I][17:56:32.274602] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:69 index:4294967295 name:module-device-restore
[I][17:56:32.274611] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:69
[I][17:56:32.278431] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:70 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:32.289006] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:71 index:4294967295 name:module-device-restore
[I][17:56:32.289046] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:71
[I][17:56:32.299976] mod.protocol-pulse | [ pulse-server.c: 1869 do_create_record_stream()] [PulseAudio Volume Control] CREATE_RECORD_STREAM tag:72 corked:0 source-name:1462 source-index:4294967295
[I][17:56:32.300030] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:32.300041] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:32.300061] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:32.300109] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:32.300122] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636343682170
[I][17:56:32.300132] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2636343682170
[I][17:56:32.300146] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.300155] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636343715488
[I][17:56:32.300236] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a396f48: loaded converter audio.convert, hnd 0x55be6a39edc0, convert 0x55be6a39edd8
[I][17:56:32.300256] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a1cd630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:32.300273] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.300283] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636343843505
[I][17:56:32.300331] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a3930f0: sync group '(null)'->'group.sync.0'
[I][17:56:32.300348] pw.node | [ impl-node.c: 1266 check_properties()] (PulseAudio Volume Control-0) latency:0/0 -> 1/144
[I][17:56:32.300357] pw.node | [ impl-node.c: 1297 check_properties()] (PulseAudio Volume Control-0) rate:0/0 -> 1/144
[I][17:56:32.300379] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-0) creating -> suspended
[I][17:56:32.300510] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:73 index:1462 name:(null)
[I][17:56:32.300586] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:74 index:1462 name:(null)
[I][17:56:32.300635] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:75 index:50 name:(null)
[I][17:56:32.309070] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6a3597d0: mute changed 0
[I][17:56:32.309101] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a3597d0: volume changed 1.000000
[I][17:56:32.309131] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:32.309474] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a1cd630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:32.311104] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:76 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:32.311285] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:77 index:1479 name:(null)
[I][17:56:32.311326] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:78
[I][17:56:32.311379] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:79 index:1479 name:(null)
[I][17:56:32.314389] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314417] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636357977314
[I][17:56:32.314489] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314508] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358068039
[I][17:56:32.314578] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314595] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358155978
[I][17:56:32.314658] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314674] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358234474
[I][17:56:32.314736] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314752] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358312398
[I][17:56:32.314807] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314823] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358383658
[I][17:56:32.314879] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.314894] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358454707
[I][17:56:32.314994] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:32.315012] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2636358571937
[I][17:56:32.316524] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [PulseAudio Volume Control] got format:F32LE rate:144 channels:1
[I][17:56:32.317762] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:32.317781] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:32.317819] mod.protocol-pulse | [ pulse-server.c: 729 reply_create_record_stream()] [PulseAudio Volume Control] reply CREATE_RECORD_STREAM tag:72 index:1479 latency:6944
[I][17:56:32.317837] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [PulseAudio Volume Control] stride 4 size 32768
[I][17:56:32.317948] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/8 at 48000->Spa:Enum:AudioFormat:F32P/8 at 48000
[I][17:56:32.317969] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR RL RR FC LFE SL SR (00000000000031f8)
[I][17:56:32.317990] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out MONO (0000000000000004)
[I][17:56:32.318011] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/8 at 48000->Spa:Enum:AudioFormat:F32P/1 at 48000 000031f8:00000004
[I][17:56:32.318025][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000c7e 00000000
[I][17:56:32.318036][channelmix-ops.c: 294 make_matrix()] assign FC to MONO (1.000000)
[I][17:56:32.318048][channelmix-ops.c: 314 make_matrix()] assign STEREO to MONO (1.000000)
[I][17:56:32.318059][channelmix-ops.c: 399 make_matrix()] assign RL+RR to MONO (1.000000)
[I][17:56:32.318070][channelmix-ops.c: 451 make_matrix()] assign SL+SR to MONO (1.000000)
[I][17:56:32.318080][channelmix-ops.c: 493 make_matrix()] assign LFE to MONO (1.000000)
[I][17:56:32.318093][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:32.318109][channelmix-ops.c: 660 make_matrix()] FL FR FC LFE SL SR RL RR
[I][17:56:32.318119][channelmix-ops.c: 662 make_matrix()] MONO 1.000 1.000 1.000 1.000 1.000 1.000 1.000 1.000 8.000000
[I][17:56:32.318136][channelmix-ops.c: 681 make_matrix()] normalize 8.000000
[I][17:56:32.318146] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/1 at 48000->Spa:Enum:AudioFormat:F32P/1 at 144
[I][17:56:32.318157] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/1 at 144->Spa:Enum:AudioFormat:F32LE/1 at 144
[I][17:56:32.318287] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-90) suspended -> running
[I][17:56:32.319844] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:80 index:1462 name:(null)
[I][17:56:32.319920] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:81 index:1462 name:(null)
[I][17:56:32.319978] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:82 index:1479 name:(null)
[I][17:56:34.660540] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a1cb5d0 AUTH tag:0 version:35
[I][17:56:34.660740] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:34.660816] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:34.660979] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:34.661963] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:83 index:1504 name:(null)
[I][17:56:34.663050] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:117 index:1504
[I][17:56:34.663207] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:34.663323] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a1cb5d0 [pactl] disconnected
[I][17:56:34.669390] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a1cb5d0 AUTH tag:0 version:35
[I][17:56:34.669508] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:34.669555] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:34.669618] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:34.670479] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:84 index:1505 name:(null)
[I][17:56:34.671652] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:117 index:1505
[I][17:56:34.671805] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:34.672265] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a1cb5d0 [pactl] disconnected
[I][17:56:39.661343] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a1cb5d0 AUTH tag:0 version:35
[I][17:56:39.661555] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:39.661612] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:39.661679] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:39.662786] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:85 index:1506 name:(null)
[I][17:56:39.663803] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:117 index:1506
[I][17:56:39.663938] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:39.664054] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a1cb5d0 [pactl] disconnected
[I][17:56:39.670424] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a1cb5d0 AUTH tag:0 version:35
[I][17:56:39.670557] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:39.670608] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:39.670677] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:39.671416] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:86 index:1507 name:(null)
[I][17:56:39.672423] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:117 index:1507
[I][17:56:39.672557] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:39.672937] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a1cb5d0 [pactl] disconnected
[I][17:56:41.055940] mod.protocol-pulse | [ pulse-server.c: 4600 do_set_profile()] [PulseAudio Volume Control] SET_CARD_PROFILE tag:87 index:50 name:(null) profile:output:analog-surround-51
[I][17:56:41.061834] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-90) running -> idle
[I][17:56:41.066241] mod.protocol-pulse | [ pulse-server.c: 2105 do_delete_stream()] [PulseAudio Volume Control] DELETE_STREAM tag:88 channel:1
[I][17:56:41.066698] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-90) idle -> suspended
[I][17:56:41.066786] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (PulseAudio Volume Control-90) destroy
[I][17:56:41.067163] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2636358571937
[I][17:56:41.067402] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:89
[I][17:56:41.073350] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:87 complete
[I][17:56:41.073417] mod.device-restore | [module-device-re: 122 emit_event()] [PulseAudio Volume Control] EVENT index:536870914 name:module-device-restore 0/1508
[I][17:56:41.073770] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:90 index:50 name:(null)
[I][17:56:41.073866] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:91 index:1508 name:(null)
[I][17:56:41.073981] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:92 index:1508 name:(null)
[I][17:56:41.074062] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:93 index:4294967295 name:module-device-restore
[I][17:56:41.074079] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:93
[I][17:56:41.077585] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:94 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:41.085872] mod.protocol-pulse | [ pulse-server.c: 4565 do_extension()] [PulseAudio Volume Control] EXTENSION tag:95 index:4294967295 name:module-device-restore
[I][17:56:41.085913] default | [ extension.c: 45 extension_process()] client 0x55be6a114540 [PulseAudio Volume Control]: module-device-restore READ_FORMATS tag:95
[I][17:56:41.093706] mod.protocol-pulse | [ pulse-server.c: 1869 do_create_record_stream()] [PulseAudio Volume Control] CREATE_RECORD_STREAM tag:96 corked:0 source-name:1508 source-index:4294967295
[I][17:56:41.093771] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:41.093788] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:41.093812] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:41.093880] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:41.093897] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645137457334
[I][17:56:41.093910] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2645137457334
[I][17:56:41.093935] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.093946] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645137506975
[I][17:56:41.094057] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a2978e8: loaded converter audio.convert, hnd 0x55be6a39edc0, convert 0x55be6a39edd8
[I][17:56:41.094084] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a2d7200: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:41.094107] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.094120] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645137680767
[I][17:56:41.094185] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a32f430: sync group '(null)'->'group.sync.0'
[I][17:56:41.094206] pw.node | [ impl-node.c: 1266 check_properties()] (PulseAudio Volume Control-0) latency:0/0 -> 1/144
[I][17:56:41.094220] pw.node | [ impl-node.c: 1297 check_properties()] (PulseAudio Volume Control-0) rate:0/0 -> 1/144
[I][17:56:41.094250] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-0) creating -> suspended
[I][17:56:41.094366] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:97 index:1508 name:(null)
[I][17:56:41.094473] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:98 index:1508 name:(null)
[I][17:56:41.094555] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CARD_INFO tag:99 index:50 name:(null)
[I][17:56:41.102695] mod.protocol-pulse | [ pulse-server.c: 5093 do_send_object_message()] [PulseAudio Volume Control] SEND_OBJECT_MESSAGE tag:100 object_path:'/core' message:'list-handlers' params:'<null>'
[I][17:56:41.102876] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:101 index:1521 name:(null)
[I][17:56:41.102936] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [PulseAudio Volume Control] GET_SERVER_INFO tag:102
[I][17:56:41.103125] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6a36f850: mute changed 0
[I][17:56:41.103150] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a36f850: volume changed 1.000000
[I][17:56:41.103160] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:41.103425] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a2d7200: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:41.104528] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:103 index:1521 name:(null)
[I][17:56:41.106840] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.106876] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645150435972
[I][17:56:41.106960] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.106980] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645150540446
[I][17:56:41.107054] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.107072] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645150632877
[I][17:56:41.107142] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.107161] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645150721955
[I][17:56:41.107228] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.107246] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645150806079
[I][17:56:41.107310] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:41.107326] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2645150886372
[I][17:56:41.108816] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [PulseAudio Volume Control] got format:F32LE rate:144 channels:1
[I][17:56:41.109094] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:41.109123] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:41.109169] mod.protocol-pulse | [ pulse-server.c: 729 reply_create_record_stream()] [PulseAudio Volume Control] reply CREATE_RECORD_STREAM tag:96 index:1521 latency:6944
[I][17:56:41.109186] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [PulseAudio Volume Control] stride 4 size 32768
[I][17:56:41.109328] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:41.109352] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR RL RR FC LFE (0000000000003078)
[I][17:56:41.109364] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out MONO (0000000000000004)
[I][17:56:41.109374] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/1 at 48000 00003078:00000004
[I][17:56:41.109389][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000c1e 00000000
[I][17:56:41.109400][channelmix-ops.c: 294 make_matrix()] assign FC to MONO (1.000000)
[I][17:56:41.109412][channelmix-ops.c: 314 make_matrix()] assign STEREO to MONO (1.000000)
[I][17:56:41.109421][channelmix-ops.c: 399 make_matrix()] assign RL+RR to MONO (1.000000)
[I][17:56:41.109432][channelmix-ops.c: 493 make_matrix()] assign LFE to MONO (1.000000)
[I][17:56:41.109443][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:41.109457][channelmix-ops.c: 660 make_matrix()] FL FR FC LFE RL RR
[I][17:56:41.109466][channelmix-ops.c: 662 make_matrix()] MONO 1.000 1.000 1.000 1.000 1.000 1.000 6.000000
[I][17:56:41.109477][channelmix-ops.c: 681 make_matrix()] normalize 6.000000
[I][17:56:41.109489] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/1 at 48000->Spa:Enum:AudioFormat:F32P/1 at 144
[I][17:56:41.109501] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6a39edc0: Spa:Enum:AudioFormat:F32P/1 at 144->Spa:Enum:AudioFormat:F32LE/1 at 144
[I][17:56:41.109630] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-68) suspended -> running
[I][17:56:41.111030] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:104 index:1508 name:(null)
[I][17:56:41.111132] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:105 index:1508 name:(null)
[I][17:56:41.111215] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:106 index:1521 name:(null)
[I][17:56:44.419667] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a298f90 AUTH tag:0 version:35
[I][17:56:44.419825] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:44.419881] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [libcanberra] SET_CLIENT_NAME tag:1
[I][17:56:44.419983] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:44.421918] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [libcanberra] reply tag:1 id:111 index:1540
[I][17:56:44.422118] mod.protocol-pulse | [ pulse-server.c: 1062 do_subscribe()] [libcanberra] SUBSCRIBE tag:2 mask:00000004
[I][17:56:44.422180] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:3 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:44.422209] mod.protocol-pulse | [ reply.c: 54 reply_error()] client 0x55be6a298f90 [libcanberra]: ERROR command:18 (PLAY_SAMPLE) tag:3 error:5 (No such file or directory)
[I][17:56:44.422542] mod.protocol-pulse | [ pulse-server.c: 2267 do_create_upload_stream()] [libcanberra] CREATE_UPLOAD_STREAM tag:4 name:audio-volume-change length:11776
[I][17:56:44.423299] mod.protocol-pulse | [ pulse-server.c: 2333 do_finish_upload_stream()] [libcanberra] FINISH_UPLOAD_STREAM tag:5 channel:0 name:audio-volume-change
[I][17:56:44.423453] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:6 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:44.423476] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:44.423515] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:44.423526] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2648467086453
[I][17:56:44.423533] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2648467086453
[I][17:56:44.423544] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:44.423551] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2648467111780
[I][17:56:44.423624] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a876b48: loaded converter audio.convert, hnd 0x55be6a2f82a0, convert 0x55be6a2f82b8
[I][17:56:44.423642] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:44.423659] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:44.423667] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2648467227987
[I][17:56:44.423706] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a87ffb0: sync group '(null)'->'group.sync.0'
[I][17:56:44.423729] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:44.424250] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:6 complete
[I][17:56:44.424271] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:6 index:1541
[I][17:56:44.424393] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:107 index:1508 name:(null)
[I][17:56:44.424415] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 5: val 0.308532<>0.462209
[I][17:56:44.424439] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:108 index:1540 name:(null)
[I][17:56:44.424676] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:109 index:1541 name:(null)
[I][17:56:44.449349] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:107 complete
[I][17:56:44.449597] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:110 index:1508 name:(null)
[I][17:56:44.459990] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:44.460220] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:44.461147] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:111 index:1541 name:(null)
[I][17:56:44.462760] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:44.464382] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:112 index:1541 name:(null)
[I][17:56:44.464679] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6a2f82a0: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:44.464705] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:44.464719] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:44.464731] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6a2f82a0: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:44.464745][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:44.464757][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:44.464768][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:44.464780][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:44.464793][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:44.464804][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:44.464816][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:44.464826][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:44.464837][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:44.464847][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:44.464858][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:44.464871] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6a2f82a0: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:44.464932] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6a2f82a0: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:44.465030] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) suspended -> running
[I][17:56:44.465241] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:113 index:1541 name:(null)
[I][17:56:44.539147] pw.stream | [ stream.c: 467 call_drained()] 0x55be6a873400: drained
[I][17:56:44.539199] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:6 result:0
[I][17:56:44.539248] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:44.539545] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) running -> suspended
[I][17:56:44.539980] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-132) destroy
[I][17:56:44.540120] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2648467227987
[I][17:56:44.662070] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a875030 AUTH tag:0 version:35
[I][17:56:44.662257] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:44.662341] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:44.662463] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:44.663384] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:114 index:1554 name:(null)
[I][17:56:44.664398] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:132 index:1554
[I][17:56:44.664555] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:44.664685] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a875030 [pactl] disconnected
[I][17:56:44.670878] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a875030 AUTH tag:0 version:35
[I][17:56:44.671072] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:44.671122] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:44.671200] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:44.672224] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:115 index:1555 name:(null)
[I][17:56:44.672967] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:132 index:1555
[I][17:56:44.673130] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:44.673708] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a875030 [pactl] disconnected
[I][17:56:45.485157] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:7 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:45.485258] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:45.485304] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:45.485315] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2649528876097
[I][17:56:45.485324] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2649528876097
[I][17:56:45.485335] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:45.485342] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2649528903111
[I][17:56:45.485378] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a324598: loaded converter audio.convert, hnd 0x55be6accc450, convert 0x55be6accc468
[I][17:56:45.485397] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a3166d0: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:45.485413] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:45.485422] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2649528982093
[I][17:56:45.485463] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a873400: sync group '(null)'->'group.sync.0'
[I][17:56:45.485488] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:45.486412] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:7 complete
[I][17:56:45.486440] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:7 index:1556
[I][17:56:45.486552] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:116 index:1508 name:(null)
[I][17:56:45.486579] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 4: val 0.308532<>0.225435
[I][17:56:45.486606] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:117 index:1556 name:(null)
[I][17:56:45.583817] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:116 complete
[I][17:56:45.584043] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:118 index:1508 name:(null)
[I][17:56:45.598923] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:45.599096] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a3166d0: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:45.599911] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:119 index:1556 name:(null)
[I][17:56:45.601543] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a3166d0: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:45.602914] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:120 index:1556 name:(null)
[I][17:56:45.603236] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:45.603254] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:45.603263] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:45.603269] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:45.603278][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:45.603286][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:45.603292][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:45.603299][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:45.603307][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:45.603312][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:45.603319][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:45.603325][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:45.603331][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:45.603337][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:45.603343][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:45.603350] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:45.603398] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:45.603485] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) suspended -> running
[I][17:56:45.603670] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:121 index:1556 name:(null)
[I][17:56:45.675132] pw.stream | [ stream.c: 467 call_drained()] 0x55be6a3166d0: drained
[I][17:56:45.675223] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:7 result:0
[I][17:56:45.675271] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:45.676192] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) running -> suspended
[I][17:56:45.678292] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-132) destroy
[I][17:56:45.678919] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2649528982093
[I][17:56:46.501694] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:8 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:46.501782] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:46.501844] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:46.501860] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2650545420675
[I][17:56:46.501872] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2650545420675
[I][17:56:46.501888] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:46.501899] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2650545459163
[I][17:56:46.502006] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a32d0b8: loaded converter audio.convert, hnd 0x55be6accc450, convert 0x55be6accc468
[I][17:56:46.502030] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:46.502053] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:46.502065] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2650545625393
[I][17:56:46.502121] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a30a980: sync group '(null)'->'group.sync.0'
[I][17:56:46.502155] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:46.502802] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:8 complete
[I][17:56:46.502831] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:8 index:1569
[I][17:56:46.502971] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:122 index:1508 name:(null)
[I][17:56:46.502993] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 3: val 0.308532<>0.408375
[I][17:56:46.503155] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:123 index:1569 name:(null)
[I][17:56:46.509137] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:122 complete
[I][17:56:46.509341] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:124 index:1508 name:(null)
[I][17:56:46.520751] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:46.520947] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:46.521613] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:125 index:1569 name:(null)
[I][17:56:46.523273] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:46.524059] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:126 index:1569 name:(null)
[I][17:56:46.525099] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:46.525125] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:46.525159] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:46.525170] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:46.525182][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:46.525193][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:46.525203][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:46.525212][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:46.525224][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:46.525232][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:46.525243][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:46.525252][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:46.525261][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:46.525270][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:46.525280][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:46.525291] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:46.525356] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:46.525472] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) suspended -> running
[I][17:56:46.525702] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:127 index:1569 name:(null)
[I][17:56:46.597643] pw.stream | [ stream.c: 467 call_drained()] 0x55be6a873400: drained
[I][17:56:46.597732] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:8 result:0
[I][17:56:46.597760] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:46.597936] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) running -> suspended
[I][17:56:46.598206] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-132) destroy
[I][17:56:46.598309] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2650545625393
[I][17:56:47.165011] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:9 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:47.165104] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:47.165154] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:47.165171] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2651208730870
[I][17:56:47.165185] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2651208730870
[I][17:56:47.165203] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:47.165215] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2651208775601
[I][17:56:47.165296] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a32d0b8: loaded converter audio.convert, hnd 0x55be6accc450, convert 0x55be6accc468
[I][17:56:47.165318] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:47.165340] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:47.165353] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2651208913720
[I][17:56:47.165406] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6acbb540: sync group '(null)'->'group.sync.0'
[I][17:56:47.165437] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:47.166131] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:9 complete
[I][17:56:47.166152] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:9 index:1582
[I][17:56:47.166284] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:128 index:1508 name:(null)
[I][17:56:47.166305] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 2: val 0.308532<>0.246491
[I][17:56:47.166329] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:129 index:1582 name:(null)
[I][17:56:47.172858] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:128 complete
[I][17:56:47.173109] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:130 index:1508 name:(null)
[I][17:56:47.183814] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:47.184026] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:47.184776] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:131 index:1582 name:(null)
[I][17:56:47.186580] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:47.188103] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:132 index:1582 name:(null)
[I][17:56:47.188364] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:47.188390] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:47.188405] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:47.188418] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:47.188432][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:47.188454][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:47.188468][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:47.188479][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:47.188493][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:47.188504][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:47.188517][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:47.188527][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:47.188539][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:47.188550][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:47.188561][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:47.188574] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:47.188637] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:47.188735] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) suspended -> running
[I][17:56:47.190561] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:133 index:1582 name:(null)
[I][17:56:47.258889] pw.stream | [ stream.c: 467 call_drained()] 0x55be6a873400: drained
[I][17:56:47.259000] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:9 result:0
[I][17:56:47.259043] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:47.259392] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) running -> suspended
[I][17:56:47.259642] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-132) destroy
[I][17:56:47.259750] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2651208913720
[I][17:56:47.891218] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:10 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:47.891310] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:47.891374] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:47.891395] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2651934955166
[I][17:56:47.891402] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2651934955166
[I][17:56:47.891413] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:47.891420] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2651934980928
[I][17:56:47.891511] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a2d0618: loaded converter audio.convert, hnd 0x55be6accc450, convert 0x55be6accc468
[I][17:56:47.891538] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:47.891555] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:47.891563] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2651935123739
[I][17:56:47.891606] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a31aaf0: sync group '(null)'->'group.sync.0'
[I][17:56:47.891631] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:47.892264] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:10 complete
[I][17:56:47.892285] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:10 index:1595
[I][17:56:47.892421] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:134 index:1508 name:(null)
[I][17:56:47.892459] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 1: val 0.308532<>0.361696
[I][17:56:47.892488] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:135 index:1595 name:(null)
[I][17:56:47.900847] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:134 complete
[I][17:56:47.901028] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:136 index:1508 name:(null)
[I][17:56:47.911397] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:47.911637] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:47.913409] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:137 index:1595 name:(null)
[I][17:56:47.914287] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:47.915181] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:138 index:1595 name:(null)
[I][17:56:47.916209] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:47.916232] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:47.916247] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:47.916257] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:47.916270][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:47.916281][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:47.916291][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:47.916300][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:47.916312][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:47.916321][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:47.916332][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:47.916341][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:47.916350][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:47.916359][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:47.916369][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:47.916380] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:47.916451] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:47.916578] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) suspended -> running
[I][17:56:47.916839] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:139 index:1595 name:(null)
[I][17:56:47.989471] pw.stream | [ stream.c: 467 call_drained()] 0x55be6a873400: drained
[I][17:56:47.989573] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:10 result:0
[I][17:56:47.989639] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:47.990591] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) running -> suspended
[I][17:56:47.991575] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-132) destroy
[I][17:56:47.991933] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2651935123739
[I][17:56:48.820194] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:11 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:48.820299] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:48.820361] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:48.820379] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2652863939136
[I][17:56:48.820391] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2652863939136
[I][17:56:48.820407] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:48.820421] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2652863981470
[I][17:56:48.820543] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a31de08: loaded converter audio.convert, hnd 0x55be6accc450, convert 0x55be6accc468
[I][17:56:48.820574] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:48.820601] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:48.820617] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2652864177611
[I][17:56:48.820679] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a2d0150: sync group '(null)'->'group.sync.0'
[I][17:56:48.820719] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:48.821296] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:11 complete
[I][17:56:48.821321] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:11 index:1608
[I][17:56:48.821460] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:140 index:1508 name:(null)
[I][17:56:48.821488] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.308532<>0.260852
[I][17:56:48.821515] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:141 index:1608 name:(null)
[I][17:56:48.828889] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:140 complete
[I][17:56:48.829113] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:142 index:1508 name:(null)
[I][17:56:48.839927] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:48.840193] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:48.841219] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:143 index:1608 name:(null)
[I][17:56:48.843598] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6a873400: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:48.844297] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:144 index:1608 name:(null)
[I][17:56:48.845380] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:48.845402] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:48.845425] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:48.845436] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:48.845449][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:48.845460][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:48.845469][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:48.845479][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:48.845490][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:48.845499][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:48.845509][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:48.845518][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:48.845528][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:48.845537][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:48.845546][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:48.845557] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:48.845621] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6accc450: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:48.845742] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) suspended -> running
[I][17:56:48.845993] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:145 index:1608 name:(null)
[I][17:56:48.917364] pw.stream | [ stream.c: 467 call_drained()] 0x55be6a873400: drained
[I][17:56:48.917494] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:11 result:0
[I][17:56:48.917545] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:48.917881] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-132) running -> suspended
[I][17:56:48.918186] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-132) destroy
[I][17:56:48.918323] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2652864177611
[I][17:56:49.662270] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a875030 AUTH tag:0 version:35
[I][17:56:49.662463] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:49.662535] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:49.662622] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:49.663599] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:146 index:1621 name:(null)
[I][17:56:49.664797] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:132 index:1621
[I][17:56:49.664956] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:49.665102] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a875030 [pactl] disconnected
[I][17:56:49.671156] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a30d550 AUTH tag:0 version:35
[I][17:56:49.671350] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:49.671410] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:49.671503] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:49.672335] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:147 index:1622 name:(null)
[I][17:56:49.673358] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:132 index:1622
[I][17:56:49.673487] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:49.674049] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a30d550 [pactl] disconnected
[I][17:56:50.132304] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a318380 AUTH tag:0 version:35
[I][17:56:50.132455] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:50.132536] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [Music Player Daemon] SET_CLIENT_NAME tag:1
[I][17:56:50.132593] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:50.133446] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:148 index:1623 name:(null)
[I][17:56:50.134677] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [Music Player Daemon] reply tag:1 id:132 index:1623
[I][17:56:50.134842] mod.protocol-pulse | [ pulse-server.c: 1062 do_subscribe()] [Music Player Daemon] SUBSCRIBE tag:2 mask:00000004
[I][17:56:50.134873] mod.protocol-pulse | [ pulse-server.c: 1591 do_create_playback_stream()] [Music Player Daemon] CREATE_PLAYBACK_STREAM tag:3 corked:0 sink-name:(null) sink-index:4294967295
[I][17:56:50.134900] mod.protocol-pulse | [ pulse-server.c: 438 fix_playback_buffer_attr()] [Music Player Daemon] maxlength:4294967295 tlength:44100 minreq:4294967295 prebuf:4294967295 max:4194304
[I][17:56:50.134913] mod.protocol-pulse | [ pulse-server.c: 511 fix_playback_buffer_attr()] [Music Player Daemon] maxlength:4194304 tlength:44100 minreq:3528/472 prebuf:40576 latency:8192/44100 4
[I][17:56:50.134933] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:50.134991] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:50.135007] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654178567136
[I][17:56:50.135019] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2654178567136
[I][17:56:50.135035] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.135046] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654178606549
[I][17:56:50.135135] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a2fa708: loaded converter audio.convert, hnd 0x55be6acec460, convert 0x55be6acec478
[I][17:56:50.135162] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6acb7390: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:50.135188] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.135202] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654178762333
[I][17:56:50.135251] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6a303b70: sync group '(null)'->'group.sync.0'
[I][17:56:50.135271] pw.node | [ impl-node.c: 1266 check_properties()] (Music Player Daemon-0) latency:0/0 -> 8192/44100
[I][17:56:50.135288] pw.node | [ impl-node.c: 1297 check_properties()] (Music Player Daemon-0) rate:0/0 -> 1/44100
[I][17:56:50.135316] pw.node | [ impl-node.c: 476 node_update_state()] (Music Player Daemon-0) creating -> suspended
[I][17:56:50.135500] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6a1ccd00: mute changed 0
[I][17:56:50.135522] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a1ccd00: volume changed 1.000000
[I][17:56:50.136164] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:149 index:1624 name:(null)
[I][17:56:50.144009] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6a1ccd00: volume changed 1.000000
[I][17:56:50.144055] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:50.144273] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6acb7390: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:50.145064] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:150 index:1624 name:(null)
[I][17:56:50.146918] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6acb7390: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:50.176884] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:151 index:1624 name:(null)
[I][17:56:50.177254] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [Music Player Daemon] got format:S16LE rate:44100 channels:2
[I][17:56:50.177297] mod.protocol-pulse | [ pulse-server.c: 438 fix_playback_buffer_attr()] [Music Player Daemon] maxlength:4294967295 tlength:44100 minreq:4294967295 prebuf:4294967295 max:4194304
[I][17:56:50.177312] mod.protocol-pulse | [ pulse-server.c: 511 fix_playback_buffer_attr()] [Music Player Daemon] maxlength:4194304 tlength:44100 minreq:3528/472 prebuf:40576 latency:8192/44100 4
[I][17:56:50.177350] mod.protocol-pulse | [ pulse-server.c: 573 reply_create_playback_stream()] [Music Player Daemon] reply CREATE_PLAYBACK_STREAM tag:3 index:1624 missing:44100 lat:185759
[I][17:56:50.177367] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [Music Player Daemon] stride 4 size 32768
[I][17:56:50.177515] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6acec460: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:50.177538] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:50.177551] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:50.177564] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6acec460: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:50.177578][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:50.177591][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:50.177603][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:50.177615][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:50.177628][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:50.177638][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:50.177651][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:50.177662][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:50.177677][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:50.177688][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:50.177699][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:50.177712] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6acec460: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:50.177775] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6acec460: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:50.177884] pw.node | [ impl-node.c: 476 node_update_state()] (Music Player Daemon-120) suspended -> running
[I][17:56:50.177956] mod.protocol-pulse | [ pulse-server.c: 1869 do_create_record_stream()] [PulseAudio Volume Control] CREATE_RECORD_STREAM tag:152 corked:0 source-name:1508 source-index:4294967295
[I][17:56:50.177986] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:50.178001] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:50.178025] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:50.178084] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:50.178105] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654221665718
[I][17:56:50.178118] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2654221665718
[I][17:56:50.178133] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.178146] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654221706489
[I][17:56:50.178250] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6ad86cb8: loaded converter audio.convert, hnd 0x55be6b2272f0, convert 0x55be6b227308
[I][17:56:50.178271] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6ad83630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:50.178290] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.178304] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654221864273
[I][17:56:50.178356] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6ad90020: sync group '(null)'->'group.sync.0'
[I][17:56:50.178379] pw.node | [ impl-node.c: 1266 check_properties()] (PulseAudio Volume Control-0) latency:0/0 -> 1/144
[I][17:56:50.178395] pw.node | [ impl-node.c: 1297 check_properties()] (PulseAudio Volume Control-0) rate:0/0 -> 1/144
[I][17:56:50.178427] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-0) creating -> suspended
[I][17:56:50.178580] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [Music Player Daemon] GET_SINK_INPUT_INFO tag:4 index:1624 name:(null)
[I][17:56:50.179005] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:153 index:1624 name:(null)
[I][17:56:50.179063] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [Music Player Daemon] GET_SINK_INPUT_INFO tag:5 index:1624 name:(null)
[I][17:56:50.179846] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:154 index:1637 name:(null)
[I][17:56:50.188708] mod.protocol-pulse | [ pulse-server.c: 1089 stream_control_info()] stream 0x55be6ad125f0: mute changed 0
[I][17:56:50.188741] mod.protocol-pulse | [ pulse-server.c: 1083 stream_control_info()] stream 0x55be6ad125f0: volume changed 1.000000
[I][17:56:50.188760] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:50.189175] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6ad83630: set output latency 0.000000-0.000000 0-0 0-0
[I][17:56:50.190417] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:155 index:1637 name:(null)
[I][17:56:50.193298] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.193340] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654236900364
[I][17:56:50.193432] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.193452] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654237012082
[I][17:56:50.193532] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.193548] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654237108928
[I][17:56:50.193610] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.193626] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654237186527
[I][17:56:50.193694] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.193710] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654237270606
[I][17:56:50.193776] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:50.193792] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2654237352084
[I][17:56:50.195086] mod.protocol-pulse | [ pulse-server.c: 1180 stream_param_changed()] [PulseAudio Volume Control] got format:F32LE rate:144 channels:1
[I][17:56:50.195136] mod.protocol-pulse | [ pulse-server.c: 641 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4294967295 fragsize:4 framesize:4
[I][17:56:50.195151] mod.protocol-pulse | [ pulse-server.c: 675 fix_record_buffer_attr()] [PulseAudio Volume Control] maxlength:4194304 fragsize:4 minfrag:4 latency:1/144
[I][17:56:50.195186] mod.protocol-pulse | [ pulse-server.c: 729 reply_create_record_stream()] [PulseAudio Volume Control] reply CREATE_RECORD_STREAM tag:152 index:1637 latency:6944
[I][17:56:50.195206] mod.protocol-pulse | [ pulse-server.c: 1148 get_buffers_param()] [PulseAudio Volume Control] stride 4 size 32768
[I][17:56:50.195311] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b2272f0: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:50.195332] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR RL RR FC LFE (0000000000003078)
[I][17:56:50.195341] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out MONO (0000000000000004)
[I][17:56:50.195352] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b2272f0: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/1 at 48000 00003078:00000004
[I][17:56:50.195365][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000c1e 00000000
[I][17:56:50.195373][channelmix-ops.c: 294 make_matrix()] assign FC to MONO (1.000000)
[I][17:56:50.195383][channelmix-ops.c: 314 make_matrix()] assign STEREO to MONO (1.000000)
[I][17:56:50.195391][channelmix-ops.c: 399 make_matrix()] assign RL+RR to MONO (1.000000)
[I][17:56:50.195399][channelmix-ops.c: 493 make_matrix()] assign LFE to MONO (1.000000)
[I][17:56:50.195407][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:50.195419][channelmix-ops.c: 660 make_matrix()] FL FR FC LFE RL RR
[I][17:56:50.195425][channelmix-ops.c: 662 make_matrix()] MONO 1.000 1.000 1.000 1.000 1.000 1.000 6.000000
[I][17:56:50.195438][channelmix-ops.c: 681 make_matrix()] normalize 6.000000
[I][17:56:50.195448] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b2272f0: Spa:Enum:AudioFormat:F32P/1 at 48000->Spa:Enum:AudioFormat:F32P/1 at 144
[I][17:56:50.195457] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b2272f0: Spa:Enum:AudioFormat:F32P/1 at 144->Spa:Enum:AudioFormat:F32LE/1 at 144
[I][17:56:50.195557] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-121) suspended -> running
[I][17:56:50.195925] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:156 index:1637 name:(null)
[I][17:56:54.663022] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6ad330e0 AUTH tag:0 version:35
[I][17:56:54.663211] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:54.663247] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:54.663309] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:54.664353] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:157 index:1656 name:(null)
[I][17:56:54.665646] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:80 index:1656
[I][17:56:54.665814] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:54.665937] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6ad330e0 [pactl] disconnected
[I][17:56:54.671973] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6ad330e0 AUTH tag:0 version:35
[I][17:56:54.672111] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:54.672157] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:54.672208] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:54.673272] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:158 index:1657 name:(null)
[I][17:56:54.674447] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:80 index:1657
[I][17:56:54.674636] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:54.675175] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6ad330e0 [pactl] disconnected
[I][17:56:58.627678] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:12 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:58.627756] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:58.627808] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:58.627825] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2662671385125
[I][17:56:58.627838] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2662671385125
[I][17:56:58.627857] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:58.627870] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2662671430822
[I][17:56:58.627901] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6b7fb7b8: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:56:58.627924] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7e2610: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:58.627945] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:58.627958] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2662671518897
[I][17:56:58.628015] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7efda0: sync group '(null)'->'group.sync.0'
[I][17:56:58.628047] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:58.628635] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:12 complete
[I][17:56:58.628667] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:12 index:1658
[I][17:56:58.628823] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:159 index:1508 name:(null)
[I][17:56:58.628872] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.260852
[I][17:56:58.628899] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:160 index:1658 name:(null)
[I][17:56:58.653338] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:159 complete
[I][17:56:58.660801] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:161 index:1508 name:(null)
[I][17:56:58.660911] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_INFO tag:162 index:1508 name:(null)
[I][17:56:58.667720] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:58.667875] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7e2610: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:58.670290] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7e2610: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:58.671753] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:58.671777] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:58.671786] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:58.671793] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:58.671802][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:58.671809][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:58.671816][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:58.671824][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:58.671831][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:58.671839][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:58.671847][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:58.671853][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:58.671858][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:58.671865][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:58.671870][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:58.671882] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:58.671954] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:58.672056] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:56:58.675546] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:163 index:1658 name:(null)
[I][17:56:58.675643] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:164 index:1658 name:(null)
[I][17:56:58.675683] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:165 index:1658 name:(null)
[I][17:56:58.746597] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7e2610: drained
[I][17:56:58.746698] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:12 result:0
[I][17:56:58.746738] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:58.746989] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:56:58.747360] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:56:58.747510] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2662671518897
[I][17:56:59.091554] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:13 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:59.091672] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:59.091741] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:59.091767] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2663135327947
[I][17:56:59.091790] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2663135327947
[I][17:56:59.091815] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:59.091837] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2663135398025
[I][17:56:59.091915] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6b7e8ab8: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:56:59.091949] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7b7970: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:59.091973] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:59.091987] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2663135547102
[I][17:56:59.092042] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7fb710: sync group '(null)'->'group.sync.0'
[I][17:56:59.092073] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:59.092662] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:13 complete
[I][17:56:59.092708] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:13 index:1671
[I][17:56:59.093015] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:166 index:1508 name:(null)
[I][17:56:59.093041] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.260852
[I][17:56:59.093126] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:167 index:1671 name:(null)
[I][17:56:59.101219] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:166 complete
[I][17:56:59.108090] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:168 index:1508 name:(null)
[I][17:56:59.114821] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:59.115030] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7b7970: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:59.117731] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7b7970: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:59.119335] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:59.119365] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:59.119381] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:59.119393] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:59.119406][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:59.119418][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:59.119428][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:59.119446][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:59.119458][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:59.119467][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:59.119477][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:59.119488][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:59.119499][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:59.119509][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:59.119518][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:59.119533] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:59.119603] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:59.119730] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:56:59.125955] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:169 index:1671 name:(null)
[I][17:56:59.126075] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:170 index:1671 name:(null)
[I][17:56:59.126139] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:171 index:1671 name:(null)
[I][17:56:59.189304] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7b7970: drained
[I][17:56:59.189401] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:13 result:0
[I][17:56:59.189434] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:59.189832] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:56:59.190152] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:56:59.190307] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2663135547102
[I][17:56:59.664114] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6ad330e0 AUTH tag:0 version:35
[I][17:56:59.664268] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:59.664341] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:59.664472] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:59.666752] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:80 index:1684
[I][17:56:59.666954] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:56:59.667105] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6ad330e0 [pactl] disconnected
[I][17:56:59.673113] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6ad330e0 AUTH tag:0 version:35
[I][17:56:59.673286] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:56:59.673363] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:56:59.673507] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:56:59.675684] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:80 index:1685
[I][17:56:59.675913] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:56:59.676234] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:172 index:1684 name:(null)
[I][17:56:59.676265] mod.protocol-pulse | [ reply.c: 54 reply_error()] client 0x55be6a114540 [PulseAudio Volume Control]: ERROR command:27 (GET_CLIENT_INFO) tag:172 error:5 (No such file or directory)
[I][17:56:59.676318] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:173 index:1685 name:(null)
[I][17:56:59.676386] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6ad330e0 [pactl] disconnected
[I][17:56:59.709140] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:14 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:56:59.709233] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:56:59.709280] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:56:59.709292] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2663752852755
[I][17:56:59.709300] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2663752852755
[I][17:56:59.709310] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:59.709318] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2663752878671
[I][17:56:59.709345] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6b7f2148: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:56:59.709362] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7b4dd0: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:59.709381] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:56:59.709390] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2663752950740
[I][17:56:59.709435] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7e8850: sync group '(null)'->'group.sync.0'
[I][17:56:59.709459] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:56:59.710038] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:14 complete
[I][17:56:59.710063] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:14 index:1686
[I][17:56:59.710210] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:174 index:1508 name:(null)
[I][17:56:59.710238] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.260852
[I][17:56:59.710471] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:175 index:1686 name:(null)
[I][17:56:59.720977] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:174 complete
[I][17:56:59.726570] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:176 index:1508 name:(null)
[I][17:56:59.735476] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:56:59.735726] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7b4dd0: set input latency 0.000000-0.000000 0-0 0-0
[I][17:56:59.738540] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7b4dd0: set input latency 1.000000-1.000000 0-0 0-0
[I][17:56:59.740130] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:56:59.740154] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:56:59.740168] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:56:59.740180] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:56:59.740194][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:56:59.740206][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:56:59.740216][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:56:59.740226][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:56:59.740238][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:56:59.740249][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:56:59.740261][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:56:59.740272][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:56:59.740282][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:56:59.740292][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:56:59.740304][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:56:59.740316] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:56:59.740379] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:56:59.740530] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:56:59.744886] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:177 index:1686 name:(null)
[I][17:56:59.744976] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:178 index:1686 name:(null)
[I][17:56:59.745043] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:179 index:1686 name:(null)
[I][17:56:59.813223] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7b4dd0: drained
[I][17:56:59.813276] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:14 result:0
[I][17:56:59.813308] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:56:59.813658] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:56:59.813953] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:56:59.814094] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2663752950740
[I][17:57:00.093021] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:15 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:57:00.093162] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:57:00.093242] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:57:00.093269] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2664136829630
[I][17:57:00.093282] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2664136829630
[I][17:57:00.093309] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:00.093333] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2664136893290
[I][17:57:00.093442] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6b7efdc8: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:57:00.093482] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7fb2f0: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:00.093509] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:00.093524] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2664137084502
[I][17:57:00.093581] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7ba0b0: sync group '(null)'->'group.sync.0'
[I][17:57:00.093622] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:57:00.094314] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:15 complete
[I][17:57:00.094336] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:15 index:1699
[I][17:57:00.094502] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:180 index:1508 name:(null)
[I][17:57:00.094525] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.260852
[I][17:57:00.094662] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:181 index:1699 name:(null)
[I][17:57:00.125293] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:180 complete
[I][17:57:00.127028] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:182 index:1508 name:(null)
[I][17:57:00.139110] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:57:00.139363] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7fb2f0: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:00.142199] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7fb2f0: set input latency 1.000000-1.000000 0-0 0-0
[I][17:57:00.143754] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:57:00.143782] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:57:00.143796] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:57:00.143807] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:57:00.143820][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:57:00.143831][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:57:00.143840][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:57:00.143850][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:57:00.143861][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:57:00.143871][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:57:00.143881][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:57:00.143890][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:57:00.143909][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:57:00.143918][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:57:00.143927][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:57:00.143941] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:57:00.144008] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:57:00.144134] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:57:00.146028] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:183 index:1699 name:(null)
[I][17:57:00.146106] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:184 index:1699 name:(null)
[I][17:57:00.146147] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:185 index:1699 name:(null)
[I][17:57:00.218626] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7fb2f0: drained
[I][17:57:00.218681] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:15 result:0
[I][17:57:00.218730] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:57:00.219182] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:57:00.219446] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:57:00.219564] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2664137084502
[I][17:57:00.578157] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:16 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:57:00.578255] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:57:00.578328] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:57:00.578355] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2664621915489
[I][17:57:00.578378] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2664621915489
[I][17:57:00.578405] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:00.578420] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2664621980233
[I][17:57:00.578488] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6b7f4828: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:57:00.578518] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7f6400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:00.578546] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:00.578561] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2664622121951
[I][17:57:00.578614] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7de2a0: sync group '(null)'->'group.sync.0'
[I][17:57:00.578653] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:57:00.579267] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:16 complete
[I][17:57:00.579288] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:16 index:1712
[I][17:57:00.579451] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:186 index:1508 name:(null)
[I][17:57:00.579483] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.260852
[I][17:57:00.579511] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:187 index:1712 name:(null)
[I][17:57:00.585134] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:186 complete
[I][17:57:00.595569] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:188 index:1508 name:(null)
[I][17:57:00.599332] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:57:00.599538] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7f6400: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:00.602088] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7f6400: set input latency 1.000000-1.000000 0-0 0-0
[I][17:57:00.603589] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:57:00.603634] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:57:00.603650] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:57:00.603662] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:57:00.603677][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:57:00.603690][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:57:00.603703][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:57:00.603716][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:57:00.603729][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:57:00.603739][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:57:00.603753][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:57:00.603763][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:57:00.603775][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:57:00.603788][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:57:00.603800][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:57:00.603817] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:57:00.603898] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:57:00.604038] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:57:00.612037] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:189 index:1712 name:(null)
[I][17:57:00.612104] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:190 index:1712 name:(null)
[I][17:57:00.612128] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:191 index:1712 name:(null)
[I][17:57:00.677247] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7f6400: drained
[I][17:57:00.677337] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:16 result:0
[I][17:57:00.677365] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:57:00.677832] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:57:00.678141] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:57:00.678261] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2664622121951
[I][17:57:00.963442] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:17 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:57:00.963533] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:57:00.963579] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:57:00.963592] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2665007152075
[I][17:57:00.963603] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2665007152075
[I][17:57:00.963618] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:00.963626] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2665007186954
[I][17:57:00.963699] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6b7eb7c8: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:57:00.963724] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7e2610: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:00.963746] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:00.963758] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2665007318745
[I][17:57:00.963809] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7aad70: sync group '(null)'->'group.sync.0'
[I][17:57:00.963844] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:57:00.964546] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:17 complete
[I][17:57:00.964566] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:17 index:1725
[I][17:57:00.964716] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:192 index:1508 name:(null)
[I][17:57:00.964746] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.260852
[I][17:57:00.965171] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:193 index:1725 name:(null)
[I][17:57:00.992949] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:192 complete
[I][17:57:00.998004] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:194 index:1508 name:(null)
[I][17:57:01.014945] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:57:01.015135] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7e2610: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:01.017588] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7e2610: set input latency 1.000000-1.000000 0-0 0-0
[I][17:57:01.019112] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:57:01.019137] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:57:01.019151] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:57:01.019162] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:57:01.019175][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:57:01.019186][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:57:01.019196][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:57:01.019206][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:57:01.019218][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:57:01.019226][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:57:01.019236][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:57:01.019247][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:57:01.019256][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:57:01.019266][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:57:01.019275][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:57:01.019290] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:57:01.019356] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:57:01.019474] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:57:01.032536] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:195 index:1725 name:(null)
[I][17:57:01.032870] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:196 index:1725 name:(null)
[I][17:57:01.032921] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:197 index:1725 name:(null)
[I][17:57:01.093261] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7e2610: drained
[I][17:57:01.093329] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:17 result:0
[I][17:57:01.093383] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:57:01.093670] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:57:01.094047] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:57:01.094184] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2665007318745
[I][17:57:01.558978] mod.protocol-pulse | [ pulse-server.c: 2550 do_play_sample()] [libcanberra] PLAY_SAMPLE tag:18 sink_index:4294967295 sink_name:1508 name:audio-volume-change
[I][17:57:01.559076] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'stream.properties'
[I][17:57:01.559127] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'(null)' class:'(null)'
[I][17:57:01.559143] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2665602703451
[I][17:57:01.559157] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2665602703451
[I][17:57:01.559175] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:01.559187] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2665602747409
[I][17:57:01.559243] spa.audioadapter | [ audioadapter.c: 2127 impl_init()] 0x55be6a3214d8: loaded converter audio.convert, hnd 0x55be6b7ff100, convert 0x55be6b7ff118
[I][17:57:01.559268] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7fb420: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:01.559289] pw.context | [ context.c: 718 pw_context_acquire_loop()] 0x55be6a0d7550: looking for name:'data-loop.0' class:'(null)'
[I][17:57:01.559301] pw.context | [ context.c: 694 acquire_data_loop()] 0x55be6a0d7550: using name:'data-loop.0' class:'data.rt' last_used:2665602861982
[I][17:57:01.559351] pw.node | [ impl-node.c: 1210 check_properties()] 0x55be6b7eb7a0: sync group '(null)'->'group.sync.0'
[I][17:57:01.559383] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-0) creating -> suspended
[I][17:57:01.559953] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [libcanberra]: tag:18 complete
[I][17:57:01.559979] mod.protocol-pulse | [pending-sample.c: 43 sample_play_ready_reply()] [libcanberra] PLAY_SAMPLE tag:18 index:1738
[I][17:57:01.560145] mod.protocol-pulse | [ pulse-server.c: 2903 do_set_volume()] [PulseAudio Volume Control] SET_SINK_VOLUME tag:198 index:1508 name:(null)
[I][17:57:01.560163] mod.protocol-pulse | [ volume.c: 23 volume_compare()] 0: val 0.260852<>0.125092
[I][17:57:01.560225] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:199 index:1738 name:(null)
[I][17:57:01.584994] mod.protocol-pulse | [ operation.c: 63 operation_complete()] [PulseAudio Volume Control]: tag:198 complete
[I][17:57:01.592413] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INFO tag:200 index:1508 name:(null)
[I][17:57:01.599324] pw.context | [ context.c: 1526 pw_context_recalc_graph()] 0x55be6a0d7550: busy:0 reason:node flags changed
[I][17:57:01.599592] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7fb420: set input latency 0.000000-0.000000 0-0 0-0
[I][17:57:01.602197] pw.stream | [ stream.c: 876 parse_latency()] stream 0x55be6b7fb420: set input latency 1.000000-1.000000 0-0 0-0
[I][17:57:01.603786] spa.audioconvert | [ audioconvert.c: 1765 setup_in_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:S16LE/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 44100
[I][17:57:01.603830] spa.audioconvert | [ audioconvert.c: 1929 setup_channelmix()] in FL FR (0000000000000018)
[I][17:57:01.603864] spa.audioconvert | [ audioconvert.c: 1931 setup_channelmix()] out FL FR RL RR FC LFE (0000000000003078)
[I][17:57:01.603884] spa.audioconvert | [ audioconvert.c: 1934 setup_channelmix()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/6 at 44100 00000018:00003078
[I][17:57:01.603893][channelmix-ops.c: 173 match_mix()] matched channel 1 (1.000000)
[I][17:57:01.603906][channelmix-ops.c: 173 match_mix()] matched channel 2 (1.000000)
[I][17:57:01.603914][channelmix-ops.c: 264 make_matrix()] unassigned downmix 00000000 00000000
[I][17:57:01.603921][channelmix-ops.c: 504 make_matrix()] unassigned upmix 00000000 lfe:0.000000
[I][17:57:01.603930][channelmix-ops.c: 660 make_matrix()] FL FR
[I][17:57:01.603937][channelmix-ops.c: 662 make_matrix()] FL 1.000 1.000000
[I][17:57:01.603948][channelmix-ops.c: 662 make_matrix()] FR 1.000 1.000000
[I][17:57:01.603954][channelmix-ops.c: 662 make_matrix()] FC 0.000000
[I][17:57:01.603960][channelmix-ops.c: 662 make_matrix()] LFE 0.000000
[I][17:57:01.603968][channelmix-ops.c: 662 make_matrix()] RL 0.000000
[I][17:57:01.603973][channelmix-ops.c: 662 make_matrix()] RR 0.000000
[I][17:57:01.603987] spa.audioconvert | [ audioconvert.c: 1978 setup_resample()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/2 at 44100->Spa:Enum:AudioFormat:F32P/2 at 48000
[I][17:57:01.604040] spa.audioconvert | [ audioconvert.c: 2052 setup_out_convert()] 0x55be6b7ff100: Spa:Enum:AudioFormat:F32P/6 at 48000->Spa:Enum:AudioFormat:F32P/6 at 48000
[I][17:57:01.604132] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) suspended -> running
[I][17:57:01.610382] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:201 index:1738 name:(null)
[I][17:57:01.610436] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:202 index:1738 name:(null)
[I][17:57:01.610459] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:203 index:1738 name:(null)
[I][17:57:01.674548] pw.stream | [ stream.c: 467 call_drained()] 0x55be6b7fb420: drained
[I][17:57:01.674609] mod.protocol-pulse | [pending-sample.c: 79 on_sample_play_done()] [libcanberra] PLAY_SAMPLE done tag:18 result:0
[I][17:57:01.674642] mod.protocol-pulse | [ sample-play.c: 48 sample_play_stream_destroy()] destroy audio-volume-change
[I][17:57:01.674859] pw.node | [ impl-node.c: 476 node_update_state()] (libcanberra-80) running -> suspended
[I][17:57:01.675168] pw.node | [ impl-node.c: 2401 pw_impl_node_destroy()] (libcanberra-80) destroy
[I][17:57:01.675311] pw.context | [ context.c: 740 pw_context_release_loop()] release name:'data-loop.0' class:'data.rt' last_used:2665602861982
[I][17:57:02.843729] mod.protocol-pulse | [ pulse-server.c: 2649 do_flush_trigger_prebuf_stream()] [Music Player Daemon] FLUSH_PLAYBACK_STREAM tag:6 channel:0
[I][17:57:02.843878] mod.protocol-pulse | [ pulse-server.c: 2618 do_cork_stream()] [Music Player Daemon] CORK_PLAYBACK_STREAM tag:7 channel:0 cork:yes
[I][17:57:02.843893] mod.protocol-pulse | [ stream.c: 217 stream_set_corked()] cork 1
[I][17:57:02.843935] mod.protocol-pulse | [ stream.c: 206 stream_set_paused()] 0x55be6a1ccd00: [Music Player Daemon] paused because of cork request
[I][17:57:02.844520] pw.node | [ impl-node.c: 476 node_update_state()] (Music Player Daemon-120) running -> idle
[I][17:57:02.844746] pw.node | [ impl-node.c: 476 node_update_state()] (PulseAudio Volume Control-121) running -> idle
[I][17:57:02.845059] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [Music Player Daemon] GET_SINK_INPUT_INFO tag:8 index:1624 name:(null)
[I][17:57:02.845163] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [Music Player Daemon] GET_SINK_INPUT_INFO tag:9 index:1624 name:(null)
[I][17:57:02.845207] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SINK_INPUT_INFO tag:204 index:1624 name:(null)
[I][17:57:02.845243] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_SOURCE_OUTPUT_INFO tag:205 index:1637 name:(null)
[I][17:57:04.663004] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6a308110 AUTH tag:0 version:35
[I][17:57:04.663174] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:57:04.663223] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:57:04.663341] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:57:04.664421] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:206 index:1751 name:(null)
[I][17:57:04.665816] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:80 index:1751
[I][17:57:04.665978] mod.protocol-pulse | [ pulse-server.c: 3269 do_get_server_info()] [pactl] GET_SERVER_INFO tag:2
[I][17:57:04.666148] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6a308110 [pactl] disconnected
[I][17:57:04.672255] mod.protocol-pulse | [ pulse-server.c: 137 do_command_auth()] client:0x55be6b7dc860 AUTH tag:0 version:35
[I][17:57:04.672412] pw.conf | [ conf.c: 1111 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.rules'
[I][17:57:04.672463] mod.protocol-pulse | [ pulse-server.c: 1020 do_set_client_name()] [pactl] SET_CLIENT_NAME tag:1
[I][17:57:04.672549] mod.protocol-native | [ local-socket.c: 72 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][17:57:04.673404] mod.protocol-pulse | [ pulse-server.c: 4238 do_get_info()] [PulseAudio Volume Control] GET_CLIENT_INFO tag:207 index:1752 name:(null)
[I][17:57:04.674574] mod.protocol-pulse | [ pulse-server.c: 161 reply_set_client_name()] [pactl] reply tag:1 id:80 index:1752
[I][17:57:04.674719] mod.protocol-pulse | [ pulse-server.c: 4394 do_get_info_list()] [pactl] GET_SINK_INFO_LIST tag:2
[I][17:57:04.675250] mod.protocol-pulse | [ server.c: 341 on_client_data()] server 0x55be6a106c20: client 0x55be6b7dc860 [pactl] disconnected
More information about the Pkg-utopia-maintainers
mailing list