Attachment 127040 Details for Bug 97064 – Bluez and Pulseaudio logs.

Bluez and Pulseaudio logs.

HSP_pulseaudio.logs (text/plain), 90.07 KB, created by bschaef14 on 2016-10-05 22:17:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: bschaef14

Created: 2016-10-05 22:17:22 UTC

Size: 90.07 KB

patch

obsolete

>Comments will be precedded and followed by ### ###
>
>Table of contents:
>0 ) Version information
>1 ) Starting bluetootd and pulseaudio
>2 ) Connect to headset "CB3 Fit"
>3 ) Attempt to play audio via headset_head_unit protocol (FAILS)
>4 ) Switch to a2dp_sink, play audio.
>5 ) Examine pa after aplay hangs 
>
>###
>version information
>###
>
># /usr/lib/bluetooth/bluetoothd --version
>5.40
># pulseaudio --version
>pulseaudio 6.0
># pactl info
>I: [pulseaudio] client.c: Created 16 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Server String: /var/run/pulse/native
>Library Protocol Version: 30
>Server Protocol Version: 30
>Is Local: yes
>Client Index: 16
>Tile Size: 65496
>User Name: pulse
>Host Name: (none)
>Server Name: pulseaudio
>Server Version: 6.0
>Default Sample Specification: s16le 2ch 44100Hz
>Default Channel Map: front-left,front-right
>Default Sink: bluez_sink.00_11_C2_08_15_F6
>Default Source: bluez_source.00_11_C2_08_15_F6
>Cookie: 675e:bce3
>I: [pulseaudio] client.c: Freed 16 "pactl"
>I: [pulseaudio] protocol-native.c: Connection died.
>#
>
>###
>1 ) Starting bluetootd and pulseaudio
>###
>
># /usr/lib/bluetooth/bluetoothd --debug -n &
># bluetoothd[756]: Bluetooth daemon 5.40
>bluetoothd[756]: src/adapter.c:adapter_init() sending read version command
>bluetoothd[756]: Starting SDP server
>bluetoothd[756]: src/sdpd-service.c:register_device_id() Adding device id record for 0002:1d6b:0246:0528
>bluetoothd[756]: src/plugin.c:plugin_init() Loading builtin plugins
>bluetoothd[756]: src/plugin.c:add_plugin() Loading hostname plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading wiimote plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading autopair plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading policy plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading a2dp plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading avrcp plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading network plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading input plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading hog plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading gap plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading scanparam plugin
>bluetoothd[756]: src/plugin.c:add_plugin() Loading deviceinfo plugin
>bluetoothd[756]: src/plugin.c:plugin_init() Loading plugins /usr/lib/bluetooth/plugins
>bluetoothd[756]: profiles/input/suspend-none.c:suspend_init()
>bluetoothd[756]: profiles/network/manager.c:read_config() /etc/bluetooth/network.conf: Key file does not have key 'DisableSecurity'
>bluetoothd[756]: profiles/network/manager.c:read_config() Config options: Security=true
>bluetoothd[756]: src/main.c:main() Entering main loop
>bluetoothd[756]: src/rfkill.c:rfkill_event() RFKILL event idx 0 type 1 op 0 soft 0 hard 0
>bluetoothd[756]: Bluetooth management interface 1.9 initialized
>bluetoothd[756]: src/adapter.c:read_version_complete() sending read supported commands command
>bluetoothd[756]: src/adapter.c:read_version_complete() sending read index list command
>bluetoothd[756]: src/rfkill.c:rfkill_event() RFKILL event idx 1 type 2 op 0 soft 0 hard 0
>bluetoothd[756]: src/adapter.c:read_commands_complete() Number of commands: 61
>bluetoothd[756]: src/adapter.c:read_commands_complete() Number of events: 34
>bluetoothd[756]: src/adapter.c:read_commands_complete() enabling kernel-side connection control
>bluetoothd[756]: src/adapter.c:read_index_list_complete() Number of controllers: 1
>bluetoothd[756]: src/adapter.c:read_index_list_complete() Found index 0
>bluetoothd[756]: src/adapter.c:index_added() index 0
>bluetoothd[756]: src/adapter.c:btd_adapter_new() System name: BlueZ 5.40
>bluetoothd[756]: src/adapter.c:btd_adapter_new() Major class: 0
>bluetoothd[756]: src/adapter.c:btd_adapter_new() Minor class: 0
>bluetoothd[756]: src/adapter.c:btd_adapter_new() Modalias: usb:v1D6Bp0246d0528
>bluetoothd[756]: src/adapter.c:btd_adapter_new() Discoverable timeout: 180 seconds
>bluetoothd[756]: src/adapter.c:btd_adapter_new() Pairable timeout: 0 seconds
>bluetoothd[756]: src/adapter.c:index_added() sending read info command for index 0
>bluetoothd[756]: src/adapter.c:read_info_complete() index 0 status 0x00
>bluetoothd[756]: src/adapter.c:clear_uuids() sending clear uuids command for index 0
>bluetoothd[756]: src/adapter.c:clear_devices() sending clear devices command for index 0
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10001
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001800-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: src/gatt-database.c:gatt_db_service_added() GATT Service added to local database
>bluetoothd[756]: Failed to obtain handles for "Service Changed" characteristic
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10002
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001801-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: src/gatt-database.c:gatt_db_service_added() GATT Service added to local database
>bluetoothd[756]: profiles/audio/a2dp.c:media_server_probe() path /org/bluez/hci0
>bluetoothd[756]: plugins/hostname.c:hostname_probe()
>bluetoothd[756]: profiles/network/manager.c:panu_server_probe() path /org/bluez/hci0
>bluetoothd[756]: profiles/network/server.c:server_register() Registered interface org.bluez.NetworkServer1 on path /org/bluez/hci0
>bluetoothd[756]: profiles/network/manager.c:gn_server_probe() path /org/bluez/hci0
>bluetoothd[756]: profiles/network/manager.c:nap_server_probe() path /org/bluez/hci0
>bluetoothd[756]: profiles/audio/avrcp.c:avrcp_controller_server_probe() path /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10003
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110f-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: profiles/audio/avrcp.c:avrcp_target_server_probe() path /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10004
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110c-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: profiles/audio/a2dp.c:a2dp_sink_server_probe() path /org/bluez/hci0
>bluetoothd[756]: profiles/audio/a2dp.c:a2dp_source_server_probe() path /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:btd_adapter_unblock_address() hci0 00:00:00:00:00:00
>bluetoothd[756]: src/adapter.c:load_link_keys() hci0 keys 0 debug_keys 0
>bluetoothd[756]: src/adapter.c:load_ltks() hci0 keys 0
>bluetoothd[756]: src/adapter.c:load_irks() hci0 irks 0
>bluetoothd[756]: src/adapter.c:load_conn_params() hci0 conn params 0
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: src/adapter.c:set_did() hci0 source 2 vendor 1d6b product 246 version 528
>bluetoothd[756]: src/adapter.c:adapter_register() Adapter /org/bluez/hci0 registered
>bluetoothd[756]: src/adapter.c:set_dev_class() sending set device class command for index 0
>bluetoothd[756]: src/adapter.c:set_name() sending set local name command for index 0
>bluetoothd[756]: src/adapter.c:load_link_keys_complete() link keys loaded for hci0
>bluetoothd[756]: src/adapter.c:load_ltks_complete() LTKs loaded for hci0
>bluetoothd[756]: src/adapter.c:load_irks_complete() IRKs loaded for hci0
>bluetoothd[756]: src/adapter.c:load_conn_params_complete() Connection Parameters loaded for hci0
>
># pulseaudio --system -vvvvv &
># W: [pulseaudio] main.c: Running in system mode, but --disallow-exit not set!
>W: [pulseaudio] main.c: Running in system mode, but --disallow-module-loading not set!
>N: [pulseaudio] main.c: Running in system mode, forcibly disabling SHM mode!
>N: [pulseaudio] main.c: Running in system mode, forcibly disabling exit idle time!
>D: [pulseaudio] core-rtclock.c: Timer slack is set to 50 us.
>D: [pulseaudio] core-util.c: setpriority() worked.
>I: [pulseaudio] core-util.c: Successfully gained nice level -11.
>I: [pulseaudio] main.c: Found user 'pulse' (UID 19) and group 'pulse' (GID 19).
>W: [pulseaudio] main.c: Home directory of user 'pulse' is not '/var/run/pulse', ignoring.
>W: [pulseaudio] caps.c: Normally all extra capabilities would be dropped now, but that's impossible because PulseAudio was built without capabilities support.
>I: [pulseaudio] main.c: Successfully changed user to "pulse".
>I: [pulseaudio] main.c: This is PulseAudio 6.0
>D: [pulseaudio] main.c: Compilation host: armv7l-timesys-linux-gnueabi
>D: [pulseaudio] main.c: Compilation CFLAGS: -Os -pipe -mfpu=neon -mfloat-abi=softfp -Wall -W -Wextra -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 armv7l 4.1.15+ #1 SMP PREEMPT Tue Oct 4 11:12:29 PDT 2016
>D: [pulseaudio] main.c: Found 2 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 (none).
>I: [pulseaudio] main.c: Using runtime directory /var/run/pulse.
>I: [pulseaudio] main.c: Using state directory /var/lib/pulse.
>I: [pulseaudio] main.c: Using modules directory /usr/lib/pulse-6.0/modules.
>I: [pulseaudio] main.c: Running in system mode: yes
>W: [pulseaudio] main.c: OK, so you are running PA in system mode. Please note that you most likely shouldn't be doing that.
>W: [pulseaudio] main.c: If you do it nonetheless then it's your own fault if things don't work as expected.
>W: [pulseaudio] main.c: Please read http://www.freedesktop.org/wiki/Software/PulseAudio/Documentation/User/WhatIsWrongWithSystemWide/ for an explanation why system mode is usually a bad idea.
>I: [pulseaudio] main.c: Fresh high-resolution timers available! Bon appetit!
>D: [pulseaudio] memblock.c: Using private memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65496
>I: [pulseaudio] cpu-arm.c: CPU flags: V6 V7 VFP EDSP NEON VFPV3
>I: [pulseaudio] svolume_arm.c: Initialising ARM optimized volume functions.
>I: [pulseaudio] sconv_neon.c: Initialising ARM NEON optimized conversions.
>I: [pulseaudio] mix_neon.c: Initialising ARM NEON optimized mixing functions.
>I: [pulseaudio] remap_neon.c: Initialising ARM NEON optimized remappers.
>D: [pulseaudio] module.c: Checking for existence of '/usr/lib/pulse-6.0/modules/module-udev-detect.so': success
>I: [pulseaudio] module-udev-detect.c: Found 0 cards.
>I: [pulseaudio] module.c: Loaded "module-udev-detect" (index: #0; argument: "").
>D: [pulseaudio] module.c: Checking for existence of '/usr/lib/pulse-6.0/modules/module-esound-protocol-unix.so': failure
>I: [pulseaudio] module.c: Loaded "module-native-protocol-unix" (index: #1; argument: "").
>D: [pulseaudio] database-gdbm.c: Opened GDBM database '/var/lib/pulse/(none)-stream-volumes.armv7l-timesys-linux-gnueabi.gdbm'
>I: [pulseaudio] module-stream-restore.c: Successfully opened database file '/var/lib/pulse/(none)-stream-volumes'.
>D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Ext.StreamRestore1 added for object /org/pulseaudio/stream_restore1
>I: [pulseaudio] module.c: Loaded "module-stream-restore" (index: #2; argument: "").
>D: [pulseaudio] database-gdbm.c: Opened GDBM database '/var/lib/pulse/(none)-device-volumes.armv7l-timesys-linux-gnueabi.gdbm'
>I: [pulseaudio] module-device-restore.c: Successfully opened database file '/var/lib/pulse/(none)-device-volumes'.
>I: [pulseaudio] module.c: Loaded "module-device-restore" (index: #3; argument: "").
>I: [pulseaudio] module-default-device-restore.c: Saved default sink 'auto_null' not existent, not restoring default sink setting.
>I: [pulseaudio] module-default-device-restore.c: Saved default source 'auto_null.monitor' not existent, not restoring default source setting.
>I: [pulseaudio] module.c: Loaded "module-default-device-restore" (index: #4; argument: "").
>I: [pulseaudio] module.c: Loaded "module-rescue-streams" (index: #5; argument: "").
>D: [pulseaudio] module-always-sink.c: Autoloading null-sink as no other sinks detected.
>I: [pulseaudio] sink.c: Created sink 0 "auto_null" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] sink.c:     device.description = "Dummy Output"
>I: [pulseaudio] sink.c:     device.class = "abstract"
>I: [pulseaudio] sink.c:     device.icon_name = "audio-card"
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>I: [pulseaudio] source.c: Created source 0 "auto_null.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] source.c:     device.description = "Monitor of Dummy Output"
>I: [pulseaudio] source.c:     device.class = "monitor"
>I: [pulseaudio] source.c:     device.icon_name = "audio-input-microphone"
>D: [null-sink] module-null-sink.c: Thread starting up
>D: [pulseaudio] module-device-restore.c: Could not set format on sink auto_null
>I: [pulseaudio] module.c: Loaded "module-null-sink" (index: #7; argument: "sink_name=auto_null sink_properties='device.description="Dummy Output"'").
>I: [pulseaudio] module.c: Loaded "module-always-sink" (index: #6; argument: "").
>I: [pulseaudio] module.c: Loaded "module-position-event-sounds" (index: #8; argument: "").
>I: [pulseaudio] module.c: Loaded "module-bluetooth-policy" (index: #9; argument: "").
>D: [pulseaudio] module.c: Checking for existence of '/usr/lib/pulse-6.0/modules/module-bluez5-discover.so': success
>D: [pulseaudio] dbus-util.c: Successfully connected to D-Bus system bus 84c96c34faf5836ff926f0a300016a41 as :1.4
>I: [pulseaudio] module.c: Loaded "module-bluez5-discover" (index: #11; argument: "headset=native").
>D: [pulseaudio] module.c: Checking for existence of '/usr/lib/pulse-6.0/modules/module-bluez4-discover.so': failure
>I: [pulseaudio] module.c: Loaded "module-bluetooth-discover" (index: #10; argument: "headset=native").
>I: [pulseaudio] module.c: Loaded "module-switch-on-connect" (index: #12; argument: "").
>D: [pulseaudio] main.c: Got org.pulseaudio.Server!
>I: [pulseaudio] main.c: Daemon startup complete.
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.AgentManager1 found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.ProfileManager1 found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Adapter /org/bluez/hci0 found
>D: [pulseaudio] bluez5-util.c: Registering /MediaEndpoint/A2DPSource on adapter /org/bluez/hci0
>D: [pulseaudio] bluez5-util.c: Registering /MediaEndpoint/A2DPSink on adapter /org/bluez/hci0
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.Media1 found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.NetworkServer1 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
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x1391d8 registered: type:0 codec:0 seid:1
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10005
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110a-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: Endpoint registered: sender=:1.4 path=/MediaEndpoint/A2DPSource
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0x1366d0 registered: type:1 codec:0 seid:2
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10006
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110b-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>bluetoothd[756]: Endpoint registered: sender=:1.4 path=/MediaEndpoint/A2DPSink
>bluetoothd[756]: src/profile.c:register_profile() sender :1.4
>bluetoothd[756]: src/profile.c:create_ext() Created "Headset Voice gateway"
>bluetoothd[756]: src/profile.c:ext_adapter_probe() "Headset Voice gateway" probed
>bluetoothd[756]: src/profile.c:ext_start_servers() Headset Voice gateway listening on chan 12
>bluetoothd[756]: src/adapter.c:adapter_service_add() /org/bluez/hci0
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10007
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000003-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001108-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001112-0000-1000-8000-00805f9
>bluetoothd[756]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001203-0000-1000-8000-00805f9
>bluetoothd[756]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
>bluetoothd[756]: src/adapter.c:add_uuid() sending add uuid command for index 0
>D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
>
># pactl list sinks
>I: [pulseaudio] client.c: Created 0 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Sink #0
>        State: IDLE
>        Name: auto_null
>        Description: Dummy Output
>        Driver: module-null-sink.c
>        Sample Specification: s16le 2ch 44100Hz
>        Channel Map: front-left,front-right
>        Owner Module: 7
>        Mute: no
>        Volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
>                balance 0.00
>        Base Volume: 65536 / 100% / 0.00 dB
>        Monitor Source: auto_null.monitor
>        Latency: 469444 usec, configured 2000000 usec
>        Flags: DECIBEL_VOLUME LATENCY
>        Properties:
>                device.description = "Dummy Output"
>                device.class = "abstract"
>                device.icon_name = "audio-card"
>        Formats:
>                pcm
>I: [pulseaudio] client.c: Freed 0 "pactl"
>I: [pulseaudio] protocol-native.c: Connection died.
>
>###
>2 ) Connect to headset
>###
>
># bluetoothctl
>[NEW] Controller 7C:EC:79:C9:43:69 BlueZ 5.40 [default]
>[bluetooth]# power on
>[bluetooth]# bluetoothd[756]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
>abluetoothd[756]: src/adapter.c:dev_class_changed_callback() Class: 0x0c0000
>D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
>[CHG] Controller 7C:EC:79:C9:43:69 Class: 0x0c0000
>[bluetooth]# abluetoothd[756]: src/adapter.c:property_set_mode_complete() Success (0x00)
>bluetoothd[756]: src/adapter.c:new_settings_callback() Settings: 0x00000ad1
>bluetoothd[756]: src/adapter.c:settings_changed() Changed settings: 0x00000001
>bluetoothd[756]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
>bluetoothd[756]: src/adapter.c:trigger_passive_scanning()
>Changing power on succeeded
>[bluetooth]# aD: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
>[CHG] Controller 7C:EC:79:C9:43:69 Powered: yes
>[bluetooth]# agent on
>[bluetooth]# bluetoothd[756]: src/agent.c:agent_ref() 0x139028: ref=1
>bluetoothd[756]: src/agent.c:register_agent() agent :1.5
>Agent registered
>[bluetooth]# default-agent
>[bluetooth]# bluetoothd[756]: src/agent.c:add_default_agent() Default agent set to :1.5 /org/bluez/agent
>Default agent request successful
>[bluetooth]# scan on
>[bluetooth]# bluetoothd[756]: src/adapter.c:start_discovery() sender :1.5
>bluetoothd[756]: src/adapter.c:update_discovery_filter()
>bluetoothd[756]: src/adapter.c:discovery_filter_to_mgmt_cp()
>bluetoothd[756]: src/adapter.c:trigger_start_discovery()
>bluetoothd[756]: src/adapter.c:cancel_passive_scanning()
>Discovery started
>[bluetooth]# bluetoothd[756]: src/adapter.c:start_discovery_timeout()
>bluetoothd[756]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0
>bluetoothd[756]: src/adapter.c:start_discovery_complete() status 0x00
>bluetoothd[756]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0
>D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
>[CHG] Controller 7C:EC:79:C9:43:69 Discovering: yes
>[bluetooth]# bluetoothd[756]: src/adapter.c:device_found_callback() hci0 addr 45:F8:A0:FF:83:3E, rssi -99 flags 0x0000 eir_len 23
>bluetoothd[756]: src/device.c:device_create() dst 45:F8:A0:FF:83:3E
>bluetoothd[756]: src/device.c:device_new() address 45:F8:A0:FF:83:3E
>bluetoothd[756]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_45_F8_A0_FF_83_3E
>bluetoothd[756]: src/device.c:device_set_legacy() legacy 0
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi -99
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_45_F8_A0_FF_83_3E found
>D: [pulseaudio] bluez5-util.c: Address: 45:F8:A0:FF:83:3E
>D: [pulseaudio] bluez5-util.c: Alias: 45-F8-A0-FF-83-3E
>D: [pulseaudio] bluez5-util.c: Adapter: /org/bluez/hci0
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
>[NEW] Device 45:F8:A0:FF:83:3E 45-F8-A0-FF-83-3E
>[bluetooth]# bluetoothd[756]: src/adapter.c:device_found_callback() hci0 addr DE:E8:D0:6F:1F:A7, rssi -81 flags 0x0000 eir_len 7
>bluetoothd[756]: src/device.c:device_create() dst DE:E8:D0:6F:1F:A7
>bluetoothd[756]: src/device.c:device_new() address DE:E8:D0:6F:1F:A7
>bluetoothd[756]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_DE_E8_D0_6F_1F_A7
>bluetoothd[756]: src/device.c:device_set_legacy() legacy 0
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi -81
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_DE_E8_D0_6F_1F_A7 found
>D: [pulseaudio] bluez5-util.c: Address: DE:E8:D0:6F:1F:A7
>D: [pulseaudio] bluez5-util.c: Alias: DE-E8-D0-6F-1F-A7
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000feed-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
>[NEW] Device DE:E8:D0:6F:1F:A7 DE-E8-D0-6F-1F-A7
>[bluetooth]# bluetoothd[756]: src/adapter.c:device_found_callback() hci0 addr 00:11:C2:08:15:F6, rssi -72 flags 0x0000 eir_len 33
>bluetoothd[756]: src/device.c:device_create() dst 00:11:C2:08:15:F6
>bluetoothd[756]: src/device.c:device_new() address 00:11:C2:08:15:F6
>bluetoothd[756]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: src/device.c:device_set_legacy() legacy 0
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi -72
>bluetoothd[756]: src/device.c:device_set_tx_power() tx_power 4
>bluetoothd[756]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_00_11_C2_08_15_F6 CB3 Fit
>bluetoothd[756]: src/device.c:device_set_class() /org/bluez/hci0/dev_00_11_C2_08_15_F6 0x240404
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_00_11_C2_08_15_F6 found
>D: [pulseaudio] bluez5-util.c: Address: 00:11:C2:08:15:F6
>D: [pulseaudio] bluez5-util.c: Alias: CB3 Fit
>D: [pulseaudio] bluez5-util.c: Class: 2360324
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000110d-0000-1000-8000-00805f9b34fb
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000110f-0000-1000-8000-00805f9b34fb
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000111e-0000-1000-8000-00805f9b34fb
>D: [pulseaudio] bluez5-util.c: UUIDs: 00001108-0000-1000-8000-00805f9b34fb
>D: [pulseaudio] bluez5-util.c: UUIDs: 00001131-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
>[NEW] Device 00:11:C2:08:15:F6 CB3 Fit
>[bluetooth]# bluetoothd[756]: src/adapter.c:device_found_callback() hci0 addr E4:B3:18:6C:0B:9B, rssi -59 flags 0x0000 eir_len 21
>bluetoothd[756]: src/device.c:device_create() dst E4:B3:18:6C:0B:9B
>bluetoothd[756]: src/device.c:device_new() address E4:B3:18:6C:0B:9B
>bluetoothd[756]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_E4_B3_18_6C_0B_9B
>bluetoothd[756]: src/device.c:device_set_legacy() legacy 0
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi -59
>bluetoothd[756]: src/device.c:device_set_tx_power() tx_power 7
>bluetoothd[756]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_E4_B3_18_6C_0B_9B PC-EVT-5CFB
>bluetoothd[756]: src/device.c:device_set_class() /org/bluez/hci0/dev_E4_B3_18_6C_0B_9B 0x02010C
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_E4_B3_18_6C_0B_9B found
>D: [pulseaudio] bluez5-util.c: Address: E4:B3:18:6C:0B:9B
>D: [pulseaudio] bluez5-util.c: Alias: PC-EVT-5CFB
>D: [pulseaudio] bluez5-util.c: Class: 131340
>D: [pulseaudio] bluez5-util.c: Adapter: /org/bluez/hci0
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
>[NEW] Device E4:B3:18:6C:0B:9B PC-EVT-5CFB
>[bluetooth]# bluetoothd[756]: src/adapter.c:device_found_callback() hci0 addr BC:14:EF:62:01:6D, rssi -92 flags 0x0000 eir_len 25
>bluetoothd[756]: src/device.c:device_create() dst BC:14:EF:62:01:6D
>bluetoothd[756]: src/device.c:device_new() address BC:14:EF:62:01:6D
>bluetoothd[756]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_BC_14_EF_62_01_6D
>bluetoothd[756]: src/device.c:device_set_legacy() legacy 0
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi -92
>bluetoothd[756]: src/device.c:device_set_tx_power() tx_power 0
>bluetoothd[756]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_BC_14_EF_62_01_6D PC-EVT-4566
>bluetoothd[756]: src/device.c:device_set_class() /org/bluez/hci0/dev_BC_14_EF_62_01_6D 0x3E0104
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Device /org/bluez/hci0/dev_BC_14_EF_62_01_6D found
>D: [pulseaudio] bluez5-util.c: Address: BC:14:EF:62:01:6D
>D: [pulseaudio] bluez5-util.c: Alias: PC-EVT-4566
>D: [pulseaudio] bluez5-util.c: Class: 4063492
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000110b-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
>[NEW] Device BC:14:EF:62:01:6D PC-EVT-4566
>[bluetooth]# bluetoothd[756]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0
>bluetoothd[756]: src/adapter.c:trigger_start_discovery()
>bluetoothd[756]: src/adapter.c:cancel_passive_scanning()
>devices
>Device 45:F8:A0:FF:83:3E 45-F8-A0-FF-83-3E
>Device DE:E8:D0:6F:1F:A7 DE-E8-D0-6F-1F-A7
>Device 00:11:C2:08:15:F6 CB3 Fit
>Device E4:B3:18:6C:0B:9B PC-EVT-5CFB
>Device BC:14:EF:62:01:6D PC-EVT-4566
>[bluetooth]# scan off
>[bluetooth]# bluetoothd[756]: src/adapter.c:stop_discovery() sender :1.5
>bluetoothd[756]: src/adapter.c:discovery_destroy() owner :1.5
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi 0
>bluetoothd[756]: src/device.c:device_set_tx_power() tx_power 127
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi 0
>bluetoothd[756]: src/device.c:device_set_tx_power() tx_power 127
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi 0
>bluetoothd[756]: src/device.c:device_set_tx_power() tx_power 127
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi 0
>bluetoothd[756]: src/device.c:device_set_rssi_with_delta() rssi 0
>bluetoothd[756]: src/adapter.c:trigger_passive_scanning()
>D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_BC_14_EF_62_01_6D
>[CHG] Device BC:14:EF:62:01:6D RSSI is nil
>[bluetooth]# D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_E4_B3_18_6C_0B_9B
>[CHG] Device E4:B3:18:6C:0B:9B RSSI is nil
>[bluetooth]# D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_00_11_C2_08_15_F6
>[CHG] Device 00:11:C2:08:15:F6 RSSI is nil
>[bluetooth]# D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_DE_E8_D0_6F_1F_A7
>[CHG] Device DE:E8:D0:6F:1F:A7 RSSI is nil
>[bluetooth]# D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_45_F8_A0_FF_83_3E
>[CHG] Device 45:F8:A0:FF:83:3E RSSI is nil
>[bluetooth]# D: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
>[CHG] Controller 7C:EC:79:C9:43:69 Discovering: no
>Discovery stopped
>[bluetooth]# pair 00:11:C2:08:15:F6
>Attempting to pair with 00:11:C2:08:15:F6
>bluetoothd[756]: src/device.c:btd_device_set_temporary() temporary 0
>bluetoothd[756]: src/agent.c:agent_ref() 0x139028: ref=2
>bluetoothd[756]: src/device.c:bonding_request_new() Requesting bonding for 00:11:C2:08:15:F6
>bluetoothd[756]: src/agent.c:agent_ref() 0x139028: ref=3
>bluetoothd[756]: src/agent.c:agent_unref() 0x139028: ref=2
>bluetoothd[756]: src/adapter.c:suspend_discovery()
>bluetoothd[756]: src/adapter.c:adapter_bonding_attempt() hci0 bdaddr 00:11:C2:08:15:F6 type 0 io_cap 0x04
>bluetoothd[756]: src/adapter.c:add_whitelist_complete() 00:11:C2:08:15:F6 added to kernel whitelist
>[bluetooth]# bluetoothd[756]: src/adapter.c:connected_callback() hci0 device 00:11:C2:08:15:F6 connected eir_len 14
>D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_00_11_C2_08_15_F6
>[CHG] Device 00:11:C2:08:15:F6 Connected: yes
>[CB3 Fit]# bluetoothd[756]: src/adapter.c:new_link_key_callback() hci0 new key for 00:11:C2:08:15:F6 type 4 pin_len 0 store_hint 1
>bluetoothd[756]: src/device.c:device_set_bonded()
>bluetoothd[756]: src/device.c:device_bonding_complete() bonding 0x13fd00 status 0x00
>bluetoothd[756]: src/device.c:device_bonding_complete() Proceeding with service discovery
>bluetoothd[756]: src/agent.c:agent_unref() 0x139028: ref=1
>bluetoothd[756]: src/adapter.c:resume_discovery()
>bluetoothd[756]: src/adapter.c:pair_device_complete() Success (0x00)
>bluetoothd[756]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 00:11:C2:08:15:F6 type 0 status 0x0
>bluetoothd[756]: src/device.c:device_bonding_complete() bonding (nil) status 0x00
>bluetoothd[756]: src/adapter.c:resume_discovery()
>cobluetoothd[756]: src/device.c:device_probe_profiles() Probing profiles for device 00:11:C2:08:15:F6
>bluetoothd[756]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: src/service.c:btd_service_ref() 0x13fd00: ref=2
>bluetoothd[756]: src/service.c:change_state() 0x13fd00: device 00:11:C2:08:15:F6 profile avrcp-controller state changed: unavailable -> disconnected (0)
>bluetoothd[756]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: src/service.c:btd_service_ref() 0x154078: ref=2
>bluetoothd[756]: src/service.c:change_state() 0x154078: device 00:11:C2:08:15:F6 profile audio-avrcp-target state changed: unavailable -> disconnected (0)
>bluetoothd[756]: profiles/audio/a2dp.c:a2dp_sink_probe() path /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: profiles/audio/sink.c:sink_init() /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: src/service.c:btd_service_ref() 0x138fa0: ref=2
>bluetoothd[756]: src/service.c:change_state() 0x138fa0: device 00:11:C2:08:15:F6 profile a2dp-sink state changed: unavailable -> disconnected (0)
>bluetoothd[756]: src/profile.c:ext_device_probe() Headset Voice gateway probed with UUID 00001108-0000-1000-8000-00805f9b34fb
>bluetoothd[756]: src/service.c:change_state() 0x139008: device 00:11:C2:08:15:F6 profile Headset Voice gateway state changed: unavailable -> disconnected (0)
>bluetoothd[756]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_00_11_C2_08_15_F6 err 0
>D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaControl1 found, skipping
>D: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_00_11_C2_08_15_F6
>D: [pulseaudio] bluez5-util.c: UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
>[CHG] Device 00:11:C2:08:15:F6 UUIDs: 00001108-0000-1000-8000-00805f9b34fb
>[CHG] Device 00:11:C2:08:15:F6 UUIDs: 0000110b-0000-1000-8000-00805f9b34fb
>[CHG] Device 00:11:C2:08:15:F6 UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
>[CHG] Device 00:11:C2:08:15:F6 UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
>[CHG] Device 00:11:C2:08:15:F6 UUIDs: 0000111e-0000-1000-8000-00805f9b34fb
>[CHG] Device 00:11:C2:08:15:F6 Paired: yes
>Pairing successful
>[CB3 Fit]# connect 00:11:C2:08:15:F6
>Attempting to connect to 00:11:C2:08:15:F6
>bluetoothd[756]: src/device.c:connect_profiles() /org/bluez/hci0/dev_00_11_C2_08_15_F6 (all), client :1.5
>bluetoothd[756]: src/service.c:btd_service_ref() 0x139008: ref=2
>bluetoothd[756]: src/service.c:change_state() 0x139008: device 00:11:C2:08:15:F6 profile Headset Voice gateway state changed: disconnected -> connecting (0)
>[CB3 Fit]# bluetoothd[756]: src/profile.c:ext_connect() Headset Voice gateway connected to 00:11:C2:08:15:F6
>D: [pulseaudio] backend-native.c: dbus: path=/Profile/HSPAGProfile, interface=org.bluez.Profile1, member=NewConnection
>D: [pulseaudio] backend-native.c: dbus: NewConnection path=/org/bluez/hci0/dev_00_11_C2_08_15_F6, fd=18
>D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18 state changed from disconnected to idle
>D: [pulseaudio] module-bluez5-discover.c: Loading module-bluez5-device path=/org/bluez/hci0/dev_00_11_C2_08_15_F6
>I: [pulseaudio] card.c: Created 0 "bluez_card.00_11_C2_08_15_F6"
>D: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18
>I: [pulseaudio] backend-native.c: doing connect
>I: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18 acquired: fd 19
>D: [pulseaudio] module-device-restore.c: Database contains no data for key: sink:bluez_sink.00_11_C2_08_15_F6
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: sink:bluez_sink.00_11_C2_08_15_F6:null
>I: [pulseaudio] sink.c: Created sink 1 "bluez_sink.00_11_C2_08_15_F6" with sample spec s16le 1ch 8000Hz and channel map mono
>I: [pulseaudio] sink.c:     bluetooth.protocol = "headset_head_unit"
>I: [pulseaudio] sink.c:     device.intended_roles = "phone"
>I: [pulseaudio] sink.c:     device.description = "CB3 Fit"
>I: [pulseaudio] sink.c:     device.string = "00:11:C2:08:15:F6"
>I: [pulseaudio] sink.c:     device.api = "bluez"
>I: [pulseaudio] sink.c:     device.class = "sound"
>I: [pulseaudio] sink.c:     device.bus = "bluetooth"
>I: [pulseaudio] sink.c:     device.form_factor = "headset"
>I: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>I: [pulseaudio] sink.c:     bluez.class = "0x240404"
>I: [pulseaudio] sink.c:     bluez.alias = "CB3 Fit"
>I: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>D: [pulseaudio] module-device-restore.c: Database contains no data for key: source:bluez_sink.00_11_C2_08_15_F6.monitor
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: source:bluez_sink.00_11_C2_08_15_F6.monitor:null
>I: [pulseaudio] source.c: Created source 1 "bluez_sink.00_11_C2_08_15_F6.monitor" with sample spec s16le 1ch 8000Hz and channel map mono
>I: [pulseaudio] source.c:     device.description = "Monitor of CB3 Fit"
>I: [pulseaudio] source.c:     device.class = "monitor"
>I: [pulseaudio] source.c:     device.string = "00:11:C2:08:15:F6"
>I: [pulseaudio] source.c:     device.api = "bluez"
>I: [pulseaudio] source.c:     device.bus = "bluetooth"
>I: [pulseaudio] source.c:     device.form_factor = "headset"
>I: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>I: [pulseaudio] source.c:     bluez.class = "0x240404"
>I: [pulseaudio] source.c:     bluez.alias = "CB3 Fit"
>I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
>I: [pulseaudio] source.c:     device.intended_roles = "phone"
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>D: [pulseaudio] module-device-restore.c: Database contains no data for key: source:bluez_source.00_11_C2_08_15_F6
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: source:bluez_source.00_11_C2_08_15_F6:null
>I: [pulseaudio] source.c: Created source 2 "bluez_source.00_11_C2_08_15_F6" with sample spec s16le 1ch 8000Hz and channel map mono
>I: [pulseaudio] source.c:     bluetooth.protocol = "headset_head_unit"
>I: [pulseaudio] source.c:     device.intended_roles = "phone"
>I: [pulseaudio] source.c:     device.description = "CB3 Fit"
>I: [pulseaudio] source.c:     device.string = "00:11:C2:08:15:F6"
>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 = "headset"
>I: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>I: [pulseaudio] source.c:     bluez.class = "0x240404"
>I: [pulseaudio] source.c:     bluez.alias = "CB3 Fit"
>I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
>D: [bluetooth] module-bluez5-device.c: IO Thread starting up
>D: [bluetooth] core-util.c: SCHED_RR|SCHED_RESET_ON_FORK worked.
>I: [bluetooth] core-util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5.
>I: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18 resuming
>D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: sink:bluez_sink.00_11_C2_08_15_F6:null
>D: [pulseaudio] module-bluetooth-policy.c: Profile headset_head_unit cannot be selected for loopback
>I: [pulseaudio] module-always-sink.c: A new sink has been discovered. Unloading null-sink.
>D: [pulseaudio] backend-native.c: RFCOMM >> +VGS=15
>D: [pulseaudio] module-bluetooth-policy.c: Profile headset_head_unit cannot be selected for loopback
>D: [pulseaudio] backend-native.c: RFCOMM >> +VGM=15
>I: [pulseaudio] module.c: Loaded "module-bluez5-device" (index: #13; argument: "path=/org/bluez/hci0/dev_00_11_C2_08_15_F6").
>D: [pulseaudio] backend-native.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18 available for profile headset_head_unit
>D: [pulseaudio] module-device-restore.c: Database contains no data for key: sink:bluez_sink.00_11_C2_08_15_F6
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: sink:bluez_sink.00_11_C2_08_15_F6:null
>I: [pulseaudio] module-device-restore.c: Storing port for device sink:bluez_sink.00_11_C2_08_15_F6.
>I: [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:bluez_sink.00_11_C2_08_15_F6:headset-output.
>D: [pulseaudio] module-device-restore.c: Database contains no data for key: source:bluez_sink.00_11_C2_08_15_F6.monitor
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: source:bluez_sink.00_11_C2_08_15_F6.monitor:null
>I: [pulseaudio] module-device-restore.c: Storing port for device source:bluez_sink.00_11_C2_08_15_F6.monitor.
>bluetoothd[756]: src/service.c:change_state() 0x139008: device 00:11:C2:08:15:F6 profile Headset Voice gateway state changed: connecting -> connected (0)
>I: [pulseaudio] module-device-restore.c: Storing volume/mute for device+port source:bluez_sink.00_11_C2_08_15_F6.monitor:null.
>D: [pulseaudio] module-device-restore.c: Database contains no data for key: source:bluez_source.00_11_C2_08_15_F6
>bluetoothd[756]: src/device.c:device_profile_connected() Headset Voice gateway Success (0)
>D: [pulseaudio] module-device-restore.c: Database contains no (or invalid) data for key: source:bluez_source.00_11_C2_08_15_F6:null
>I: [pulseaudio] module-device-restore.c: Storing port for device source:bluez_source.00_11_C2_08_15_F6.
>bluetoothd[756]: profiles/audio/a2dp.c:a2dp_sink_connect() path /org/bluez/hci0/dev_00_11_C2_08_15_F6I: [pulseaudio] module-device-restore.c: Storing volume/mute for device+port source:bluez_source.00_11_C2_08_15_F6:headset-input.
>
>I: [pulseaudio] module.c: Unloading "module-null-sink" (index: #7).
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_ref() 0x140ee0: ref=1
>D: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_ref() 0x140ee0: ref=2
>D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
>bluetoothd[756]: profiles/audio/a2dp.c:setup_ref() 0x154a50: ref=1
>D: [null-sink] module-null-sink.c: Thread shutting down
>bluetoothd[756]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_11_C2_08_15_F6: SINK_STATE_DISCONNECTED -> SINK_STATE_CONNECTING
>I: [pulseaudio] sink.c: Freeing sink 0 "auto_null"
>I: [pulseaudio] source.c: Freeing source 0 "auto_null.monitor"
>bluetoothd[756]: profiles/audio/sink.c:sink_connect() stream creation in progress
>I: [pulseaudio] module.c: Unloaded "module-null-sink" (index: #7).
>bluetoothd[756]: src/service.c:change_state() 0x138fa0: device 00:11:C2:08:15:F6 profile a2dp-sink state changed: disconnected -> connecting (0)
>D: [pulseaudio] backend-native.c: RFCOMM << AT+VGS=12
>bluetoothd[756]: src/service.c:btd_service_ref() 0x139008: ref=3
>D: [pulseaudio] sink.c: The reference volume of sink bluez_sink.00_11_C2_08_15_F6 changed from mono: 65536 / 100% to mono: 52429 /  80%.
>D: [pulseaudio] backend-native.c: RFCOMM >> OK
>bluetoothd[756]: plugins/policy.c:service_cb() Added Headset Voice gateway reconnect 0
>I: [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:bluez_sink.00_11_C2_08_15_F6:headset-output.
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to 00:11:C2:08:15:F6
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=895
>D: [pulseaudio] backend-native.c: RFCOMM << AT+XAPL=0000-0000-0100,7
>D: [pulseaudio] backend-native.c: RFCOMM >> OK
>bluetoothd[756]: profiles/audio/avdtp.c:session_cb()
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 1 media 0 in use 0
>D: [pulseaudio] backend-native.c: RFCOMM << AT+IPHONEACCEV=2,1,8,2,0
>D: [pulseaudio] backend-native.c: RFCOMM >> OK
>bluetoothd[756]: profiles/audio/avdtp.c:session_cb()
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 1 type 1 media 0
>bluetoothd[756]: profiles/audio/a2dp.c:discover_cb() err (nil)
>bluetoothd[756]: profiles/audio/sink.c:discovery_complete() Discovery complete
>bluetoothd[756]: profiles/audio/a2dp.c:setup_ref() 0x154a50: ref=2
>bluetoothd[756]: profiles/audio/media.c:media_endpoint_async_call() Calling SelectConfiguration: name = :1.4 path = /MediaEndpoint/A2DPSource
>bluetoothd[756]: profiles/audio/a2dp.c:setup_unref() 0x154a50: ref=1
>D: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource, interface=org.bluez.MediaEndpoint1, member=SelectConfiguration
>bluetoothd[756]: profiles/audio/a2dp.c:a2dp_config() a2dp_config: selected SEP 0x1391d8
>bluetoothd[756]: profiles/audio/a2dp.c:setup_ref() 0x154a50: ref=2
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_set_configuration() 0x140ee0: int_seid=1, acp_seid=1
>bluetoothd[756]: profiles/audio/a2dp.c:setup_unref() 0x154a50: ref=1
>bluetoothd[756]: profiles/audio/avdtp.c:session_cb()
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request succeeded
>bluetoothd[756]: profiles/audio/a2dp.c:setconf_cfm() Source 0x1391d8: Set_Configuration_Cfm
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaTransport1 found, skipping
>D: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
>D: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource, interface=org.bluez.MediaEndpoint1, member=SetConfiguration
>D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 state changed from disconnected to idle
>D: [pulseaudio] card.c: Setting card bluez_card.00_11_C2_08_15_F6 profile a2dp_sink to availability status unknown
>D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 available for profile a2dp_sink
>bluetoothd[756]: profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name = :1.4 path = /MediaEndpoint/A2DPSource
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED
>bluetoothd[756]: profiles/audio/avdtp.c:session_cb()
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_parse_resp() OPEN request succeeded
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected transport channel to 00:11:C2:08:15:F6
>bluetoothd[756]: profiles/audio/avdtp.c:handle_transport_connect() Flushable packets enabled
>bluetoothd[756]: profiles/audio/avdtp.c:handle_transport_connect() sk 23, omtu 895, send buffer size 81920
>bluetoothd[756]: profiles/audio/a2dp.c:open_cfm() Source 0x1391d8: Open_Cfm
>bluetoothd[756]: profiles/audio/a2dp.c:setup_unref() 0x154a50: ref=0
>bluetoothd[756]: profiles/audio/a2dp.c:setup_free() 0x154a50
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_unref() 0x140ee0: ref=1
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN
>bluetoothd[756]: src/service.c:change_state() 0x138fa0: device 00:11:C2:08:15:F6 profile a2dp-sink state changed: connecting -> connected (0)
>bluetoothd[756]: src/device.c:device_profile_connected() a2dp-sink Success (0)
>bluetoothd[756]: src/device.c:device_profile_connected() returning response to :1.5
>bluetoothd[756]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_00_11_C2_08_15_F6 profile avrcp-controller
>bluetoothd[756]: profiles/audio/avrcp.c:avrcp_connect() path /org/bluez/hci0/dev_00_11_C2_08_15_F6
>bluetoothd[756]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
>Connection successful
>bluetoothd[756]: src/service.c:change_state() 0x13fd00: device 00:11:C2:08:15:F6 profile avrcp-controller state changed: disconnected -> connecting (0)[CB3 Fit]#
>bluetoothd[756]: src/service.c:btd_service_ref() 0x138fa0: ref=3
>bluetoothd[756]: plugins/policy.c:service_cb() Added a2dp-sink reconnect 0
>bluetoothd[756]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_11_C2_08_15_F6: SINK_STATE_CONNECTING -> SINK_STATE_CONNECTED
>bluetoothd[756]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 State=TRANSPORT_STATE_IDLE Playing=0
>bluetoothd[756]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 00:11:C2:08:15:F6
>bluetoothd[756]: Can't open input device: No such file or directory (2)
>bluetoothd[756]: AVRCP: failed to init uinput for 00:11:C2:08:15:F6
>bluetoothd[756]: profiles/audio/avrcp.c:controller_init() 0x154848 version 0x0104
>bluetoothd[756]: profiles/audio/avrcp.c:target_init() 0x154be0 version 0x0104
>bluetoothd[756]: src/service.c:change_state() 0x13fd00: device 00:11:C2:08:15:F6 profile avrcp-controller state changed: connecting -> connected (0)
>bluetoothd[756]: src/device.c:device_profile_connected() avrcp-controller Success (0)
>bluetoothd[756]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected
>[CB3 Fit]# exit
>Agent unregistered
>[DEL] Controller 7C:EC:79:C9:43:69 BlueZ 5.40 [default]
># bluetoothd[756]: src/agent.c:agent_disconnect() Agent :1.5 disconnected
>bluetoothd[756]: src/agent.c:remove_default_agent() Default agent cleared
>bluetoothd[756]: src/agent.c:agent_destroy() agent :1.5
>bluetoothd[756]: src/agent.c:agent_unref() 0x139028: ref=0
>
>###
>3 ) Attempt to play audio via headset_head_unit profile (FAILS) 
>###
>
>#
># pactl list cards
>I: [pulseaudio] client.c: Created 1 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Card #0
>        Name: bluez_card.00_11_C2_08_15_F6
>        Driver: module-bluez5-device.c
>        Owner Module: 13
>        Properties:
>                device.description = "CB3 Fit"
>                device.string = "00:11:C2:08:15:F6"
>                device.api = "bluez"
>                device.class = "sound"
>                device.bus = "bluetooth"
>                device.form_factor = "headset"
>                bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>                bluez.class = "0x240404"
>                bluez.alias = "CB3 Fit"
>                device.icon_name = "audio-headset-bluetooth"
>                device.intended_roles = "phone"
>        Profiles:
>                a2dp_sink: High Fidelity Playback (A2DP Sink) (sinks: 1, sources: 0, priority: 10, available: yes)
>                headset_head_unit: Headset Head Unit (HSP/HFP) (sinks: 1, sources: 1, priority: 20, available: yes)
>                off: Off (sinks: 0, sources: 0, priority: 0, available: yes)
>        Active Profile: headset_head_unit
>        Ports:
>                headset-output: Headset (priority: 0, latency offset: 0 usec)
>                        Part of profile(s): a2dp_sink, headset_head_unit
>                headset-input: Headset (priority: 0, latency offset: 0 usec)
>                        Part of profile(s): headset_head_unit
>I: [pulseaudio] client.c: Freed 1 "pactl"
>I: [pulseaudio] protocol-native.c: Connection died.
># pactl list sinks
>I: [pulseaudio] client.c: Created 2 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Sink #1
>        State: IDLE
>        Name: bluez_sink.00_11_C2_08_15_F6
>        Description: CB3 Fit
>        Driver: module-bluez5-device.c
>        Sample Specification: s16le 1ch 8000Hz
>        Channel Map: mono
>        Owner Module: 13
>        Mute: no
>        Volume: mono: 52429 /  80%
>                balance 0.00
>        Base Volume: 65536 / 100%
>        Monitor Source: bluez_sink.00_11_C2_08_15_F6.monitor
>        Latency: 31000 usec, configured 128000 usec
>        Flags: HARDWARE HW_VOLUME_CTRL LATENCY
>        Properties:
>                bluetooth.protocol = "headset_head_unit"
>                device.intended_roles = "phone"
>                device.description = "CB3 Fit"
>                device.string = "00:11:C2:08:15:F6"
>                device.api = "bluez"
>                device.class = "sound"
>                device.bus = "bluetooth"
>                device.form_factor = "headset"
>                bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>                bluez.class = "0x240404"
>                bluez.alias = "CB3 Fit"
>                device.icon_name = "audio-headset-bluetooth"
>        Ports:
>                headset-output: Headset (priority: 0)
>        Active Port: headset-output
>        Formats:
>                pcm
>I: [pulseaudio] client.c: Freed 2 "pactl"
>I: [pulseaudio] protocol-native.c: Connection died.
># aplay -Dpulse /opt/foo/data/sounds/factory_clean.wav
>I: [pulseaudio] client.c: Created 3 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Playing WAVE '/opt/foo/data/sounds/factory_clean.wav' : Signed 16 bit Little Endian, Rate 22050 Hz, Stereo
>D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\""  format.rate = "22050"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
>I: [pulseaudio] sink-input.c: Trying to change sample rate
>D: [pulseaudio] resampler.c: Resampler:
>D: [pulseaudio] resampler.c:   rate 22050 -> 8000 (method ffmpeg)
>D: [pulseaudio] resampler.c:   format s16le -> s16le (intermediate s16le)
>D: [pulseaudio] resampler.c:   channels 2 -> 1 (resampling 1)
>D: [pulseaudio] resampler.c: Channel matrix:
>D: [pulseaudio] resampler.c:        I00   I01
>D: [pulseaudio] resampler.c:     +------------
>D: [pulseaudio] resampler.c: O00 | 0.500 0.500
>I: [pulseaudio] remap_neon.c: Using ARM NEON stereo to mono remapping
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=2, prebuf=0, minreq=1 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=2, prebuf=0, minreq=2 maxrewind=0
>I: [pulseaudio] sink-input.c: Created input 0 "ALSA Playback" on bluez_sink.00_11_C2_08_15_F6 with sample spec s16le 2ch 22050Hz and channel map front-left,front-right
>I: [pulseaudio] sink-input.c:     media.name = "ALSA Playback"
>I: [pulseaudio] sink-input.c:     application.name = "ALSA plug-in [aplay]"
>I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
>I: [pulseaudio] sink-input.c:     native-protocol.version = "30"
>I: [pulseaudio] sink-input.c:     application.process.id = "782"
>I: [pulseaudio] sink-input.c:     application.process.user = "root"
>I: [pulseaudio] sink-input.c:     application.process.host = "(none)"
>I: [pulseaudio] sink-input.c:     application.process.binary = "aplay"
>I: [pulseaudio] sink-input.c:     application.language = "C"
>I: [pulseaudio] sink-input.c:     application.process.machine_id = "(none)"
>I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [aplay]"
>I: [pulseaudio] protocol-native.c: Requested tlength=500.00 ms, minreq=124.99 ms
>D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
>D: [pulseaudio] protocol-native.c: Could not configure a sufficiently low latency. Early requests might not be satisifed.
>D: [pulseaudio] protocol-native.c: Requested latency=124.99 ms, Received latency=128.00 ms
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=44100, base=4, prebuf=11024, minreq=11024 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=44100, base=4, prebuf=11024, minreq=11024 maxrewind=0
>I: [pulseaudio] protocol-native.c: Final latency 628.00 ms = 250.02 ms + 2*124.99 ms + 128.00 ms
>I: [pulseaudio] module-stream-restore.c: Storing volume/mute/device for stream sink-input-by-application-name:ALSA plug-in [aplay].
>D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Ext.StreamRestore1.RestoreEntry added for object /org/pulseaudio/stream_restore1/entry0
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>
>
>###
>aplay hangs, Ctrl + C to signal
>###
>
>^CAborted by signal Interrupt...
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>^C
>D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
># I: [pulseaudio] sink-input.c: Freeing input 0 "ALSA Playback"
>I: [pulseaudio] client.c: Freed 3 "ALSA plug-in [aplay]"
>I: [pulseaudio] protocol-native.c: Connection died.
>
>###
>4 ) Switch to A2DP and play
>###
># pactl set-card-profile bluez_card.00_11_C2_08_15_F6 a2dp_sink
>I: [pulseaudio] client.c: Created 4 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>D: [pulseaudio] module-always-sink.c: Autoloading null-sink as no other sinks detected.
>I: [pulseaudio] sink.c: Created sink 2 "auto_null" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] sink.c:     device.description = "Dummy Output"
>I: [pulseaudio] sink.c:     device.class = "abstract"
>I: [pulseaudio] sink.c:     device.icon_name = "audio-card"
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>I: [pulseaudio] source.c: Created source 3 "auto_null.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] source.c:     device.description = "Monitor of Dummy Output"
>I: [pulseaudio] source.c:     device.class = "monitor"
>I: [pulseaudio] source.c:     device.icon_name = "audio-input-microphone"
>D: [null-sink] module-null-sink.c: Thread starting up
>D: [pulseaudio] module-device-restore.c: Could not set format on sink auto_null
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>D: [pulseaudio] module-switch-on-connect.c: No sink inputs to move away.
>I: [pulseaudio] module.c: Loaded "module-null-sink" (index: #14; argument: "sink_name=auto_null sink_properties='device.description="Dummy Output"'").
>D: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
>D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
>D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
>D: [bluetooth] module-bluez5-device.c: IO thread shutdown requested, stopping cleanly
>D: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18
>I: [bluetooth] backend-native.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd18 released
>D: [bluetooth] module-bluez5-device.c: Audio stream torn down
>D: [bluetooth] module-bluez5-device.c: IO thread shutting down
>I: [pulseaudio] sink.c: Freeing sink 1 "bluez_sink.00_11_C2_08_15_F6"
>I: [pulseaudio] source.c: Freeing source 1 "bluez_sink.00_11_C2_08_15_F6.monitor"
>I: [pulseaudio] source.c: Freeing source 2 "bluez_source.00_11_C2_08_15_F6"
>D: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0
>bluetoothd[756]: profiles/audio/transport.c:media_owner_create() Owner created: sender=:1.4
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_ref() 0x140ee0: ref=2
>bluetoothd[756]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0x1391d8 locked
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_ref() 0x140ee0: ref=3
>bluetoothd[756]: profiles/audio/a2dp.c:setup_ref() 0x13f890: ref=1
>bluetoothd[756]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0: TRANSPORT_STATE_IDLE -> TRANSPORT_STATE_REQUESTING
>bluetoothd[756]: profiles/audio/transport.c:media_request_create() Request created: method=Acquire id=4
>bluetoothd[756]: profiles/audio/transport.c:media_owner_add() Owner :1.4 Request Acquire
>bluetoothd[756]: profiles/audio/transport.c:media_transport_set_owner() Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 Owner :1.4
>bluetoothd[756]: profiles/audio/avdtp.c:session_cb()
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_parse_resp() START request succeeded
>bluetoothd[756]: profiles/audio/a2dp.c:start_cfm() Source 0x1391d8: Start_Cfm
>bluetoothd[756]: /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0: fd(23) ready
>bluetoothd[756]: profiles/audio/transport.c:media_owner_remove() Owner :1.4 Request Acquire
>bluetoothd[756]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0: TRANSPORT_STATE_REQUESTING -> TRANSPORT_STATE_ACTIVE
>bluetoothd[756]: profiles/audio/a2dp.c:setup_unref() 0x13f890: ref=0
>bluetoothd[756]: profiles/audio/a2dp.c:setup_free() 0x13f890
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_unref() 0x140ee0: ref=2
>bluetoothd[756]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: OPEN -> STREAMING
>bluetoothd[756]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_11_C2_08_15_F6: SINK_STATE_CONNECTED -> SINK_STATE_PLAYING
>bluetoothd[756]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 State=TRANSPORT_STATE_ACTIVE Playing=1
>I: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 acquired: fd 20
>I: [pulseaudio] module-bluez5-device.c: SBC parameters: allocation=0, subbands=1, blocks=3, bitpool=53
>I: [pulseaudio] module-device-restore.c: Restoring volume for sink bluez_sink.00_11_C2_08_15_F6: front-left: 52429 /  80%,   front-right: 52429 /  80%
>I: [pulseaudio] sink.c: Created sink 3 "bluez_sink.00_11_C2_08_15_F6" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] sink.c:     bluetooth.protocol = "a2dp_sink"
>I: [pulseaudio] sink.c:     device.description = "CB3 Fit"
>I: [pulseaudio] sink.c:     device.string = "00:11:C2:08:15:F6"
>I: [pulseaudio] sink.c:     device.api = "bluez"
>I: [pulseaudio] sink.c:     device.class = "sound"
>I: [pulseaudio] sink.c:     device.bus = "bluetooth"
>I: [pulseaudio] sink.c:     device.form_factor = "headset"
>I: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>I: [pulseaudio] sink.c:     bluez.class = "0x240404"
>I: [pulseaudio] sink.c:     bluez.alias = "CB3 Fit"
>I: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
>I: [pulseaudio] sink.c:     device.intended_roles = "phone"
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>I: [pulseaudio] source.c: Created source 4 "bluez_sink.00_11_C2_08_15_F6.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] source.c:     device.description = "Monitor of CB3 Fit"
>I: [pulseaudio] source.c:     device.class = "monitor"
>I: [pulseaudio] source.c:     device.string = "00:11:C2:08:15:F6"
>I: [pulseaudio] source.c:     device.api = "bluez"
>I: [pulseaudio] source.c:     device.bus = "bluetooth"
>I: [pulseaudio] source.c:     device.form_factor = "headset"
>I: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>I: [pulseaudio] source.c:     bluez.class = "0x240404"
>I: [pulseaudio] source.c:     bluez.alias = "CB3 Fit"
>I: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
>I: [pulseaudio] source.c:     device.intended_roles = "phone"
>D: [bluetooth] module-bluez5-device.c: IO Thread starting up
>D: [bluetooth] core-util.c: SCHED_RR|SCHED_RESET_ON_FORK worked.
>I: [bluetooth] core-util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5.
>I: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 resuming
>D: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
>D: [bluetooth] module-bluez5-device.c: Using SBC encoder implementation: NEON
>D: [pulseaudio] module-device-restore.c: Could not set format on sink bluez_sink.00_11_C2_08_15_F6
>D: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be selected for loopback
>I: [pulseaudio] module-always-sink.c: A new sink has been discovered. Unloading null-sink.
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>D: [pulseaudio] module-switch-on-connect.c: No sink inputs to move away.
>I: [pulseaudio] card.c: Changed profile of card 0 "bluez_card.00_11_C2_08_15_F6" to a2dp_sink
>I: [pulseaudio] module.c: Unloading "module-null-sink" (index: #14).
>D: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
>D: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to remove event.
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to remove event.
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to remove event.
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to remove event.
>D: [null-sink] module-null-sink.c: Thread shutting down
>I: [pulseaudio] sink.c: Freeing sink 2 "auto_null"
>I: [pulseaudio] source.c: Freeing source 3 "auto_null.monitor"
>I: [pulseaudio] module.c: Unloaded "module-null-sink" (index: #14).
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to remove event.
>I: [pulseaudio] client.c: Freed 4 "pactl"
># I: [pulseaudio] protocol-native.c: Connection died.
>D: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0
>D: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_11_C2_08_15_F6/fd0 state changed from idle to playing
>D: [pulseaudio] card.c: Setting card bluez_card.00_11_C2_08_15_F6 profile a2dp_sink to availability status yes
>D: [pulseaudio] device-port.c: Setting port headset-output to status yes
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>D: [pulseaudio] module-bluez5-device.c: Resuming sink bluez_sink.00_11_C2_08_15_F6 because its transport state changed to playing
>
># aplay -Dpulse /opt/cicada/data/sounds/factory_clean.wav
>I: [pulseaudio] client.c: Created 5 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Playing WAVE '/opt/cicada/data/sounds/factory_clean.wav' : Signed 16 bit Little Endian, Rate 22050 Hz, Stereo
>D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\""  format.rate = "22050"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
>I: [pulseaudio] sink-input.c: Trying to change sample rate
>D: [pulseaudio] resampler.c: Resampler:
>D: [pulseaudio] resampler.c:   rate 22050 -> 44100 (method ffmpeg)
>D: [pulseaudio] resampler.c:   format s16le -> s16le (intermediate s16le)
>D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
>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 Playback" on bluez_sink.00_11_C2_08_15_F6 with sample spec s16le 2ch 22050Hz and channel map front-left,front-right
>I: [pulseaudio] sink-input.c:     media.name = "ALSA Playback"
>I: [pulseaudio] sink-input.c:     application.name = "ALSA plug-in [aplay]"
>I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
>I: [pulseaudio] sink-input.c:     native-protocol.version = "30"
>I: [pulseaudio] sink-input.c:     application.process.id = "791"
>I: [pulseaudio] sink-input.c:     application.process.user = "root"
>I: [pulseaudio] sink-input.c:     application.process.host = "(none)"
>I: [pulseaudio] sink-input.c:     application.process.binary = "aplay"
>I: [pulseaudio] sink-input.c:     application.language = "C"
>I: [pulseaudio] sink-input.c:     application.process.machine_id = "(none)"
>I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [aplay]"
>I: [pulseaudio] protocol-native.c: Requested tlength=500.00 ms, minreq=124.99 ms
>D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
>D: [pulseaudio] protocol-native.c: Could not configure a sufficiently low latency. Early requests might not be satisifed.
>D: [pulseaudio] protocol-native.c: Requested latency=124.99 ms, Received latency=45.32 ms
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=44100, base=4, prebuf=11024, minreq=11024 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=44100, base=4, prebuf=11024, minreq=11024 maxrewind=0
>I: [pulseaudio] protocol-native.c: Final latency 545.32 ms = 250.02 ms + 2*124.99 ms + 45.32 ms
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Implicit drain of 'ALSA Playback'
>D: [bluetooth] protocol-native.c: Drain acknowledged of 'ALSA Playback'
>D: [bluetooth] sink-input.c: Requesting rewind due to corking
>D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
>I: [pulseaudio] sink-input.c: Freeing input 1 "ALSA Playback"
>I: [pulseaudio] client.c: Freed 5 "ALSA plug-in [aplay]"
>I: [pulseaudio] protocol-native.c: Connection died.
>#
>
>###
>5 ) Examine pulseaudio after aplay hangs as headset_head_unit
>###
>
># aplay -Dpulse /opt/cicada/data/sounds/factory_clean.wav &
># I: [pulseaudio] client.c: Created 14 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Playing WAVE '/opt/cicada/data/sounds/factory_clean.wav' : Signed 16 bit Little Endian, Rate 22050 Hz, Stereo
>D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\""  format.rate = "22050"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
>I: [pulseaudio] sink-input.c: Trying to change sample rate
>D: [pulseaudio] resampler.c: Resampler:
>D: [pulseaudio] resampler.c:   rate 22050 -> 8000 (method ffmpeg)
>D: [pulseaudio] resampler.c:   format s16le -> s16le (intermediate s16le)
>D: [pulseaudio] resampler.c:   channels 2 -> 1 (resampling 1)
>D: [pulseaudio] resampler.c: Channel matrix:
>D: [pulseaudio] resampler.c:        I00   I01
>D: [pulseaudio] resampler.c:     +------------
>D: [pulseaudio] resampler.c: O00 | 0.500 0.500
>I: [pulseaudio] remap_neon.c: Using ARM NEON stereo to mono remapping
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=2, prebuf=0, minreq=1 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=2, prebuf=0, minreq=2 maxrewind=0
>I: [pulseaudio] sink-input.c: Created input 6 "ALSA Playback" on bluez_sink.00_11_C2_08_15_F6 with sample spec s16le 2ch 22050Hz and channel map front-left,front-right
>I: [pulseaudio] sink-input.c:     media.name = "ALSA Playback"
>I: [pulseaudio] sink-input.c:     application.name = "ALSA plug-in [aplay]"
>I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
>I: [pulseaudio] sink-input.c:     native-protocol.version = "30"
>I: [pulseaudio] sink-input.c:     application.process.id = "852"
>I: [pulseaudio] sink-input.c:     application.process.user = "root"
>I: [pulseaudio] sink-input.c:     application.process.host = "(none)"
>I: [pulseaudio] sink-input.c:     application.process.binary = "aplay"
>I: [pulseaudio] sink-input.c:     application.language = "C"
>I: [pulseaudio] sink-input.c:     application.process.machine_id = "(none)"
>I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [aplay]"
>I: [pulseaudio] protocol-native.c: Requested tlength=500.00 ms, minreq=124.99 ms
>D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
>D: [pulseaudio] protocol-native.c: Could not configure a sufficiently low latency. Early requests might not be satisifed.
>D: [pulseaudio] protocol-native.c: Requested latency=124.99 ms, Received latency=128.00 ms
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=44100, base=4, prebuf=11024, minreq=11024 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=44100, base=4, prebuf=11024, minreq=11024 maxrewind=0
>I: [pulseaudio] protocol-native.c: Final latency 628.00 ms = 250.02 ms + 2*124.99 ms + 128.00 ms
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>D: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
>
># pactl list
>I: [pulseaudio] client.c: Created 15 "Native client (UNIX socket client)"
>D: [pulseaudio] protocol-native.c: Protocol version: remote 30, local 30
>I: [pulseaudio] protocol-native.c: Got credentials: uid=0 gid=0 success=1
>D: [pulseaudio] protocol-native.c: SHM possible: no
>D: [pulseaudio] protocol-native.c: Negotiated SHM: no
>D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: Must be enabled by module parameter
>Module #0
>        Name: module-udev-detect
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "Detect available audio hardware and load matching drivers"
>                module.version = "6.0"
>
>Module #1
>        Name: module-native-protocol-unix
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "Native protocol (UNIX sockets)"
>                module.version = "6.0"
>
>Module #2
>        Name: module-stream-restore
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "Automatically restore the volume/mute/device state of streams"
>                module.version = "6.0"
>
>Module #3
>        Name: module-device-restore
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "Automatically restore the volume/mute state of devices"
>                module.version = "6.0"
>
>Module #4
>        Name: module-default-device-restore
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "Automatically restore the default sink and source"
>                module.version = "6.0"
>
>Module #5
>        Name: module-rescue-streams
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "When a sink/source is removed, try to move its streams to the default sink/source"
>                module.version = "6.0"
>
>Module #6
>        Name: module-always-sink
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Colin Guthrie"
>                module.description = "Always keeps at least one sink loaded even if it's a null one"
>                module.version = "6.0"
>
>Module #8
>        Name: module-position-event-sounds
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Lennart Poettering"
>                module.description = "Position event sounds between L and R depending on the position on screen of the widget triggering them."
>                module.version = "6.0"
>
>Module #9
>        Name: module-bluetooth-policy
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "FrÃ©dÃ©ric Dalleau"
>                module.description = "When a bluetooth sink or source is added, load module-loopback"
>                module.version = "6.0"
>
>Module #10
>        Name: module-bluetooth-discover
>        Argument: headset=native
>        Usage counter: n/a
>        Properties:
>                module.author = "JoÃ£o Paulo Rechi Vita"
>                module.description = "Detect available Bluetooth daemon and load the corresponding discovery module"
>                module.version = "6.0"
>
>Module #11
>        Name: module-bluez5-discover
>        Argument: headset=native
>        Usage counter: n/a
>        Properties:
>                module.author = "JoÃ£o Paulo Rechi Vita"
>                module.description = "Detect available BlueZ 5 Bluetooth audio devices and load BlueZ 5 Bluetooth audio drivers"
>                module.version = "6.0"
>
>Module #12
>        Name: module-switch-on-connect
>        Argument:
>        Usage counter: n/a
>        Properties:
>                module.author = "Michael Terry"
>                module.description = "When a sink/source is added, switch to it or conditionally switch to it"
>                module.version = "6.0"
>
>Module #13
>        Name: module-bluez5-device
>        Argument: path=/org/bluez/hci0/dev_00_11_C2_08_15_F6
>        Usage counter: 1
>        Properties:
>                module.author = "JoÃ£o Paulo Rechi Vita"
>                module.description = "BlueZ 5 Bluetooth audio sink and source"
>                module.version = "6.0"
>
>Sink #5
>        State: RUNNING
>        Name: bluez_sink.00_11_C2_08_15_F6
>        Description: CB3 Fit
>        Driver: module-bluez5-device.c
>        Sample Specification: s16le 1ch 8000Hz
>        Channel Map: mono
>        Owner Module: 13
>        Mute: no
>        Volume: mono: 52429 /  80%
>                balance 0.00
>        Base Volume: 65536 / 100%
>        Monitor Source: bluez_sink.00_11_C2_08_15_F6.monitor
>        Latency: 31000 usec, configured 128000 usec
>        Flags: HARDWARE HW_VOLUME_CTRL LATENCY
>        Properties:
>                bluetooth.protocol = "headset_head_unit"
>                device.intended_roles = "phone"
>                device.description = "CB3 Fit"
>                device.string = "00:11:C2:08:15:F6"
>                device.api = "bluez"
>                device.class = "sound"
>                device.bus = "bluetooth"
>                device.form_factor = "headset"
>                bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>                bluez.class = "0x240404"
>                bluez.alias = "CB3 Fit"
>                device.icon_name = "audio-headset-bluetooth"
>        Ports:
>                headset-output: Headset (priority: 0)
>        Active Port: headset-output
>        Formats:
>                pcm
>
>Source #6
>        State: IDLE
>        Name: bluez_sink.00_11_C2_08_15_F6.monitor
>        Description: Monitor of CB3 Fit
>        Driver: module-bluez5-device.c
>        Sample Specification: s16le 1ch 8000Hz
>        Channel Map: mono
>        Owner Module: 13
>        Mute: no
>        Volume: mono: 65536 / 100% / 0.00 dB
>                balance 0.00
>        Base Volume: 65536 / 100% / 0.00 dB
>        Monitor of Sink: bluez_sink.00_11_C2_08_15_F6
>        Latency: 0 usec, configured 128000 usec
>        Flags: DECIBEL_VOLUME LATENCY
>        Properties:
>                device.description = "Monitor of CB3 Fit"
>                device.class = "monitor"
>                device.string = "00:11:C2:08:15:F6"
>                device.api = "bluez"
>                device.bus = "bluetooth"
>                device.form_factor = "headset"
>                bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>                bluez.class = "0x240404"
>                bluez.alias = "CB3 Fit"
>                device.icon_name = "audio-headset-bluetooth"
>                device.intended_roles = "phone"
>        Formats:
>                pcm
>
>Source #7
>        State: IDLE
>        Name: bluez_source.00_11_C2_08_15_F6
>        Description: CB3 Fit
>        Driver: module-bluez5-device.c
>        Sample Specification: s16le 1ch 8000Hz
>        Channel Map: mono
>        Owner Module: 13
>        Mute: no
>        Volume: mono: 65536 / 100%
>                balance 0.00
>        Base Volume: 65536 / 100%
>        Monitor of Sink: n/a
>        Latency: 25000 usec, configured 28000 usec
>        Flags: HARDWARE HW_VOLUME_CTRL LATENCY
>        Properties:
>                bluetooth.protocol = "headset_head_unit"
>                device.intended_roles = "phone"
>                device.description = "CB3 Fit"
>                device.string = "00:11:C2:08:15:F6"
>                device.api = "bluez"
>                device.class = "sound"
>                device.bus = "bluetooth"
>                device.form_factor = "headset"
>                bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>                bluez.class = "0x240404"
>                bluez.alias = "CB3 Fit"
>                device.icon_name = "audio-headset-bluetooth"
>        Ports:
>                headset-input: Headset (priority: 0)
>        Active Port: headset-input
>        Formats:
>                pcm
>
>Sink Input #6
>        Driver: protocol-native.c
>        Owner Module: 1
>        Client: 14
>        Sink: 5
>        Sample Specification: s16le 2ch 22050Hz
>        Channel Map: front-left,front-right
>        Format: pcm, format.sample_format = "\"s16le\""  format.rate = "22050"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
>        Corked: no
>        Mute: no
>        Volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
>                balance 0.00
>        Buffer Latency: 500000 usec
>        Sink Latency: 31000 usec
>        Resample method: ffmpeg
>        Properties:
>                media.name = "ALSA Playback"
>                application.name = "ALSA plug-in [aplay]"
>                native-protocol.peer = "UNIX socket client"
>                native-protocol.version = "30"
>                application.process.id = "852"
>                application.process.user = "root"
>                application.process.host = "(none)"
>                application.process.binary = "aplay"
>                application.language = "C"
>                application.process.machine_id = "(none)"
>                module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [aplay]"
>
>Client #14
>        Driver: protocol-native.c
>        Owner Module: 1
>        Properties:
>                application.name = "ALSA plug-in [aplay]"
>                native-protocol.peer = "UNIX socket client"
>                native-protocol.version = "30"
>                application.process.id = "852"
>                application.process.user = "root"
>                application.process.host = "(none)"
>                application.process.binary = "aplay"
>                application.language = "C"
>                application.process.machine_id = "(none)"
>
>Client #15
>        Driver: protocol-native.c
>        Owner Module: 1
>        Properties:
>                application.name = "pactl"
>                native-protocol.peer = "UNIX socket client"
>                native-protocol.version = "30"
>                application.process.id = "854"
>                application.process.user = "root"
>                application.process.host = "(none)"
>                application.process.binary = "pactl"
>                application.language = "C"
>                application.process.machine_id = "(none)"
>
>Card #0
>        Name: bluez_card.00_11_C2_08_15_F6
>        Driver: module-bluez5-device.c
>        Owner Module: 13
>        Properties:
>                device.description = "CB3 Fit"
>                device.string = "00:11:C2:08:15:F6"
>                device.api = "bluez"
>                device.class = "sound"
>                device.bus = "bluetooth"
>                device.form_factor = "headset"
>                bluez.path = "/org/bluez/hci0/dev_00_11_C2_08_15_F6"
>                bluez.class = "0x240404"
>                bluez.alias = "CB3 Fit"
>                device.icon_name = "audio-headset-bluetooth"
>                device.intended_roles = "phone"
>        Profiles:
>                a2dp_sink: High Fidelity Playback (A2DP Sink) (sinks: 1, sources: 0, priority: 10, available: yes)
>                headset_head_unit: Headset Head Unit (HSP/HFP) (sinks: 1, sources: 1, priority: 20, available: yes)
>                off: Off (sinks: 0, sources: 0, priority: 0, available: yes)
>        Active Profile: headset_head_unit
>        Ports:
>                headset-output: Headset (priority: 0, latency offset: 0 usec)
>                        Part of profile(s): a2dp_sink, headset_head_unit
>                headset-input: Headset (priority: 0, latency offset: 0 usec)
>                        Part of profile(s): headset_head_unit
>I: [pulseaudio] client.c: Freed 15 "pactl"
>I: [pulseaudio] protocol-native.c: Connection died.
>#

Actions: View

Attachments on bug 97064: 125285 | 127040 | 127046 | 127047 | 127121

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.