[pulseaudio-discuss] Pulseaudio 8.0, paplay BUS ERROR

Mon Mar 6 08:30:15 UTC 2017

Hi all,

I am trying to stream audio from my embedded Linux board to Bluetooth
speaker and everything seems to be OK with pair/connect/stream music
with 8, 11, and 22 KHz to the Bluetooth Speaker.
However, when I tried to play 44KHz music using PAPLAY I got the "BUS
ERROR" and PAPLAY exits immediately without streaming any sound.
I have tried to tweet around configuration files but the issue still
happens. It would be great if you can give me some suggestions or
advice.

Sorry for the long attached log.

- Here is my log:
1. BlueZ 5.43
2. Pulseaudio 8.0

- Log when using PAPLAY:
/tmp # paplay -v pcm0844s.wav
Opening a playback stream with sample specification 's16le 2ch
44100Hz' and channel map 'front-left,front-right'.
Connection established.
Stream successfully created.
Buffer metrics: maxlength=4194304, tlength=352800, prebuf=349276, minreq=3528
Using sample spec 's16le 2ch 44100Hz', channel map 'front-left,front-right'.
Connected to device bluez_sink.00_51_2B_A6_44_00 (index: 1, suspended: no).
Bus error

- Log verbose of pulseaudio daemon:
W: [pulseaudio] main.c: This program is not intended to be run as root
(unless --system is specified).
D: [pulseaudio] conf-parser.c: Parsing configuration file
'//etc/pulse/client.conf'
D: [pulseaudio] conf-parser.c: //etc/pulse/client.conf.d does not
exist, ignoring.
D: [pulseaudio] core-rtclock.c: Timer slack is set to 50 us.
D: [pulseaudio] core-util.c: setpriority() worked.
I: [pulseaudio] core-util.c: Successfully gained nice level -11.
I: [pulseaudio] main.c: This is PulseAudio 8.0
D: [pulseaudio] main.c: Running on host: Linux armv7l 3.14.43 #1 SMP
PREEMPT Fri Mar 3 15:54:52 ICT 2017
D: [pulseaudio] main.c: Found 4 CPUs.
I: [pulseaudio] main.c: Page size is 4096 bytes
D: [pulseaudio] main.c: Compiled with Valgrind support: no
D: [pulseaudio] main.c: Running in valgrind mode: no
D: [pulseaudio] main.c: Running in VM: no
D: [pulseaudio] main.c: Optimized build: yes
D: [pulseaudio] main.c: FASTPATH defined, only fast path asserts disabled.
I: [pulseaudio] main.c: Machine ID is harlek-computer.
I: [pulseaudio] main.c: Using runtime directory
//.config/pulse/harlek-computer-runtime.
I: [pulseaudio] main.c: Using state directory //.config/pulse.
I: [pulseaudio] main.c: Using modules directory //lib/pulse-8.0/modules.
I: [pulseaudio] main.c: Running in system mode: no
W: [pulseaudio] pid.c: Stale PID file, overwriting.
I: [pulseaudio] main.c: System supports high resolution timers
D: [pulseaudio] memblock.c: Using private memory pool with 1024 slots
of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot
size is 65496
I: [pulseaudio] cpu-arm.c: CPU flags: V6 V7 VFP EDSP NEON VFPV3
I: [pulseaudio] svolume_arm.c: Initialising ARM optimized volume functions.
I: [pulseaudio] module-device-restore.c: Successfully opened database
file '//.config/pulse/harlek-computer-device-volumes'.
I: [pulseaudio] module.c: Loaded "module-device-restore" (index: #0;
argument: "").
I: [pulseaudio] module-stream-restore.c: Successfully opened database
file '//.config/pulse/harlek-computer-stream-volumes'.
D: [pulseaudio] protocol-dbus.c: Interface
org.PulseAudio.Ext.StreamRestore1 added for object
/org/pulseaudio/stream_restore1
D: [pulseaudio] protocol-dbus.c: Interface
org.PulseAudio.Ext.StreamRestore1.RestoreEntry added for object
/org/pulseaudio/stream_restore1/entry0
I: [pulseaudio] module.c: Loaded "module-stream-restore" (index: #1;
argument: "").
I: [pulseaudio] module-card-restore.c: Successfully opened database
file '//.config/pulse/harlek-computer-card-database'.
I: [pulseaudio] module.c: Loaded "module-card-restore" (index: #2;
argument: "").
I: [pulseaudio] module.c: Loaded "module-augment-properties" (index:
#3; argument: "").
I: [pulseaudio] module.c: Loaded "module-switch-on-port-available"
(index: #4; argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-jackdbus-detect.so': failure
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-bluetooth-policy.so': success
I: [pulseaudio] module.c: Loaded "module-bluetooth-policy" (index: #5;
argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-bluetooth-discover.so': success
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-bluez5-discover.so': success
D: [pulseaudio] dbus-util.c: Successfully connected to D-Bus system
bus cdb2e23e5f00b38a19e1db4400000027 as :1.11
I: [pulseaudio] module.c: Loaded "module-bluez5-discover" (index: #7;
argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-bluez4-discover.so': failure
I: [pulseaudio] module.c: Loaded "module-bluetooth-discover" (index:
#6; argument: "").
I: [pulseaudio] module.c: Loaded "module-native-protocol-unix" (index:
#8; argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-gconf.so': failure
I: [pulseaudio] module-default-device-restore.c: Saved default sink
'auto_null' not existent, not restoring default sink setting.
I: [pulseaudio] module-default-device-restore.c: Saved default source
'auto_null.monitor' not existent, not restoring default source
setting.
I: [pulseaudio] module.c: Loaded "module-default-device-restore"
(index: #9; argument: "").
I: [pulseaudio] module.c: Loaded "module-rescue-streams" (index: #10;
argument: "").
D: [pulseaudio] module-always-sink.c: Autoloading null-sink as no
other sinks detected.
I: [pulseaudio] sink.c: Created sink 0 "auto_null" with sample spec
s16le 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] sink.c:     device.description = "Dummy Output"
I: [pulseaudio] sink.c:     device.class = "abstract"
I: [pulseaudio] sink.c:     device.icon_name = "audio-card"
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
I: [pulseaudio] source.c: Created source 0 "auto_null.monitor" with
sample spec s16le 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] source.c:     device.description = "Monitor of Dummy Output"
I: [pulseaudio] source.c:     device.class = "monitor"
I: [pulseaudio] source.c:     device.icon_name = "audio-input-microphone"
D: [null-sink] module-null-sink.c: Thread starting up
D: [pulseaudio] module-device-restore.c: Could not set format on sink auto_null
I: [pulseaudio] module.c: Loaded "module-null-sink" (index: #12;
argument: "sink_name=auto_null
sink_properties='device.description="Dummy Output"'").
I: [pulseaudio] module.c: Loaded "module-always-sink" (index: #11;
argument: "").
I: [pulseaudio] module.c: Loaded "module-intended-roles" (index: #13;
argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/lib/pulse-8.0/modules/module-systemd-login.so': failure
I: [pulseaudio] module.c: Loaded "module-position-event-sounds"
(index: #14; argument: "").
D: [pulseaudio] module-role-cork.c: Using role 'phone' as trigger role.
D: [pulseaudio] module-role-cork.c: Using roles 'music' and 'video' as
cork roles.
I: [pulseaudio] module.c: Loaded "module-role-cork" (index: #15; argument: "").
I: [pulseaudio] module.c: Loaded "module-filter-heuristics" (index:
#16; argument: "").
I: [pulseaudio] module.c: Loaded "module-filter-apply" (index: #17;
argument: "").
W: [pulseaudio] server-lookup.c: Unable to contact D-Bus:
org.freedesktop.DBus.Error.NotSupported: Using X11 for dbus-daemon
autolaunch was disabled at compile time, set your
DBUS_SESSION_BUS_ADDRESS instead
W: [pulseaudio] main.c: Unable to contact D-Bus:
org.freedesktop.DBus.Error.NotSupported: Using X11 for dbus-daemon
autolaunch was disabled at compile time, set your
DBUS_SESSION_BUS_ADDRESS instead
I: [pulseaudio] main.c: Daemon startup complete.
I: [pulseaudio] main.c: Daemon startup successful.
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.AgentManager1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.ProfileManager1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Adapter /org/bluez/hci0 found
D: [pulseaudio] bluez5-util.c: Registering /MediaEndpoint/A2DPSource
on adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Registering /MediaEndpoint/A2DPSink on
adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Properties found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.GattManager1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.Media1
found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.NetworkServer1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Device
/org/bluez/hci0/dev_00_51_2B_A6_44_00 found
D: [pulseaudio] bluez5-util.c: Address: 00:51:2B:A6:44:00
D: [pulseaudio] bluez5-util.c: Alias: V-24BT
D: [pulseaudio] bluez5-util.c: Class: 2360324
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110d-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: Adapter: /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Properties found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.MediaControl1 found, skipping
D: [pulseaudio] backend-native.c: Bluetooth Headset Backend API
support using the native backend
D: [pulseaudio] backend-native.c: Registering Profile /Profile/HSPAGProfile
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Device
/org/bluez/hci0/dev_00_51_2B_A6_44_00 removed
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Device
/org/bluez/hci0/dev_34_BE_00_42_E2_61 found
D: [pulseaudio] bluez5-util.c: Address: 34:BE:00:42:E2:61
D: [pulseaudio] bluez5-util.c: Alias: GT-S7580
D: [pulseaudio] bluez5-util.c: Class: 5898764
D: [pulseaudio] bluez5-util.c: UUIDs: 00001105-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110a-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001112-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001115-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001116-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000111f-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000112d-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000112f-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001200-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001132-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: Adapter: /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Properties found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Device
/org/bluez/hci0/dev_00_51_2B_A6_44_00 found
D: [pulseaudio] bluez5-util.c: Address: 00:51:2B:A6:44:00
D: [pulseaudio] bluez5-util.c: Alias: V-24BT
D: [pulseaudio] bluez5-util.c: Class: 2360324
D: [pulseaudio] bluez5-util.c: UUIDs: 0000111e-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001108-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110d-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: Adapter: /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Properties found, skipping
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_34_BE_00_42_E2_61
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_34_BE_00_42_E2_61
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_34_BE_00_42_E2_61
D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.MediaControl1 found, skipping
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
D: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource,
interface=org.bluez.MediaEndpoint1, member=SelectConfiguration
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.MediaTransport1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Properties found, skipping
D: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource,
interface=org.bluez.MediaEndpoint1, member=SetConfiguration
D: [pulseaudio] bluez5-util.c: Transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0 state changed from
disconnected to idle
D: [pulseaudio] module-bluez5-discover.c: Loading module-bluez5-device
path=/org/bluez/hci0/dev_00_51_2B_A6_44_00
I: [pulseaudio] module-card-restore.c: Restoring port latency offsets
for card bluez_card.00_51_2B_A6_44_00.
I: [pulseaudio] card.c: Created 0 "bluez_card.00_51_2B_A6_44_00"
D: [pulseaudio] module-bluez5-device.c: Acquiring transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0
I: [pulseaudio] module-bluez5-device.c: Transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0 acquired: fd 9
I: [pulseaudio] module-bluez5-device.c: SBC parameters: allocation=0,
subbands=1, blocks=3, bitpool=53
I: [pulseaudio] sink.c: Created sink 1 "bluez_sink.00_51_2B_A6_44_00"
with sample spec s16le 2ch 44100Hz and channel map
front-left,front-right
I: [pulseaudio] sink.c:     bluetooth.protocol = "a2dp_sink"
I: [pulseaudio] sink.c:     device.description = "V-24BT"
I: [pulseaudio] sink.c:     device.string = "00:51:2B:A6:44:00"
I: [pulseaudio] sink.c:     device.api = "bluez"
I: [pulseaudio] sink.c:     device.class = "sound"
I: [pulseaudio] sink.c:     device.bus = "bluetooth"
I: [pulseaudio] sink.c:     device.form_factor = "headset"
I: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_00_51_2B_A6_44_00"
I: [pulseaudio] sink.c:     bluez.class = "0x240404"
I: [pulseaudio] sink.c:     bluez.alias = "V-24BT"
I: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
I: [pulseaudio] sink.c:     device.intended_roles = "phone"
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
I: [pulseaudio] source.c: Created source 1
"bluez_sink.00_51_2B_A6_44_00.monitor" with sample spec s16le 2ch
44100Hz and channel map front-left,front-right
I: [pulseaudio] source.c:     device.description = "Monitor of V-24BT"
I: [pulseaudio] source.c:     device.class = "monitor"
I: [pulseaudio] source.c:     device.string = "00:51:2B:A6:44:00"
I: [pulseaudio] source.c:     device.api = "bluez"
I: [pulseaudio] source.c:     device.bus = "bluetooth"
I: [pulseaudio] source.c:     device.form_factor = "headset"
I: [pulseaudio] source.c:     bluez.path =
"/org/bluez/hci0/dev_00_51_2B_A6_44_00"
I: [pulseaudio] source.c:     bluez.class = "0x240404"
I: [pulseaudio] source.c:     bluez.alias = "V-24BT"
I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
I: [pulseaudio] source.c:     device.intended_roles = "phone"
D: [bluetooth] module-bluez5-device.c: IO Thread starting up
D: [bluetooth] core-util.c: SCHED_RR|SCHED_RESET_ON_FORK worked.
I: [bluetooth] core-util.c: Successfully enabled SCHED_RR scheduling
for thread, with priority 5.
I: [bluetooth] module-bluez5-device.c: Transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0 resuming
D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're
ready to roll!
D: [bluetooth] module-bluez5-device.c: Using SBC encoder
implementation: ARMv6 SIMD
D: [pulseaudio] module-device-restore.c: Could not set format on sink
bluez_sink.00_51_2B_A6_44_00
D: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be
selected for loopback
I: [pulseaudio] module-always-sink.c: A new sink has been discovered.
Unloading null-sink.
I: [pulseaudio] module.c: Loaded "module-bluez5-device" (index: #18;
argument: "path=/org/bluez/hci0/dev_00_51_2B_A6_44_00").
D: [pulseaudio] bluez5-util.c: Transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0 available for profile
a2dp_sink
I: [pulseaudio] module.c: Unloading "module-null-sink" (index: #12).
D: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
D: [null-sink] module-null-sink.c: Thread shutting down
I: [pulseaudio] sink.c: Freeing sink 0 "auto_null"
I: [pulseaudio] source.c: Freeing source 0 "auto_null.monitor"
I: [pulseaudio] module.c: Unloaded "module-null-sink" (index: #12).
D: [pulseaudio] bluez5-util.c: Properties changed in transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0
D: [pulseaudio] bluez5-util.c: Transport
/org/bluez/hci0/dev_00_51_2B_A6_44_00/fd0 state changed from idle to
playing
D: [pulseaudio] card.c: Setting card bluez_card.00_51_2B_A6_44_00
profile a2dp_sink to availability status yes
D: [pulseaudio] device-port.c: Setting port headset-output to status yes
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
D: [pulseaudio] module-bluez5-device.c: Resuming sink
bluez_sink.00_51_2B_A6_44_00 because its transport state changed to
playing
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_00_51_2B_A6_44_00
I: [pulseaudio] client.c: Created 0 "Native client (UNIX socket client)"
D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
D: [pulseaudio] protocol-native.c: SHM possible: no
D: [pulseaudio] protocol-native.c: Negotiated SHM: no
D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: No SHM support
D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for pacat
D: [pulseaudio] module-intended-roles.c: Not setting device for stream
pcm0844s.wav, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm,
format.sample_format = "\"s16le\""  format.rate = "44100"
format.channels = "2"  format.channel_map =
"\"front-left,front-right\""
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432,
tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432,
tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 0 "pcm0844s.wav" on
bluez_sink.00_51_2B_A6_44_00 with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.format = "WAV (Microsoft)"
I: [pulseaudio] sink-input.c:     application.name = "paplay"
I: [pulseaudio] sink-input.c:     media.name = "pcm0844s.wav"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "30"
I: [pulseaudio] sink-input.c:     application.process.id = "22652"
I: [pulseaudio] sink-input.c:     application.process.user = "root"
I: [pulseaudio] sink-input.c:     application.process.host = "harlek-computer"
I: [pulseaudio] sink-input.c:     application.process.binary = "pacat"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     application.process.machine_id =
"harlek-computer"
I: [pulseaudio] sink-input.c:     module-stream-restore.id =
"sink-input-by-application-name:paplay"
I: [pulseaudio] protocol-native.c: Requested tlength=2000.00 ms, minreq=20.00 ms
D: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying
sink usec only for compat with minreq.
D: [pulseaudio] protocol-native.c: Requested latency=1960.00 ms,
Received latency=39.51 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
tlength=352800, base=4, prebuf=349276, minreq=3528 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
tlength=352800, base=4, prebuf=349276, minreq=3528 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 2039.51 ms = 1960.00
ms + 2*20.00 ms + 39.51 ms
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
I: [pulseaudio] sink-input.c: Freeing input 0 "pcm0844s.wav"
I: [pulseaudio] client.c: Freed 0 "paplay"
I: [pulseaudio] protocol-native.c: Connection died.

Thank you,
Hieu.