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>