[pulseaudio-discuss] Horribly choppy sound during bluetooth a2dp playback
Vladimir Lebedev
zeronineseven at gmail.com
Sun Sep 3 22:23:47 UTC 2017
Hi All,
I'm trying to create bluetooth speaker from my nanopi neo plus 2 SBC
(running Ubuntu 16.04.3 LTS with Linux 4.11.2 provided by FriendlyElec).
(With some pain) I've managed to make all native linux clients to use
pulseaudio server on nanopi as network sink via zeroconf. But I'm stuck
with bluetooth - currently all my android devices can seamlessly connect to
pulseaudio server and are correctly negotiated as a2dp devices but actual
sound playback is horribly choppy.
I've tried to play with priority and realtime settings, performed NTP
timesync on all devices involved(including android phones), disabled WIFI
on nanopi without any result. Also it looks like that distance between
bluetooth transmitter and reciever doesn't play any role - skipping is
constant. Or I'm hearing it as constant.
My google-fu failed me so any help would be greatly appreciated! I've
already spent two days to no avail. I think that my next step if posting
here won't succeed will be digging pulseaudio source code and a2dp spec and
this idea hurts =/
Thanks and Regards,
Vladimir
pulse at garble:~$ /usr/bin/dbus-run-session /usr/bin/pulseaudio -n
--daemonize=no --realtime=no --high-priority=no -L "module-suspend-on-idle"
-L "module-position-event-sounds" -L "module-alsa-sink device=hw:1,0" -L
"module-native-protocol-tcp auth-ip-acl=127.0.0.1;192.168.1.0/24
auth-anonymous=1" -L "module-zeroconf-publish" -L "module-bluetooth-policy"
-L "module-bluetooth-discover" -vvvv
I: [pulseaudio] main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation
not
permitted
I: [pulseaudio] main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation
not
permitted
D: [pulseaudio] core-rtclock.c: Timer slack is set to 50
us.
I: [pulseaudio] main.c: This is PulseAudio
8.0
D: [pulseaudio] main.c: Compilation host:
aarch64-unknown-linux-gnu
D: [pulseaudio] main.c: Compilation CFLAGS: -g -O2 -fstack-protector-strong
-Wformat -Werror=format-security -Wall -W -Wextra -pipe -Wno-long-long
-Wno-overlength-strings -Wunsafe-loop-optimizations -Wundef -Wformat=2
-Wlogical-op -Wsign-compare -Wformat-security -Wmissing-include-dirs
-Wformat-nonliteral -Wpointer-arith -Winit-self
-Wdeclaration-after-statement -Wfloat-equal -Wmissing-prototypes
-Wredundant-decls -Wmissing-declarations -Wmissing-noreturn -Wshadow
-Wendif-labels -Wcast-align -Wstrict-aliasing -Wwrite-strings
-Wno-unused-parameter -ffast-math -fno-common -fdiagnostics-show-option
-fdiagnostics-color=auto
D: [pulseaudio] main.c: Running on host: Linux aarch64 4.11.2 #119 SMP Tue
Jun 13 18:55:51 CST
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
aac3ac07e4df97b8e52f39c0577cda62.
I: [pulseaudio] main.c: Using runtime directory
/var/run/pulse/.config/pulse/aac3ac07e4df97b8e52f39c0577cda62-runtime.
I: [pulseaudio] main.c: Using state directory
/var/run/pulse/.config/pulse.
I: [pulseaudio] main.c: Using modules directory
/usr/lib/pulse-8.0/modules.
I: [pulseaudio] main.c: Running in system mode:
no
I: [pulseaudio] main.c: System supports high resolution
timers
D: [pulseaudio] memblock.c: Using shared memory pool with 1024 slots of
size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is
65472
D: [pulseaudio] memblock.c: Using shared memory pool with 1024 slots of
size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is
65472
I: [pulseaudio] module.c: Loaded "module-suspend-on-idle" (index: #0;
argument:
"").
I: [pulseaudio] module.c: Loaded "module-position-event-sounds" (index: #1;
argument:
"").
D: [pulseaudio] alsa-util.c: Trying hw:1,0 with SND_PCM_NO_AUTO_FORMAT
...
D: [pulseaudio] alsa-util.c: Managed to open
hw:1,0
I: [pulseaudio] alsa-util.c: Cannot disable ALSA period
wakeups
D: [pulseaudio] alsa-util.c: Maximum hw buffer size is 5944
ms
D: [pulseaudio] alsa-util.c: Set buffer size first (to 88200 samples),
period size second (to 88200
samples).
I: [pulseaudio] alsa-util.c: ALSA period wakeups were not
disabled
I: [pulseaudio] alsa-sink.c: Successfully opened device
hw:1,0.
I: [pulseaudio] alsa-sink.c: Successfully enabled mmap()
mode.
I: [pulseaudio] alsa-sink.c: Successfully enabled timer-based scheduling
mode.
I: [pulseaudio] sink.c: Created sink 0 "alsa_output.hw_1_0" with sample
spec s16le 2ch 44100Hz and channel map
front-left,front-right
I: [pulseaudio] sink.c: alsa.resolution_bits =
"16"
I: [pulseaudio] sink.c: device.api =
"alsa"
I: [pulseaudio] sink.c: device.class =
"sound"
I: [pulseaudio] sink.c: alsa.class =
"generic"
I: [pulseaudio] sink.c: alsa.subclass = "generic-mix"
I: [pulseaudio] sink.c: alsa.name = "USB Audio"
I: [pulseaudio] sink.c: alsa.id = "USB Audio"
I: [pulseaudio] sink.c: alsa.subdevice = "0"
I: [pulseaudio] sink.c: alsa.subdevice_name = "subdevice #0"
I: [pulseaudio] sink.c: alsa.device = "0"
I: [pulseaudio] sink.c: alsa.card = "1"
I: [pulseaudio] sink.c: alsa.card_name = "Audioengine 2+"
I: [pulseaudio] sink.c: alsa.long_card_name = "Audioengine Audioengine
2+ at usb-1c1a400.usb-1, full speed"
I: [pulseaudio] sink.c: alsa.driver_name = "snd_usb_audio"
I: [pulseaudio] sink.c: device.bus_path =
"platform-1c1a400.usb-usb-0:1:1.0"
I: [pulseaudio] sink.c: sysfs.path =
"/devices/platform/soc/1c1a400.usb/usb2/2-1/2-1:1.0/sound/card1"
I: [pulseaudio] sink.c: udev.id = "usb-Audioengine_Audioengine_2+-00"
I: [pulseaudio] sink.c: device.bus = "usb"
I: [pulseaudio] sink.c: device.vendor.id = "08bb"
I: [pulseaudio] sink.c: device.vendor.name = "Texas Instruments"
I: [pulseaudio] sink.c: device.product.id = "2704"
I: [pulseaudio] sink.c: device.product.name = "Audio Codec"
I: [pulseaudio] sink.c: device.serial = "Audioengine_Audioengine_2+"
I: [pulseaudio] sink.c: device.string = "hw:1,0"
I: [pulseaudio] sink.c: device.buffering.buffer_size = "352800"
I: [pulseaudio] sink.c: device.buffering.fragment_size = "176400"
I: [pulseaudio] sink.c: device.access_mode = "mmap+timer"
I: [pulseaudio] sink.c: device.description = "Audio Codec"
I: [pulseaudio] sink.c: device.icon_name = "audio-card-usb"
I: [pulseaudio] source.c: Created source 0 "alsa_output.hw_1_0.monitor"
with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] source.c: device.description = "Monitor of Audio Codec"
I: [pulseaudio] source.c: device.class = "monitor"
I: [pulseaudio] source.c: device.icon_name = "audio-input-microphone"
I: [pulseaudio] alsa-sink.c: Using 2.0 fragments of size 176400 bytes
(1000.00ms), buffer size is 352800 bytes (2000.00ms)
I: [pulseaudio] alsa-sink.c: Time scheduling watermark is 20.00ms
D: [pulseaudio] alsa-sink.c: hwbuf_unused=0
D: [pulseaudio] alsa-sink.c: setting avail_min=87319
D: [pulseaudio] alsa-util.c: snd_pcm_dump():
D: [pulseaudio] alsa-util.c: Hardware PCM card 1 'Audioengine 2+' device 0
subdevice 0
D: [pulseaudio] alsa-util.c: Its setup is:
D: [pulseaudio] alsa-util.c: stream : PLAYBACK
D: [pulseaudio] alsa-util.c: access : MMAP_INTERLEAVED
D: [pulseaudio] alsa-util.c: format : S16_LE
D: [pulseaudio] alsa-util.c: subformat : STD
D: [pulseaudio] alsa-util.c: channels : 2
D: [pulseaudio] alsa-util.c: rate : 44100
D: [pulseaudio] alsa-util.c: exact rate : 44100 (44100/1)
D: [pulseaudio] alsa-util.c: msbits : 16
D: [pulseaudio] alsa-util.c: buffer_size : 88200
D: [pulseaudio] alsa-util.c: period_size : 44100
D: [pulseaudio] alsa-util.c: period_time : 1000000
D: [pulseaudio] alsa-util.c: tstamp_mode : ENABLE
D: [pulseaudio] alsa-util.c: tstamp_type : MONOTONIC
D: [pulseaudio] alsa-util.c: period_step : 1
D: [pulseaudio] alsa-util.c: avail_min : 87319
D: [pulseaudio] alsa-util.c: period_event : 0
D: [pulseaudio] alsa-util.c: start_threshold : -1
D: [pulseaudio] alsa-util.c: stop_threshold : 6206523236469964800
D: [pulseaudio] alsa-util.c: silence_threshold: 0
D: [pulseaudio] alsa-util.c: silence_size : 0
D: [pulseaudio] alsa-util.c: boundary : 6206523236469964800
D: [pulseaudio] alsa-util.c: appl_ptr : 0
D: [pulseaudio] alsa-util.c: hw_ptr : 0
D: [alsa-sink-USB Audio] alsa-sink.c: Thread starting up
I: [alsa-sink-USB Audio] alsa-sink.c: Starting playback.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
idle, timeout in 5 seconds.
I: [pulseaudio] module.c: Loaded "module-alsa-sink" (index: #2; argument:
"device=hw:1,0").
I: [pulseaudio] module.c: Loaded "module-native-protocol-tcp" (index: #3;
argument: "auth-ip-acl=127.0.0.1;192.168.1.0/24 auth-anonymous=1").
I: [pulseaudio] module.c: Loaded "module-zeroconf-publish" (index: #4;
argument: "").
I: [pulseaudio] module.c: Loaded "module-bluetooth-policy" (index: #5;
argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/usr/lib/pulse-8.0/modules/module-bluez5-discover.so': success
D: [pulseaudio] dbus-util.c: Successfully connected to D-Bus system bus
197c5809421b7039cf6f329b56bcb692 as :1.126
D: [avahi-ml] module-zeroconf-publish.c: Started Avahi threaded mainloop
I: [pulseaudio] module.c: Loaded "module-bluez5-discover" (index: #7;
argument: "").
D: [pulseaudio] module.c: Checking for existence of
'/usr/lib/pulse-8.0/modules/module-bluez4-discover.so': failure
I: [pulseaudio] module.c: Loaded "module-bluetooth-discover" (index: #6;
argument: "").
D: [pulseaudio] dbus-util.c: Successfully connected to D-Bus session bus
475bf96fe066f0d89a78457859ac7a24 as :1.0
D: [pulseaudio] main.c: Got org.PulseAudio1!
D: [pulseaudio] main.c: Got org.pulseaudio.Server!
I: [pulseaudio] main.c: Daemon startup complete.
D: [pulseaudio] module-zeroconf-publish.c: Publishing services in Zeroconf
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.bluez.Alert1 found,
skipping
D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.HealthManager1
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.Media1 found,
skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.CyclingSpeedManager1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.HeartRateManager1 found, skipping
D: [avahi-ml] module-zeroconf-publish.c: Successfully created entry group
for pulse at garble: Audio Codec.
D: [pulseaudio] bluez5-util.c: Unknown interface
org.bluez.ThermometerManager1 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_44_85_00_12_DC_B5
found
D: [pulseaudio] bluez5-util.c: Address: 44:85:00:12:DC:B5
D: [pulseaudio] bluez5-util.c: Alias: discord
D: [pulseaudio] bluez5-util.c: Class: 1048844
D: [pulseaudio] bluez5-util.c: UUIDs: 00001104-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001105-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001106-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000112f-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001132-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001133-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001200-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001800-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001801-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00005005-0000-1000-8000-0002ee000001
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.ProximityReporter1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaControl1
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_B0_79_94_6B_96_B4
found
D: [pulseaudio] bluez5-util.c: Address: B0:79:94:6B:96:B4
D: [pulseaudio] bluez5-util.c: Alias: Motorola Razr HD
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: 0000110d-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110e-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: 0000112f-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001132-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001200-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.Network1 found,
skipping
D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaControl1
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_40_B8_37_7A_8C_2D
found
D: [pulseaudio] bluez5-util.c: Address: 40:B8:37:7A:8C:2D
D: [pulseaudio] bluez5-util.c: Alias: Xperia Z5 Compact
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: 0000110d-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 0000110e-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: 0000112f-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001132-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001200-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001800-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 00001801-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: 8e780202-0000-1000-8000-00805f9b34fb
D: [pulseaudio] bluez5-util.c: UUIDs: fa87c0d0-afac-11de-8a39-0800200c9a67
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.ProximityReporter1 found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.Network1 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
E: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio
agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name
org.ofono was not provided by any .service files
D: [pulseaudio] bluez5-util.c: oFono is running: no
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
I: [avahi-ml] module-zeroconf-publish.c: Successfully established main
service.
I: [avahi-ml] module-zeroconf-publish.c: Successfully established service
pulse at garble: Audio Codec.
I: [pulseaudio] socket-server.c: TCP connection accepted by tcpwrap.
I: [pulseaudio] client.c: Created 0 "Native client (TCP/IP client from
192.168.1.114:39504)"
I: [pulseaudio] protocol-native.c: Client authenticated anonymously.
I: [pulseaudio] socket-server.c: TCP connection accepted by tcpwrap.
I: [pulseaudio] client.c: Created 1 "Native client (TCP/IP client from
[fe80::b811:2f4:225d:406c]:52262)"
I: [pulseaudio] protocol-native.c: Client authenticated anonymously.
D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 30
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] protocol-native.c: Protocol version: remote 32, local 30
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] 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] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
busy, resuming.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
idle, timeout in 5 seconds.
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 "alsa_output.hw_1_0 for
vlad at flim" on alsa_output.hw_1_0 with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
I: [pulseaudio] sink-input.c: media.name = "alsa_output.hw_1_0 for
vlad at flim"
I: [pulseaudio] sink-input.c: media.role = "abstract"
I: [pulseaudio] sink-input.c: application.name = "pulseaudio"
I: [pulseaudio] sink-input.c: native-protocol.peer = "TCP/IP client
from 192.168.1.114:39504"
I: [pulseaudio] sink-input.c: native-protocol.version = "32"
I: [pulseaudio] sink-input.c: application.id =
"org.PulseAudio.PulseAudio"
I: [pulseaudio] sink-input.c: application.version = "10.0"
I: [pulseaudio] sink-input.c: application.process.id = "1007"
I: [pulseaudio] sink-input.c: application.process.user = "vlad"
I: [pulseaudio] sink-input.c: application.process.host = "flim"
I: [pulseaudio] sink-input.c: application.process.binary = "pulseaudio"
I: [pulseaudio] sink-input.c: application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c: window.x11.display = ":0"
I: [pulseaudio] sink-input.c: application.process.machine_id =
"394e84c400af4cd9874f99c31498f178"
I: [pulseaudio] sink-input.c: application.process.session_id = "c1"
I: [pulseaudio] protocol-native.c: Requested tlength=150.00 ms,
minreq=24.99 ms
D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring
sink latency to minreq.
D: [pulseaudio] protocol-native.c: Requested latency=24.99 ms, Received
latency=24.99 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
tlength=26460, base=4, prebuf=22056, minreq=4408 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
tlength=26460, base=4, prebuf=22056, minreq=4408 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 174.99 ms = 100.02 ms +
2*24.99 ms + 24.99 ms
D: [alsa-sink-USB Audio] alsa-sink.c: Latency set to 24.99ms
D: [alsa-sink-USB Audio] alsa-sink.c: hwbuf_unused=348396
D: [alsa-sink-USB Audio] alsa-sink.c: setting avail_min=87542
D: [alsa-sink-USB Audio] alsa-sink.c: Requesting rewind due to latency
change.
D: [alsa-sink-USB Audio] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: Limited to 333844 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: before: 83461
D: [alsa-sink-USB Audio] alsa-sink.c: after: 83461
D: [alsa-sink-USB Audio] alsa-sink.c: Rewound 333844 bytes.
D: [alsa-sink-USB Audio] sink.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 333844 bytes on
render memblockq.
D: [alsa-sink-USB Audio] source.c: Processing rewind...
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] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
busy, resuming.
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 1 "alsa_output.hw_1_0 for
vlad at flim" on alsa_output.hw_1_0 with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
I: [pulseaudio] sink-input.c: media.name = "alsa_output.hw_1_0 for
vlad at flim"
I: [pulseaudio] sink-input.c: media.role = "abstract"
I: [pulseaudio] sink-input.c: application.name = "pulseaudio"
I: [pulseaudio] sink-input.c: native-protocol.peer = "TCP/IP client
from [fe80::b811:2f4:225d:406c]:52262"
I: [pulseaudio] sink-input.c: native-protocol.version = "32"
I: [pulseaudio] sink-input.c: application.id =
"org.PulseAudio.PulseAudio"
I: [pulseaudio] sink-input.c: application.version = "10.0"
I: [pulseaudio] sink-input.c: application.process.id = "1007"
I: [pulseaudio] sink-input.c: application.process.user = "vlad"
I: [pulseaudio] sink-input.c: application.process.host = "flim"
I: [pulseaudio] sink-input.c: application.process.binary = "pulseaudio"
I: [pulseaudio] sink-input.c: application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c: window.x11.display = ":0"
I: [pulseaudio] sink-input.c: application.process.machine_id =
"394e84c400af4cd9874f99c31498f178"
I: [pulseaudio] sink-input.c: application.process.session_id = "c1"
I: [pulseaudio] protocol-native.c: Requested tlength=150.00 ms,
minreq=24.99 ms
D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring
sink latency to minreq.
D: [pulseaudio] protocol-native.c: Requested latency=24.99 ms, Received
latency=24.99 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
tlength=26460, base=4, prebuf=22056, minreq=4408 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
tlength=26460, base=4, prebuf=22056, minreq=4408 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 174.99 ms = 100.02 ms +
2*24.99 ms + 24.99 ms
D: [alsa-sink-USB Audio] alsa-sink.c: Latency set to 24.99ms
D: [alsa-sink-USB Audio] alsa-sink.c: hwbuf_unused=348396
D: [alsa-sink-USB Audio] alsa-sink.c: setting avail_min=87542
D: [alsa-sink-USB Audio] protocol-native.c: Requesting rewind due to end of
underrun.
D: [alsa-sink-USB Audio] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: Limited to 4148 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: before: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: after: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: Rewound 4148 bytes.
D: [alsa-sink-USB Audio] sink.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] source.c: Processing rewind...
D: [alsa-sink-USB Audio] protocol-native.c: Requesting rewind due to end of
underrun.
D: [alsa-sink-USB Audio] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: Limited to 4148 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: before: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: after: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: Rewound 4148 bytes.
D: [alsa-sink-USB Audio] sink.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] source.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-USB Audio] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: Limited to 4148 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: before: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: after: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: Rewound 4148 bytes.
D: [alsa-sink-USB Audio] sink.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on
implementor.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] source.c: Processing rewind...
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-USB Audio] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: Limited to 4148 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: before: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: after: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: Rewound 4148 bytes.
D: [alsa-sink-USB Audio] sink.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on
implementor.
D: [alsa-sink-USB Audio] source.c: Processing rewind...
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
idle, timeout in 5 seconds.
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4148
bytes ago (256 bytes ahead in playback buffer)
D: [pulseaudio] bluez5-util.c: Properties changed in device
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D
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/A2DPSink,
interface=org.bluez.MediaEndpoint1, member=SetConfiguration
D: [pulseaudio] bluez5-util.c: Transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14 state changed from disconnected
to idle
D: [pulseaudio] bluez5-util.c: Transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14 available for profile a2dp_source
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 idle for
too long, suspending ...
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.hw_1_0 is 0x0004,
suspending
I: [alsa-sink-USB Audio] alsa-sink.c: Device suspended...
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming
memory
D: [pulseaudio] bluez5-util.c: Timeout expired, and device
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D still has disconnected profiles:
headset_audio_gateway
D: [pulseaudio] module-bluez5-discover.c: Loading module-bluez5-device
path=/org/bluez/hci0/dev_40_B8_37_7A_8C_2D
I: [pulseaudio] card.c: Created 0 "bluez_card.40_B8_37_7A_8C_2D"
D: [pulseaudio] module-bluez5-device.c: Created card (current profile
a2dp_source)
D: [pulseaudio] module-bluez5-device.c: Initializing profile a2dp_source
D: [pulseaudio] module-bluez5-device.c: profile a2dp_source
D: [pulseaudio] module-bluez5-device.c: profile a2dp_source transport
0x12c689a0 transport state idle
D: [pulseaudio] module-bluez5-device.c: Acquiring transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14
I: [pulseaudio] bluez5-util.c: Failed optional acquire of unavailable
transport /org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14
D: [pulseaudio] module-bluez5-device.c: Configuring transport for profile
a2dp_source
I: [pulseaudio] module-bluez5-device.c: SBC parameters: allocation=0,
subbands=1, blocks=3, bitpool=2
D: [pulseaudio] module-bluez5-device.c: Transport for profile a2dp_source
successfully setup
I: [pulseaudio] source.c: Created source 1 "bluez_source.40_B8_37_7A_8C_2D"
with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
I: [pulseaudio] source.c: bluetooth.protocol = "a2dp_source"
I: [pulseaudio] source.c: device.description = "Xperia Z5 Compact"
I: [pulseaudio] source.c: device.string = "40:B8:37:7A:8C:2D"
I: [pulseaudio] source.c: device.api = "bluez"
I: [pulseaudio] source.c: device.class = "sound"
I: [pulseaudio] source.c: device.bus = "bluetooth"
I: [pulseaudio] source.c: device.form_factor = "phone"
I: [pulseaudio] source.c: bluez.path =
"/org/bluez/hci0/dev_40_B8_37_7A_8C_2D"
I: [pulseaudio] source.c: bluez.class = "0x5a020c"
I: [pulseaudio] source.c: bluez.alias = "Xperia Z5 Compact"
I: [pulseaudio] source.c: device.icon_name = "audio-card-bluetooth"
D: [bluetooth] module-bluez5-device.c: IO Thread starting up
D: [pulseaudio] module-bluez5-device.c: Source
bluez_source.40_B8_37_7A_8C_2D state has changed
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes idle, timeout in 5 seconds.
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] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
busy, resuming.
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.hw_1_0 is 0x0000,
resuming
I: [alsa-sink-USB Audio] alsa-sink.c: Trying resume...
I: [alsa-sink-USB Audio] alsa-util.c: Cannot disable ALSA period wakeups
D: [alsa-sink-USB Audio] alsa-util.c: Maximum hw buffer size is 5944 ms
D: [alsa-sink-USB Audio] alsa-util.c: Set buffer size first (to 88200
samples), period size second (to 44100 samples).
I: [alsa-sink-USB Audio] alsa-util.c: ALSA period wakeups were not disabled
D: [alsa-sink-USB Audio] alsa-sink.c: Latency set to 24.99ms
D: [alsa-sink-USB Audio] alsa-sink.c: hwbuf_unused=348396
D: [alsa-sink-USB Audio] alsa-sink.c: setting avail_min=87542
I: [alsa-sink-USB Audio] alsa-sink.c: Time scheduling watermark is 14.96ms
I: [alsa-sink-USB Audio] alsa-sink.c: Resumed successfully...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
idle, timeout in 5 seconds.
D: [pulseaudio] module-bluez5-device.c: Sink alsa_output.hw_1_0 state has
changed
I: [alsa-sink-USB Audio] alsa-sink.c: Starting playback.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-bluez5-device.c: Source alsa_output.hw_1_0.monitor
state has changed
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
idle, timeout in 5 seconds.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c: rate 44100 -> 44100 (method speex-float-1)
D: [pulseaudio] resampler.c: format s16le -> s16le (intermediate
float32le)
D: [pulseaudio] resampler.c: channels 2 -> 2 (resampling 2)
I: [pulseaudio] speex.c: Choosing speex quality setting 1.
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 2 "(null)" on
alsa_output.hw_1_0 with sample spec s16le 2ch 44100Hz and channel map
front-left,front-right
I: [pulseaudio] sink-input.c: media.role = "music"
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] source-output.c: Negotiated format: pcm,
format.sample_format = "\"s16le\"" format.rate = "44100" format.channels
= "2" format.channel_map = "\"front-left,front-right\""
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes busy, resuming.
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes idle, timeout in 5 seconds.
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] source-output.c: Created output 0 "(null)" on
bluez_source.40_B8_37_7A_8C_2D with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
I: [pulseaudio] source-output.c: media.role = "abstract"
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=16777216,
tlength=16777216, base=4, prebuf=0, minreq=0 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=16777216,
tlength=16777216, base=4, prebuf=0, minreq=4 maxrewind=0
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Latency set to 24.99ms
D: [alsa-sink-USB Audio] alsa-sink.c: hwbuf_unused=348396
D: [alsa-sink-USB Audio] alsa-sink.c: setting avail_min=87542
I: [alsa-sink-USB Audio] module-loopback.c: Max request changed
I: [bluetooth] module-loopback.c: Skipping 0 bytes
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes busy, resuming.
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change
event.
I: [pulseaudio] module.c: Loaded "module-loopback" (index: #9; argument:
"source="bluez_source.40_B8_37_7A_8C_2D" source_dont_move="true"
sink_input_properties="media.role=music"").
I: [pulseaudio] module.c: Loaded "module-bluez5-device" (index: #8;
argument: "path=/org/bluez/hci0/dev_40_B8_37_7A_8C_2D").
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [avahi-ml] module-zeroconf-publish.c: Successfully created entry group
for pulse at garble: Xperia Z5 Compact.
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaPlayer1
found, skipping
D: [pulseaudio] bluez5-util.c: Unknown interface
org.freedesktop.DBus.Properties found, skipping
I: [avahi-ml] module-zeroconf-publish.c: Successfully established service
pulse at garble: Xperia Z5 Compact.
I: [pulseaudio] socket-server.c: TCP connection accepted by tcpwrap.
I: [pulseaudio] client.c: Created 2 "Native client (TCP/IP client from
192.168.1.114:39512)"
I: [pulseaudio] protocol-native.c: Client authenticated anonymously.
I: [pulseaudio] socket-server.c: TCP connection accepted by tcpwrap.
I: [pulseaudio] client.c: Created 3 "Native client (TCP/IP client from
[fe80::b811:2f4:225d:406c]:52270)"
I: [pulseaudio] protocol-native.c: Client authenticated anonymously.
D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 30
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] protocol-native.c: Protocol version: remote 32, local 30
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] source-output.c: Negotiated format: pcm,
format.sample_format = "\"s16le\"" format.rate = "44100" format.channels
= "2" format.channel_map = "\"front-left,front-right\""
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes busy, resuming.
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] source-output.c: Created output 1
"bluez_source.40_B8_37_7A_8C_2D for vlad at flim" on
bluez_source.40_B8_37_7A_8C_2D with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
I: [pulseaudio] source-output.c: media.name =
"bluez_source.40_B8_37_7A_8C_2D for vlad at flim"
I: [pulseaudio] source-output.c: media.role = "abstract"
I: [pulseaudio] source-output.c: application.name = "pulseaudio"
I: [pulseaudio] source-output.c: native-protocol.peer = "TCP/IP client
from 192.168.1.114:39512"
I: [pulseaudio] source-output.c: native-protocol.version = "32"
I: [pulseaudio] source-output.c: application.id =
"org.PulseAudio.PulseAudio"
I: [pulseaudio] source-output.c: application.version = "10.0"
I: [pulseaudio] source-output.c: application.process.id = "1007"
I: [pulseaudio] source-output.c: application.process.user = "vlad"
I: [pulseaudio] source-output.c: application.process.host = "flim"
I: [pulseaudio] source-output.c: application.process.binary =
"pulseaudio"
I: [pulseaudio] source-output.c: application.language = "en_US.UTF-8"
I: [pulseaudio] source-output.c: window.x11.display = ":0"
I: [pulseaudio] source-output.c: application.process.machine_id =
"394e84c400af4cd9874f99c31498f178"
I: [pulseaudio] source-output.c: application.process.session_id = "c1"
D: [pulseaudio] protocol-native.c: Could not configure a sufficiently low
latency. Early requests might not be satisifed.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 274.99 ms = 24.99 ms +
250.00 ms
D: [pulseaudio] source-output.c: Negotiated format: pcm,
format.sample_format = "\"s16le\"" format.rate = "44100" format.channels
= "2" format.channel_map = "\"front-left,front-right\""
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes busy, resuming.
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] source-output.c: Created output 2
"bluez_source.40_B8_37_7A_8C_2D for vlad at flim" on
bluez_source.40_B8_37_7A_8C_2D with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
I: [pulseaudio] source-output.c: media.name =
"bluez_source.40_B8_37_7A_8C_2D for vlad at flim"
I: [pulseaudio] source-output.c: media.role = "abstract"
I: [pulseaudio] source-output.c: application.name = "pulseaudio"
I: [pulseaudio] source-output.c: native-protocol.peer = "TCP/IP client
from [fe80::b811:2f4:225d:406c]:52270"
I: [pulseaudio] source-output.c: native-protocol.version = "32"
I: [pulseaudio] source-output.c: application.id =
"org.PulseAudio.PulseAudio"
I: [pulseaudio] source-output.c: application.version = "10.0"
I: [pulseaudio] source-output.c: application.process.id = "1007"
I: [pulseaudio] source-output.c: application.process.user = "vlad"
I: [pulseaudio] source-output.c: application.process.host = "flim"
I: [pulseaudio] source-output.c: application.process.binary =
"pulseaudio"
I: [pulseaudio] source-output.c: application.language = "en_US.UTF-8"
I: [pulseaudio] source-output.c: window.x11.display = ":0"
I: [pulseaudio] source-output.c: application.process.machine_id =
"394e84c400af4cd9874f99c31498f178"
I: [pulseaudio] source-output.c: application.process.session_id = "c1"
D: [pulseaudio] protocol-native.c: Could not configure a sufficiently low
latency. Early requests might not be satisifed.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304,
tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304,
tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 274.99 ms = 24.99 ms +
250.00 ms
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 idle for
too long, suspending ...
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.hw_1_0 is 0x0004,
suspending
I: [alsa-sink-USB Audio] module-loopback.c: Max request changed
I: [alsa-sink-USB Audio] alsa-sink.c: Device suspended...
D: [pulseaudio] module-bluez5-device.c: Sink alsa_output.hw_1_0 state has
changed
D: [pulseaudio] module-bluez5-device.c: Source alsa_output.hw_1_0.monitor
state has changed
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming
memory
D: [pulseaudio] bluez5-util.c: Properties changed in transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14
D: [pulseaudio] bluez5-util.c: Transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14 state changed from idle to
playing
D: [pulseaudio] module-bluez5-device.c: State of transport for profile
a2dp_source has changed to playing
D: [pulseaudio] module-bluez5-device.c: State of transport for profile
a2dp_source changed to playing
D: [pulseaudio] card.c: Setting card bluez_card.40_B8_37_7A_8C_2D profile
a2dp_source to availability status yes
D: [pulseaudio] device-port.c: Setting port phone-input to status yes
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change
event.
D: [pulseaudio] module-bluez5-device.c: Acquiring transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14
I: [pulseaudio] module-bluez5-device.c: Transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14 acquired: fd 34
D: [pulseaudio] module-bluez5-device.c: Acquiring transport for profile
a2dp_source
D: [pulseaudio] module-bluez5-device.c: Resuming source
bluez_source.40_B8_37_7A_8C_2D because its transport state changed to
playing
D: [pulseaudio] source.c: Suspend cause of source
bluez_source.40_B8_37_7A_8C_2D is 0x0000, resuming
D: [bluetooth] module-bluez5-device.c: Transport already acquired
I: [bluetooth] module-bluez5-device.c: Transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14 resuming
D: [bluetooth] module-bluez5-device.c: Configuring MTU for transport of
profile a2dp_source
D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready
to roll!
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-bluez5-device.c: Source
bluez_source.40_B8_37_7A_8C_2D state has changed
D: [alsa-sink-USB Audio] sink-input.c: Requesting rewind due to uncorking
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.hw_1_0 becomes
busy, resuming.
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.hw_1_0 is 0x0000,
resuming
I: [alsa-sink-USB Audio] alsa-sink.c: Trying resume...
I: [alsa-sink-USB Audio] alsa-util.c: Cannot disable ALSA period wakeups
D: [alsa-sink-USB Audio] alsa-util.c: Maximum hw buffer size is 5944 ms
D: [alsa-sink-USB Audio] alsa-util.c: Set buffer size first (to 88200
samples), period size second (to 44100 samples).
I: [alsa-sink-USB Audio] alsa-util.c: ALSA period wakeups were not disabled
D: [alsa-sink-USB Audio] alsa-sink.c: Latency set to 24.99ms
D: [alsa-sink-USB Audio] alsa-sink.c: hwbuf_unused=348396
D: [alsa-sink-USB Audio] alsa-sink.c: setting avail_min=87542
I: [alsa-sink-USB Audio] module-loopback.c: Max request changed
I: [alsa-sink-USB Audio] alsa-sink.c: Time scheduling watermark is 14.96ms
I: [alsa-sink-USB Audio] alsa-sink.c: Resumed successfully...
D: [pulseaudio] module-bluez5-device.c: Sink alsa_output.hw_1_0 state has
changed
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] alsa-sink.c: Starting playback.
D: [pulseaudio] module-suspend-on-idle.c: Source
bluez_source.40_B8_37_7A_8C_2D becomes busy, resuming.
D: [alsa-sink-USB Audio] ratelimit.c: 387 events suppressed
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-bluez5-device.c: Source
bluez_source.40_B8_37_7A_8C_2D state has changed
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change
event.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-bluez5-device.c: Source alsa_output.hw_1_0.monitor
state has changed
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] module-loopback.c: Loopback overall latency is 18.79 ms +
0.00 ms + 25.00 ms = 43.79 ms
D: [pulseaudio] module-loopback.c: Should buffer 8808 bytes, buffered at
minimum 0 bytes
I: [pulseaudio] module-loopback.c: New rate of 43880 Hz not within 2‰ of
44100 Hz, forcing smaller adjustment
D: [pulseaudio] module-loopback.c: [alsa_output.hw_1_0] Updated sampling
rate to 44011 Hz.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [pulseaudio] bluez5-util.c: Properties changed in transport
/org/bluez/hci0/dev_40_B8_37_7A_8C_2D/fd14
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] alsa-sink.c: Cutting sleep time for the initial
iterations by half.
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] module-loopback.c: Requesting rewind due to end of
underrun.
D: [alsa-sink-USB Audio] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: Limited to 4148 bytes.
D: [alsa-sink-USB Audio] alsa-sink.c: before: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: after: 1037
D: [alsa-sink-USB Audio] alsa-sink.c: Rewound 4148 bytes.
D: [alsa-sink-USB Audio] sink.c: Processing rewind...
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] sink-input.c: Have to rewind 4148 bytes on render
memblockq.
D: [alsa-sink-USB Audio] source.c: Processing rewind...
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 236
bytes ago (4168 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 1296
bytes ago (3108 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 1296
bytes ago (2228 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 3236
bytes ago (1168 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4292
bytes ago (112 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4292
bytes ago (112 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4292
bytes ago (112 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4292
bytes ago (112 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4292
bytes ago (112 bytes ahead in playback buffer)
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 4292
bytes ago (112 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 324
bytes ago (4080 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 1384
bytes ago (3020 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 2440
bytes ago (1964 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
D: [alsa-sink-USB Audio] sink.c: alsa_output.hw_1_0: Found underrun 3500
bytes ago (904 bytes ahead in playback buffer)
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
I: [alsa-sink-USB Audio] module-loopback.c: Could not peek into queue
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/pulseaudio-discuss/attachments/20170904/987a0467/attachment-0001.html>
More information about the pulseaudio-discuss
mailing list