Hi Tanu,
Thanks for the reply.
I think that the loopback module shouldn’t be unloaded each time steaming stops
for the simple reason that it is loaded when connection is established, and not
when streaming starts.
I get all kind of PulseAudio problems, for example in the example below,
PulseAudio success to stream in the start, but after pausing and playing again
PulseAudio act differently.
- It looks like PulseAudio has problem to handle changing connection state or
something.
The below log was produced by the following steps:
a) connection establishment
b) streaming session
c) stop from remote device
d) unloading loopback module
e) toggling streaming start/stop without PulseAudio suitable response
f) pacmd failure for long time (more then 3-4 minutes). – so it’s
probably not a dbus thing.
Please notice that each time I try a test, something a little different
happens- but it always has to do with modules unloading/PulseAudio doesn’t
response
I know the log is a little long, but the important part as I see it start
around line ~443.
Thanks, Eytan.
pulse@OpenWrt:/$
-----Original Message-----
From: Tanu Kaskinen [mailto:[email protected]]
Sent: Wednesday, August 12, 2015 6:49 PM
To: Eytan Naim; [email protected]
Subject: Re: [pulseaudio-discuss] pulseaudio a2dp disconnect problem
On Wed, 2015-08-12 at 09:36 +0000, Eytan Naim wrote:
> Hi,
> I'm hoping I could get help me because I have searched the web twice
> and still not sure what to do.
> I'm trying to enable PulseAudio as an a2dp sink which seems to work
> fine on start, until I pause the audio app momentarily on the source
> device.
> After that, instead of connection should be idle or something,
> PulseAudio unload his Bluetooth loopback module as seen below. And it
> seems that this disconnection event is triggered by Bluetoothd itself,
> but I assume that the problem is with PulseAudio.
The PA log is not as verbose as it could be (use -vv to get the full debug
output), which makes it harder to know what exactly is happening, but my guess
is that unloading the loopback module doesn't indicate any issue. When the
streaming pauses, PulseAudio changes the bluetooth card profile to off, which
unloads the source, which in turn causes the loopback module to get unloaded.
Perhaps PulseAudio shouldn't switch the card profile to off, but probably it
should.
> After that PulseAudio seem somehow zombie, I can only kill him with
> kill command (ctrl+c doesn't work).
I suppose pactl can't connect to the daemon either?
You could run PA under gdb, and get a backtrace when it gets stuck. My guess
would be that it's stuck in some synchronous D-Bus call to bluetoothd (the last
log message is about setting up the streaming again). D-Bus calls should
eventually terminate with a timeout, though
- is PA stuck for more than a minute?
--
Tanu
pulse@OpenWrt:/$ pulseaudio -vvvvvvv --log-meta=true --log-time=true --log-backt
race=5 &
pulse@OpenWrt:/$ ( 0.000| 0.000) I: [pulseaudio][daemon/main.c:273
set_one_rlimit()] setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not
permitted
( 0.012| 0.012) I: [pulseaudio][daemon/main.c:273 set_one_rlimit()]
setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted
( 0.025| 0.012) D: [pulseaudio][pulsecore/core-rtclock.c:164
pa_rtclock_hrtimer_enable()] Timer slack is set to 50 us.
( 0.060| 0.035) I: [pulseaudio][pulsecore/core-util.c:908
pa_raise_priority()] Failed to acquire high-priority scheduling: No such file
or directory
( 0.074| 0.014) I: [pulseaudio][daemon/main.c:907 main()] This is
PulseAudio 6.0-dirty
( 0.083| 0.008) D: [pulseaudio][daemon/main.c:908 main()] Compilation host:
arm-openwrt-linux-gnu
( 0.092| 0.009) D: [pulseaudio][daemon/main.c:909 main()] Compilation
CFLAGS: -Os -pipe -march=armv7-a -mtune=cortex-a7 -mfpu=neon-vfpv4
-fno-caller-saves -fhonour-copts -Wno-error=unused-but-set-variable
-mfloat-abi=hard
-I/local/store/NZ3/openwrt/staging_dir/target-arm_cortex-a7+neon-vfpv4_uClibc-0.9.33.2_eabi/usr/lib/libiconv-stub/include
-I/local/store/NZ3/openwrt/staging_dir/target-arm_cortex-a7+neon-vfpv4_uClibc-0.9.33.2_eabi/usr/lib/libintl-stub/include
-std=gnu99 -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
( 0.177| 0.085) D: [pulseaudio][daemon/main.c:916 main()] Running on host:
Linux armv7l 3.10.33 #70 PREEMPT Thu Aug 13 09:52:55 MSK 2015
( 0.190| 0.012) D: [pulseaudio][daemon/main.c:919 main()] Found 1 CPUs.
( 0.197| 0.007) I: [pulseaudio][daemon/main.c:921 main()] Page size is 4096
bytes
( 0.205| 0.007) D: [pulseaudio][daemon/main.c:926 main()] Compiled with
Valgrind support: no
( 0.214| 0.008) D: [pulseaudio][daemon/main.c:929 main()] Running in
valgrind mode: no
( 0.222| 0.008) D: [pulseaudio][daemon/main.c:931 main()] Running in VM: no
( 0.230| 0.007) D: [pulseaudio][daemon/main.c:934 main()] Optimized build:
yes
( 0.237| 0.007) D: [pulseaudio][daemon/main.c:942 main()] FASTPATH defined,
only fast path asserts disabled.
( 0.248| 0.010) I: [pulseaudio][daemon/main.c:951 main()] Machine ID is
db73771a52a35c20a503995655cc3eed.
( 0.258| 0.010) I: [pulseaudio][daemon/main.c:961 main()] Using runtime
directory /pulse/.config/pulse/db73771a52a35c20a503995655cc3eed-runtime.
( 0.272| 0.013) I: [pulseaudio][daemon/main.c:966 main()] Using state
directory /pulse/.config/pulse.
( 0.282| 0.009) I: [pulseaudio][daemon/main.c:969 main()] Using modules
directory /usr/lib/pulse-6.0/modules.
( 0.293| 0.010) I: [pulseaudio][daemon/main.c:971 main()] Running in system
mode: no
( 0.302| 0.009) I: [pulseaudio][daemon/main.c:1001 main()] Fresh
high-resolution timers available! Bon appetit!
( 0.313| 0.011) E: [pulseaudio][pulsecore/shm.c:154 pa_shm_create_rw()]
shm_open() failed: Permission denied
( 0.325| 0.011) W: [pulseaudio][pulsecore/core.c:73 pa_core_new()] Failed
to allocate shared memory pool. Falling back to a normal memory pool.
( 0.339| 0.014) D: [pulseaudio][pulsecore/memblock.c:776 pa_mempool_new()]
Using private memory pool with 1024 slots of size 4.0 KiB each, total size is
4.0 MiB, maximum usable slot size is 4056
( 0.358| 0.019) I: [pulseaudio][pulsecore/cpu-arm.c:140
pa_cpu_get_arm_flags()] CPU flags: V6 V7 VFP EDSP NEON VFPV3
( 0.369| 0.011) I: [pulseaudio][pulsecore/svolume_arm.c:160
pa_volume_func_init_arm()] Initialising ARM optimized volume functions.
( 0.382| 0.012) I: [pulseaudio][pulsecore/sconv_neon.c:89
pa_convert_func_init_neon()] Initialising ARM NEON optimized conversions.
( 0.394| 0.012) I: [pulseaudio][pulsecore/mix_neon.c:219
pa_mix_func_init_neon()] Initialising ARM NEON optimized mixing functions.
( 0.406| 0.012) I: [pulseaudio][pulsecore/remap_neon.c:495
pa_remap_func_init_neon()] Initialising ARM NEON optimized remappers.
( 0.431| 0.024) I: [pulseaudio][modules/module-device-restore.c:1275
module_device_restore_LTX_pa__init()] Successfully opened database file
'/pulse/.config/pulse/db73771a52a35c20a503995655cc3eed-device-volumes'.
( 0.451| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-device-restore" (index: #0; argument: "").
( 0.474| 0.022) I: [pulseaudio][modules/module-stream-restore.c:2436
module_stream_restore_LTX_pa__init()] Successfully opened database file
'/pulse/.config/pulse/db73771a52a35c20a503995655cc3eed-stream-volumes'.
( 0.494| 0.020) D: [pulseaudio][pulsecore/protocol-dbus.c:797
pa_dbus_protocol_add_interface()] Interface org.PulseAudio.Ext.StreamRestore1
added for object /org/pulseaudio/stream_restore1
( 0.512| 0.017) D: [pulseaudio][pulsecore/protocol-dbus.c:797
pa_dbus_protocol_add_interface()] Interface
org.PulseAudio.Ext.StreamRestore1.RestoreEntry added for object
/org/pulseaudio/stream_restore1/entry0
( 0.532| 0.019) D: [pulseaudio][pulsecore/protocol-dbus.c:797
pa_dbus_protocol_add_interface()] Interface
org.PulseAudio.Ext.StreamRestore1.RestoreEntry added for object
/org/pulseaudio/stream_restore1/entry1
( 0.551| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-stream-restore" (index: #1; argument: "").
( 0.571| 0.020) I: [pulseaudio][modules/module-card-restore.c:557
module_card_restore_LTX_pa__init()] Successfully opened database file
'/pulse/.config/pulse/db73771a52a35c20a503995655cc3eed-card-database'.
( 0.590| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-card-restore" (index: #2; argument: "").
( 0.609| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-augment-properties" (index: #3; argument: "").
( 0.629| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-switch-on-port-available" (index: #4; argument: "").
( 0.643| 0.014) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of '/usr/lib/pulse-6.0/modules/module-udev-detect.so':
success
( 0.674| 0.030) I: [pulseaudio][modules/module-udev-detect.c:799
module_udev_detect_LTX_pa__init()] Found 0 cards.
( 0.685| 0.011) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-udev-detect" (index: #5; argument: "").
( 0.697| 0.012) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of
'/usr/lib/pulse-6.0/modules/module-jackdbus-detect.so': failure
( 0.712| 0.015) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of
'/usr/lib/pulse-6.0/modules/module-bluetooth-policy.so': success
( 0.734| 0.021) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-bluetooth-policy" (index: #6; argument: "").
( 0.747| 0.012) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of
'/usr/lib/pulse-6.0/modules/module-bluetooth-discover.so': success
( 0.769| 0.022) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of
'/usr/lib/pulse-6.0/modules/module-bluez5-discover.so': success
( 0.802| 0.032) D: [pulseaudio][pulsecore/dbus-util.c:288
pa_dbus_wrap_connection_new()] Successfully connected to D-Bus system bus
df9e36e607206584f1c1a27055cc6c56 as :1.12
( 0.829| 0.027) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-bluez5-discover" (index: #8; argument: "").
( 0.842| 0.012) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of
'/usr/lib/pulse-6.0/modules/module-bluez4-discover.so': failure
( 0.857| 0.015) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-bluetooth-discover" (index: #7; argument: "").
( 0.870| 0.012) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of
'/usr/lib/pulse-6.0/modules/module-esound-protocol-unix.so': success
( 0.896| 0.025) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-native-protocol-unix" (index: #9; argument: "").
( 0.909| 0.013) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of '/usr/lib/pulse-6.0/modules/module-gconf.so': failure
( 0.932| 0.022) I: [pulseaudio][modules/module-default-device-restore.c:75
load()] Saved default sink 'auto_null' not existent, not restoring default sink
setting.
( 0.947| 0.015) I: [pulseaudio][modules/module-default-device-restore.c:96
load()] Saved default source 'bluez_source.98_D6_F7_34_98_E8' not existent, not
restoring default source setting.
( 0.965| 0.017) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-default-device-restore" (index: #10; argument: "").
( 0.985| 0.020) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-rescue-streams" (index: #11; argument: "").
( 1.005| 0.019) D: [pulseaudio][modules/module-always-sink.c:75
load_null_sink_if_needed()] Autoloading null-sink as no other sinks detected.
( 1.027| 0.022) I: [pulseaudio][pulsecore/sink.c:362 pa_sink_new()] Created
sink 0 "auto_null" with sample spec s16le 2ch 44100Hz and channel map
front-left,front-right
( 1.027| 0.022) I: [pulseaudio][pulsecore/sink.c:362 pa_sink_new()]
device.description = "Dummy Output"
( 1.027| 0.022) I: [pulseaudio][pulsecore/sink.c:362 pa_sink_new()]
device.class = "abstract"
( 1.027| 0.022) I: [pulseaudio][pulsecore/sink.c:362 pa_sink_new()]
device.icon_name = "audio-card"
( 1.072| 0.045) D: [pulseaudio][pulsecore/core-subscribe.c:242
pa_subscription_post()] Dropped redundant event due to change event.
( 1.085| 0.012) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
Created source 0 "auto_null.monitor" with sample spec s16le 2ch 44100Hz and
channel map front-left,front-right
( 1.085| 0.012) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.description = "Monitor of Dummy Output"
( 1.085| 0.012) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.class = "monitor"
( 1.085| 0.012) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.icon_name = "audio-input-microphone"
( 1.134| 0.049) D: [null-sink][modules/module-null-sink.c:201
thread_func()] Thread starting up
( 1.144| 0.010) D: [pulseaudio][modules/module-device-restore.c:863
sink_put_hook_callback()] Could not set format on sink auto_null
( 1.157| 0.012) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-null-sink" (index: #13; argument: "sink_name=auto_null
sink_properties='device.description="Dummy Output"'").
( 1.175| 0.018) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-always-sink" (index: #12; argument: "").
( 1.195| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-intended-roles" (index: #14; argument: "").
( 1.215| 0.019) D: [pulseaudio][modules/module-suspend-on-idle.c:112
restart()] Sink auto_null becomes idle, timeout in 5 seconds.
( 1.227| 0.012) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-suspend-on-idle" (index: #15; argument: "").
( 1.240| 0.012) D: [pulseaudio][pulsecore/module.c:75 pa_module_exists()]
Checking for existence of '/usr/lib/pulse-6.0/modules/module-systemd-login.so':
failure
( 1.262| 0.022) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-position-event-sounds" (index: #16; argument: "").
( 1.283| 0.020) D: [pulseaudio][modules/module-role-cork.c:233
module_role_cork_LTX_pa__init()] Using role 'phone' as trigger role.
( 1.296| 0.012) D: [pulseaudio][modules/module-role-cork.c:250
module_role_cork_LTX_pa__init()] Using roles 'music' and 'video' as cork roles.
( 1.309| 0.013) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-role-cork" (index: #17; argument: "").
( 1.329| 0.019) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-filter-heuristics" (index: #18; argument: "").
( 1.349| 0.020) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-filter-apply" (index: #19; argument: "").
( 1.368| 0.006) D: [pulseaudio][pulsecore/dbus-util.c:288
pa_dbus_wrap_connection_new()] Successfully connected to D-Bus session bus
df9e36e607206584f1c1a27055cc6c56 as :1.13
( 1.387| 0.018) D: [pulseaudio][daemon/main.c:348 register_dbus_name()] Got
org.PulseAudio1!
( 1.399| 0.011) D: [pulseaudio][daemon/main.c:348 register_dbus_name()] Got
org.pulseaudio.Server!
( 1.408| 0.009) I: [pulseaudio][daemon/main.c:1131 main()] Daemon startup
complete.
( 1.418| 0.009) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
( 1.434| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.AgentManager1
found, skipping
( 1.449| 0.014) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.ProfileManager1
found, skipping
( 1.464| 0.014) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.Alert1 found,
skipping
( 1.478| 0.014) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.HealthManager1
found, skipping
( 1.493| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
( 1.509| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:815
parse_interfaces_and_properties()] Adapter /org/bluez/hci0 found
( 1.522| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:748
register_endpoint()] Registering /MediaEndpoint/A2DPSource on adapter
/org/bluez/hci0
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_register_sep() SEP 0xcb0b0
registered: type:0 codec:0 seid:1
( 1.547| 0.025) D: [pulseaudio][modules/bluetooth/bluez5-util.c:748
register_endpoint()] bluetoothd[2283]: src/adapter.c:adapter_service_add()
/org/bluez/hci0
Registering /MediaEndpoint/A2DPSink on adapter /org/bluez/hci0
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Adding record with
handle 0x10005
( 1.578| 0.031) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
00000019-0000-1000-8000-00805f9
Unknown interface org.freedesktop.DBus.Properties found,
skippingbluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record
pattern UUID 00000100-0000-1000-8000-00805f9
( 1.615| 0.036) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
00001002-0000-1000-8000-00805f9
Unknown interface org.bluez.Media1 found, skipping
( 1.640| 0.024) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
0000110a-0000-1000-8000-00805f9
Unknown interface org.bluez.CyclingSpeedManager1 found,
skippingbluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record
pattern UUID 0000110d-0000-1000-8000-00805f9
( 1.677| 0.036) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]:
src/adapter.c:adapter_service_insert() /org/bluez/hci0
Unknown interface org.bluez.HeartRateManager1 found, skipping
( 1.699| 0.022) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]: src/adapter.c:add_uuid()
sending add uuid command for index 0
Unknown interface org.bluez.ThermometerManager1 found, skipping
( 1.722| 0.022) bluetoothd[2283]: Endpoint registered: sender=:1.12
path=/MediaEndpoint/A2DPSource
D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.NetworkServer1
found, skippingbluetoothd[2283]: profiles/audio/avdtp.c:avdtp_register_sep()
SEP 0xc9810 registered: type:1 codec:0 seid:2
( 1.758| 0.035) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]:
src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Adding record with
handle 0x10006
Unknown interface org.freedesktop.DBus.Introspectable found, skipping
( 1.790| 0.031) D: [pulseaudio][modules/bluetooth/bluez5-util.c:835
parse_interfaces_and_properties()] Device /org/bluez/hci0/dev_98_D6_F7_34_98_E8
found
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00000019-0000-1000-8000-00805f9
( 1.814| 0.024) D: [pulseaudio][modules/bluetooth/bluez5-util.c:575
parse_device_property()] Address: 98:D6:F7:34:98:E8bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
00000100-0000-1000-8000-00805f9
( 1.836| 0.022) D: [pulseaudio][modules/bluetooth/bluez5-util.c:562
parse_device_property()] bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
00001002-0000-1000-8000-00805f9
Alias: Nexus 4
( 1.857| 0.020) D: [pulseaudio][modules/bluetooth/bluez5-util.c:610
parse_device_property()] bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
0000110b-0000-1000-8000-00805f9
Class: 5898764
( 1.878| 0.020) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] bluetoothd[2283]:
src/sdpd-service.c:add_record_to_server() Record pattern UUID
0000110d-0000-1000-8000-00805f9
UUIDs: 00001105-0000-1000-8000-00805f9b34fb
( 1.901| 0.023) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] bluetoothd[2283]:
src/adapter.c:adapter_service_insert() /org/bluez/hci0
UUIDs: 0000110a-0000-1000-8000-00805f9b34fb
( 1.921| 0.019) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] bluetoothd[2283]: src/adapter.c:add_uuid() sending add
uuid command for index 0
UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
( 1.942| 0.020) Dbluetoothd[2283]: Endpoint registered: sender=:1.12
path=/MediaEndpoint/A2DPSink
: [pulseaudio][modules/bluetooth/bluez5-util.c:637 parse_device_property()]
UUIDs: 0000110d-0000-1000-8000-00805f9b34fb
( 1.965| 0.022) D: bluetoothd[2283]:
src/adapter.c:dev_class_changed_callback() Class: 0x080000
[pulseaudio][modules/bluetooth/bluez5-util.c:637 parse_device_property()]
UUIDs: 0000110e-0000-1000-8000-00805f9b34fbbluetoothd[2283]:
src/adapter.c:dev_class_changed_callback() Class: 0x0c0000
( 1.999| 0.034) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001112-0000-1000-8000-00805f9b34fb
( 2.016| 0.017) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001115-0000-1000-8000-00805f9b34fb
( 2.029| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001116-0000-1000-8000-00805f9b34fb
( 2.042| 0.013) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 0000111f-0000-1000-8000-00805f9b34fb
( 2.055| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 0000112f-0000-1000-8000-00805f9b34fb
( 2.068| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001132-0000-1000-8000-00805f9b34fb
( 2.080| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001200-0000-1000-8000-00805f9b34fb
( 2.093| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001800-0000-1000-8000-00805f9b34fb
( 2.106| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:637
parse_device_property()] UUIDs: 00001801-0000-1000-8000-00805f9b34fb
( 2.119| 0.012) D: [pulseaudio][modules/bluetooth/bluez5-util.c:598
parse_device_property()] Adapter: /org/bluez/hci0
( 2.130| 0.011) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.freedesktop.DBus.Properties found, skipping
( 2.145| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.bluez.ProximityReporter1 found, skipping
( 2.161| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.Network1 found,
skipping
( 2.175| 0.014) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.MediaControl1
found, skipping
( 2.190| 0.014) D: [pulseaudio][modules/bluetooth/backend-native.c:469
pa_bluetooth_native_backend_new()] Bluetooth Headset Backend API support using
the native backend
( 2.206| 0.015) D: [pulseaudio][modules/bluetooth/backend-native.c:204
register_profile()] Registering Profile /Profile/HSPAGProfile
bluetoothd[2283]: src/profile.c:register_profile() sender :1.12
( 2.226| 0.020) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1059
filter_cb()] bluetoothd[2283]: src/profile.c:create_ext() Created "Headset
Voice gateway"
Properties changed in adapter /org/bluez/hci0
( 2.246| 0.020) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1059
filter_cb()] bluetoothd[2283]: src/profile.c:ext_adapter_probe() "Headset Voice
gateway" probed
Properties changed in adapter /org/bluez/hci0
bluetoothd[2283]: src/profile.c:ext_start_servers() Headset Voice gateway
listening on chan 12
bluetoothd[2283]: src/adapter.c:adapter_service_add() /org/bluez/hci0
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Adding record with
handle 0x10007
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00000003-0000-1000-8000-00805f9
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00000100-0000-1000-8000-00805f9
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00001002-0000-1000-8000-00805f9
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00001108-0000-1000-8000-00805f9
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00001112-0000-1000-8000-00805f9
bluetoothd[2283]: src/sdpd-service.c:add_record_to_server() Record pattern UUID
00001203-0000-1000-8000-00805f9
bluetoothd[2283]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
bluetoothd[2283]: src/adapter.c:add_uuid() sending add uuid command for index 0
( 2.379| 0.132) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1059
filter_cb()] Properties changed in adapter /org/bluez/hci0
pulse@OpenWrt:/$
(reverse-i-search)'c': pulseaudio -vvvvvvv --log-meta=true --log-time=true --log
-ba( 6.219| 3.839) I: [pulseaudio][modules/module-suspend-on-idle.c:90
timeout_cb()] Sink auto_null idle for too long, suspending ...
( 6.232| 0.012) D: [pulseaudio][pulsecore/sink.c:872 pa_sink_suspend()]
Suspend cause of sink auto_null is 0x0004, suspending
pulse@OpenWrt:/$ bluetoothctl
[NEW] Controller 00:50:43:21:EE:DF BlueZ 5.30 [default]
[NEW] Device 98:D6:F7:34:98:E8 Nexus 4
[bluetooth]# agent on
bluetoothd[2283]: src/agent.c:agent_ref() 0xc4bd0: ref=1
[bluetooth]# bluetoothd[2283]: src/agent.c:register_agent() agent :1.14
Agent registered
[bluetooth]# default-agent
[bluetooth]# bluetoothd[2283]: src/agent.c:add_default_agent() Default agent
set to :1.14 /org/bluez/agent
Default agent request successful
[bluetooth]# bluetoothd[2283]: src/adapter.c:connected_callback() hci0 device
98:D6:F7:34:98:E8 connected eir_len 5
D: [pulseaudio][modules/bluetooth/bluez5-util.c:1071 filter_cb()] [CHG] Device
98:D6:F7:34:98:E8 Connected: yes
Properties changed in device /org/bluez/hci0/dev_98_D6_F7_34_98_E8[Nexus 4]#
[Nexus 4]# bluetoothd[2283]: src/adapter.c:dev_disconnected() Device
98:D6:F7:34:98:E8 disconnected, reason 3
bluetoothd[2283]: src/adapter.c:adapter_remove_connection()
bluetoothd[2283]: plugins/policy.c:disconnect_cb() reason 3
bluetoothd[2283]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr
98:D6:F7:34:98:E8 type 0 status 0xe
bluetoothd[2283]: src/device.c:device_bonding_complete() bonding (nil) status
0x0e
bluetoothd[2283]: src/device.c:device_bonding_failed() status 14
bluetoothd[2283]: src/adapter.c:resume_discovery()
[CHG] Device 98:D6:F7:34:98:E8 Connected: no
[bluetooth]#
[bluetooth]# bluetoothd[2283]: src/adapter.c:connected_callback() hci0 device
98:D6:F7:34:98:E8 connected eir_len 5
[CHG] Device 98:D6:F7:34:98:E8 Connected: yes
[Nexus 4]# Properties changed in device /org/bluez/hci0/dev_98_D6_F7_34_98_E8
bluetoothd[2283]: profiles/audio/a2dp.c:confirm_cb() AVDTP: incoming connect
from 98:D6:F7:34:98:E8
bluetoothd[2283]: src/agent.c:agent_ref() 0xc4bd0: ref=2
Authorize service
o): bluetoothd[2283]: src/agent.c:agent_authorize_service() authorize service
request was sent for /org/bluez/hci0/dev_98_D6_F7_34_98_E8
o): yes Authorize service 0000110d-0000-1000-8000-00805f9b34fb (yes/no
[Nexus 4]# bluetoothd[2283]: src/agent.c:agent_ref() 0xc4bd0: ref=3
bluetoothd[2283]: src/agent.c:agent_unref() 0xc4bd0: ref=2
bluetoothd[2283]: src/agent.c:agent_unref() 0xc4bd0: ref=1
[Nexus 4]# bluetoothd[2283]: profiles/audio/source.c:source_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_DISCONNECTED ->
SOURCE_STATE_CONNECTING
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected
signaling channel to 98:D6:F7:34:98:E8
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672,
omtu=672
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received DISCOVER_CMD
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received
GET_ALL_CAPABILITIES_CMD
bluetoothd[2283]: profiles/audio/a2dp.c:endpoint_getcap_ind() Sink 0xc9810:
Get_Capability_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received
SET_CONFIGURATION_CMD
bluetoothd[2283]: profiles/audio/a2dp.c:endpoint_setconf_ind() Sink 0xc9810:
Set_Configuration_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=1
bluetoothd[2283]: profiles/audio/a2dp.c:setup_ref() 0xc9130: ref=1
( 35.658| 3.741) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] bluetoothd[2283]:
profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration:
name = :1.12 path = /MediaEndpoint/A2DPSink
Unknown interface org.freedesktop.DBus.Introspectable found, skipping
( 35.693| 0.035) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.MediaTransport1
found, skipping
( 35.709| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.freedesktop.DBus.Properties found, skipping
( 35.725| 0.016) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1505
endpoint_handler()] dbus: path=/MediaEndpoint/A2DPSink,
interface=org.bluez.MediaEndpoint1, member=SetConfiguration
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=2
( 35.750| 0.024) D: [pulseaudio][modules/bluetooth/bluez5-util.c:178
pa_bluetooth_transport_set_state()] bluetoothd[2283]:
profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE ->
CONFIGURED
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 state changed from
disconnected to idlebluetoothd[2283]: profiles/audio/a2dp.c:setup_unref()
0xc9130: ref=0
( 35.785| 0.035) D:
[pulseaudio][modules/bluetooth/module-bluez5-discover.c:76
device_connection_changed_cb()] bluetoothd[2283]:
profiles/audio/a2dp.c:setup_free() 0xc9130
Loading module-bluez5-device path=/org/bluez/hci0/dev_98_D6_F7_34_98_E8
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_unref() 0xcc840: ref=1
( 35.829| 0.043) I: [pulseaudio][modules/module-card-restore.c:512
card_new_hook_callback()] Restoring port latency offsets for card
bluez_card.98_D6_F7_34_98_E8.
( 35.844| 0.015) I: [pulseaudio][pulsecore/card.c:208 pa_card_new()] Created
0 "bluez_card.98_D6_F7_34_98_E8"
( 35.855| 0.010) D:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:739 transport_acquire()]
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
Acquiring transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1bluetoothd[2283]:
profiles/audio/avdtp.c:avdtp_parse_cmd() Received OPEN_CMD
bluetoothd[2283]: profiles/audio/a2dp.c:open_ind() Sink 0xc9810: Open_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=2
bluetoothd[2283]: profiles/audio/a2dp.c:setup_ref() 0xc4570: ref=1
( 35.906| 0.051) I: [pulseaudio][modules/bluetooth/bluez5-util.c:235
bluez5_transport_acquire_cb()] Failed optional acquire of unavailable transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
( 35.924| 0.017) I:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:1258 transport_config()]
bluetoothd[2283]: profiles/audio/a2dp.c:confirm_cb() AVDTP: incoming connect
from 98:D6:F7:34:98:E8
SBC parameters: allocation=0, subbands=1, blocks=3, bitpool=2
[Nexus 4]# Created source 1 "bluez_source.98_D6_F7_34_98_E8" with sample spec
s16le 2ch 44100Hz and channel map front-left,front-right
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluetooth.protocol = "a2dp_source"
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state
changed: CONFIGURED -> OPEN
device.description = "Nexus 4"
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.string = "98:D6:F7:34:98:E8"bluetoothd[2283]:
src/service.c:change_state() 0xcc130: device 98:D6:F7:34:98:E8 profile
a2dp-source state changed: disconnected -> connected (0)
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluetoothd[2283]: plugins/policy.c:service_cb() Added a2dp-source reconnect 1
device.api = "bluez"
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.class = "sound"bluetoothd[2283]:
profiles/audio/source.c:source_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_CONNECTING ->
SOURCE_STATE_CONNECTED
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluetoothd[2283]: profiles/audio/transport.c:transport_update_playing()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 State=TRANSPORT_STATE_IDLE Playing=0
device.bus = "bluetooth"
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.form_factor = "phone"bluetoothd[2283]:
profiles/audio/a2dp.c:setup_unref() 0xc4570: ref=0
bluetoothd[2283]: profiles/audio/a2dp.c:setup_free() 0xc4570
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluez.path = "/org/bluez/hci0/dev_98_D6_F7_34_98_E8"bluetoothd[2283]:
profiles/audio/avdtp.c:avdtp_unref() 0xcc840: ref=1
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluez.class = "0x5a020c"
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluez.alias = "Nexus 4"
( 35.951| 0.026) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.icon_name = "audio-card-bluetooth"
( 36.167| 0.216) D:
[bluetooth][modules/bluetooth/module-bluez5-device.c:1334 thread_func()] IO
Thread starting up
[Nexus 4]# ( 36.222| 0.055) I: [bluetooth][pulsecore/core-util.c:839
pa_make_realtime()] Failed to acquire real-time scheduling: No such file or
directory
( 36.246| 0.024) D: [pulseaudio][pulsecore/sink-input.c:341
pa_sink_input_new()] Negotiated format: pcm, format.sample_format = "\"s16le\""
format.rate = "44100" format.channels = "2" format.channel_map =
"\"front-left,front-right\""
( 36.268| 0.022) D: [pulseaudio][modules/module-suspend-on-idle.c:123
resume()] Sink auto_null becomes busy, resuming.
( 36.279| 0.011) D: [pulseaudio][pulsecore/sink.c:872 pa_sink_suspend()]
Suspend cause of sink auto_null is 0x0000, resuming
( 36.298| 0.018) D: [pulseaudio][modules/module-suspend-on-idle.c:112
restart()] Sink auto_null becomes idle, timeout in 5 seconds.
( 36.310| 0.012) D: [pulseaudio][modules/module-suspend-on-idle.c:112
restart()] Sink auto_null becomes idle, timeout in 5 seconds.
( 36.323| 0.012) I: [pulseaudio][pulsecore/resampler.c:190 fix_method()]
Speex appears to be compiled with --enable-fixed-point. Switching to a
fixed-point resampler because it should be faster.
( 36.341| 0.017) D: [pulseaudio][pulsecore/resampler.c:385
pa_resampler_new()] Resampler:
( 36.349| 0.008) D: [pulseaudio][pulsecore/resampler.c:386
pa_resampler_new()] rate 44100 -> 44100 (method speex-fixed-1)
( 36.361| 0.011) D: [pulseaudio][pulsecore/resampler.c:388
pa_resampler_new()] format s16le -> s16le (intermediate s16le)
( 36.372| 0.011) D: [pulseaudio][pulsecore/resampler.c:389
pa_resampler_new()] channels 2 -> 2 (resampling 2)
( 36.383| 0.010) I: [pulseaudio][pulsecore/resampler/speex.c:170
pa_resampler_speex_init()] Choosing speex quality setting 1.
( 36.395| 0.012) D: [pulseaudio][pulsecore/memblockq.c:85
pa_memblockq_new()] memblockq requested: maxlength=33554432, tlength=0, base=4,
prebuf=0, minreq=1 maxrewind=0
( 36.410| 0.015) D: [pulseaudio][pulsecore/memblockq.c:96
pa_memblockq_new()] memblockq sanitized: maxlength=33554432, tlength=33554432,
base=4, prebuf=0, minreq[Nexus 4]#
( 36.429| 0.019) I: [pulseaudio][pulsecore/sink-input.c:570
pa_sink_input_new()] Created input 0 "(null)" on auto_null with sample spec
s16le 2ch 44100Hz and channel map front-left,front-right
( 36.429| 0.019) I: [pulseaudio][pulsecore/sink-input.c:570
pa_sink_input_new()] media.role = "music"
( 36.429| 0.019) I: [pulseaudio][pulsecore/sink-input.c:570
pa_sink_input_new()] module-stream-restore.id =
"sink-input-by-media-role:music"
( 36.471| 0.041) D: [pulseaudio][modules/module-stream-restore.c:1520
source_output_new_hook_callback()] Not restoring device for stream
source-output-by-media-role:abstract, because already set
( 36.489| 0.017) D: [pulseaudio][modules/module-intended-roles.c:129
source_output_new_hook_callback()] Not setting device for stream (null),
because already set.
( 36.504| 0.015) D: [pulseaudio][pulsecore/source-output.c:283
pa_source_output_new()] Negotiated format: pcm, format.sample_format =
"\"s16le\"" format.rate = "44100" format.channels = "2" format.channel_map =
"\"front-left,front-right\""
( 36.526| 0.022) D: [pulseaudio][pulsecore/memblockq.c:85
pa_memblockq_new()] memblockq requested: maxlength=33554432, tlength=0, base=4,
prebuf=0, minreq=1 maxrewind=0
( 36.542| 0.015) D: [pulseaudio][pulsecore/memblockq.c:96
pa_memblockq_new()] memblockq sanitized: maxlength=33554432, tlength=33554432,
base=4, prebuf=0, minreq=4 maxrewind=0
( 36.558| 0.016) I: [pulseaudio][pulsecore/source-output.c:493
pa_source_output_new()] Created output 0 "(null)" on
bluez_source.98_D6_F7_34_98_E8 with sample spec s16le 2ch 44100Hz and channel
map front-left,front-right
( 36.558| 0.016) I: [pulseaudio][pulsecore/source-output.c:493
pa_source_output_new()] media.role = "abstract"
( 36.558| 0.016) I: [pulseaudio][pulsecore/source-output.c:493
pa_source_output_new()] module-stream-restore.id =
"source-output-by-media-role:abstract"
[Nexus 4]# memblockq requested: maxlength=16777216, tlength=16777216, base=4,
prebuf=0, minreq=0 maxrewind=0
( 36.623| 0.019) D: [pulseaudio][pulsecore/memblockq.c:96
pa_memblockq_new()] memblockq sanitized: maxlength=16777216, tlength=16777216,
base=4, prebuf=0, minreq=4 maxrewind=0
( 36.639| 0.016) I: [null-sink][modules/module-loopback.c:644
sink_input_update_max_request_cb()] Max request changed
( 36.651| 0.011) I: [null-sink][modules/module-loopback.c:644
sink_input_update_max_request_cb()] Max request changed
( 36.662| 0.011) I: [bluetooth][modules/module-loopback.c:369
source_output_state_change_cb()] Skipping 0 bytes
( 36.673| 0.010) D: [pulseaudio][pulsecore/core-subscribe.c:242
pa_subscription_post()] Dropped redundant event due to change event.
( 36.686| 0.012) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-loopback" (index: #21; argument:
"source="bluez_source.98_D6_F7_34_98_E8" source_dont_move="true"
sink_input_properties="media.role=music"").
( 36.707| 0.021) I: [pulseaudio][pulsecore/module.c:177 pa_module_load()]
Loaded "module-bluez5-device" (index: #20; argument:
"path=/org/bluez/hci0/dev_98_D6_F7_34_98_E8").
( 36.723| 0.016) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1315
endpoint_set_configuration()] Transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 available for profile a2dp_source
[Nexus 4]# bluetoothd[2283]: plugins/policy.c:policy_connect()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8 profile audio-avrcp-target
bluetoothd[2283]: profiles/audio/avrcp.c:avrcp_connect() path
/org/bluez/hci0/dev_98_D6_F7_34_98_E8
bluetoothd[2283]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
bluetoothd[2283]: src/service.c:change_state() 0xcbde0: device
98:D6:F7:34:98:E8 profile audio-avrcp-target state changed: disconnected ->
connecting (0)
[Nexus 4]# bluetoothd[2283]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP:
connected to 98:D6:F7:34:98:E8
[12129.006225] input: 98:D6:F7:34:98:E8 as /devices/virtual/input/input13
[12129.028869] input_boost: touchboost_connect: connect to 98:D6:F7:34:98:E8
bluetoothd[2283]: profiles/audio/avctp.c:init_uinput() AVRCP: uinput
initialized for 98:D6:F7:34:98:E8
bluetoothd[2283]: profiles/audio/avrcp.c:controller_init() 0xc16d8 version
0x0103
bluetoothd[2283]: src/service.c:change_state() 0xcbde0: device
98:D6:F7:34:98:E8 profile audio-avrcp-target state changed: connecting ->
connected (0)
bluetoothd[2283]: src/device.c:device_profile_connected() audio-avrcp-target
Success (0)
bluetoothd[2283]: profiles/audio/player.c:media_player_controller_create()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/player0
bluetoothd[2283]: profiles/audio/avrcp.c:target_init() 0xccd78 version 0x0103
bluetoothd[2283]: src/service.c:change_state() 0xcbf20: device
98:D6:F7:34:98:E8 profile avrcp-controller state changed: disconnected ->
connected (0)
bluetoothd[2283]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected
( 38.837| 2.114) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.freedesktop.DBus.Introspectable found, skipping
( 38.862| 0.024) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface org.bluez.MediaPlayer1
found, skipping
( 38.877| 0.015) D: [pulseaudio][modules/bluetooth/bluez5-util.c:840
parse_interfaces_and_properties()] Unknown interface
org.freedesktop.DBus.Properties found, skipping
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
bluetoothd[2283]: profiles/audio/player.c:media_player_set_playlist_item() 0
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata() Title:
??? ????
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata() Artist:
???? ?????????
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata() Album:
????? ??? ?????
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata()
TrackNumber:
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata()
NumberOfTracks:
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata() Genre:
bluetoothd[2283]: profiles/audio/player.c:media_player_set_metadata() Duration:
243853
bluetoothd[2283]: profiles/audio/player.c:media_player_set_duration() 243853
bluetoothd[2283]: profiles/audio/player.c:media_player_set_position() 104976
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
bluetoothd[2283]: profiles/audio/player.c:media_player_set_duration() 243853
bluetoothd[2283]: profiles/audio/player.c:media_player_set_position() 104976
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
[Nexus 4]# ( 41.313| 2.435) I:
[pulseaudio][modules/module-suspend-on-idle.c:90 timeout_cb()] Sink auto_null
idle for too long, suspending ...
( 41.325| 0.012) D: [pulseaudio][pulsecore/sink.c:872 pa_sink_suspend()]
Suspend cause of sink auto_null is 0x0004, suspending
( 41.338| 0.012) I: [pulseaudio][pulsecore/core.c:281
pa_core_maybe_vacuum()] All sinks and sources are suspended, vacuuming memory
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received START_CMD
bluetoothd[2283]: profiles/audio/a2dp.c:start_ind() Sink 0xc9810: Start_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=2
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state
changed: OPEN -> STREAMING
bluetoothd[2283]: profiles/audio/source.c:source_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_CONNECTED ->
SOURCE_STATE_PLAYING
bluetoothd[2283]: profiles/audio/transport.c:transport_update_playing()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 State=TRANSPORT_STATE_IDLE Playing=1
bluetoothd[2283]: profiles/audio/transport.c:transport_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_IDLE ->
TRANSPORT_STATE_PENDING
( 43.963| 2.625) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1085
filter_cb()] Properties changed in transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
( 43.980| 0.017) D: [pulseaudio][modules/bluetooth/bluez5-util.c:178
pa_bluetooth_transport_set_state()] Transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 state changed from idle to playing
( 43.997| 0.017) D: [pulseaudio][pulsecore/card.c:76
pa_card_profile_set_available()] Setting card bluez_card.98_D6_F7_34_98_E8
profile a2dp_source to availability status yes
( 44.013| 0.016) D: [pulseaudio][pulsecore/device-port.c:80
pa_device_port_set_available()] bluetoothd[2283]:
profiles/audio/player.c:media_player_set_status() playing
Setting port phone-input to status yes
( 44.034| 0.020) D: [pulseaudio][pulsecore/core-subscribe.c:242
pa_subscription_post()] Dropped redundant event due to change event.
( 44.050| 0.016) D:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:739 transport_acquire()]
Acquiring transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
bluetoothd[2283]: profiles/audio/transport.c:media_owner_create() Owner
created: sender=:1.12
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=3
bluetoothd[2283]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0xc9810 locked
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=4
bluetoothd[2283]: profiles/audio/a2dp.c:setup_ref() 0xd2438: ref=1
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_unref() 0xcc840: ref=3
bluetoothd[2283]: profiles/audio/transport.c:media_request_create() Request
created: method=TryAcquire id=2
bluetoothd[2283]: profiles/audio/transport.c:media_owner_add() Owner :1.12
Request TryAcquire
bluetoothd[2283]: profiles/audio/transport.c:media_transport_set_owner()
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 Owner :1.12
bluetoothd[2283]: profiles/audio/player.c:media_player_set_duration() 243853
bluetoothd[2283]: profiles/audio/player.c:media_player_set_position() 105640
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() playing
bluetoothd[2283]: /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: fd(23) ready
( 44.179| 0.128) I:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:746 transport_acquire()]
bluetoothd[2283]: profiles/audio/transport.c:media_owner_remove() Owner :1.12
Request TryAcquire
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 acquired: fd
24bluetoothd[2283]: profiles/audio/transport.c:transport_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_PENDING ->
TRANSPORT_STATE_ACTIVE
( 44.222| 0.042) D:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:1990
handle_transport_state_change()] Resuming source bluez_source.98_D6_F7_34_98_E8
because its transport state changed to playingbluetoothd[2283]:
profiles/audio/a2dp.c:setup_unref() 0xd2438: ref=0
( 44.247| 0.025) D: [pulseaudio][pulsecore/source.c:789 pa_source_suspend()]
bluetoothd[2283]: profiles/audio/a2dp.c:setup_free() 0xd2438
Suspend cause of source bluez_source.98_D6_F7_34_98_E8 is 0x0000,
resumingbluetoothd[2283]: profiles/audio/avdtp.c:avdtp_unref() 0xcc840: ref=2
( 44.275| 0.027) I: [bluetooth][modules/bluetooth/module-bluez5-device.c:802
setup_stream()] Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 resuming
( 44.294| 0.019) D: [bluetooth][modules/bluetooth/module-bluez5-device.c:813
setup_stream()] Stream properly set up, we're ready to roll!
( 44.308| 0.014) D: [pulseaudio][modules/module-suspend-on-idle.c:114
restart()] Source bluez_source.98_D6_F7_34_98_E8 becomes idle, timeout in 5
seconds.
( 44.316| 0.007) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.334| 0.018) D: [null-sink][pulsecore/sink-input.c:1901
pa_sink_input_set_state_within_thread()] Requesting rewind due to uncorking
( 44.346| 0.011) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.358| 0.012) D: [pulseaudio][modules/module-suspend-on-idle.c:123
resume()] Sink auto_null becomes busy, resuming.
( 44.373| 0.015) D: [pulseaudio][pulsecore/sink.c:872 pa_sink_suspend()]
Suspend cause of sink auto_null is 0x0000, resuming
( 44.375| 0.002) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.397| 0.021) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.408| 0.010) D: [pulseaudio][modules/module-suspend-on-idle.c:128
resume()] Source bluez_source.98_D6_F7_34_98_E8 becomes busy, resuming.
( 44.408| 0.000) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.419| 0.011) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.446| 0.027) D: [pulseaudio][pulsecore/core-subscribe.c:242
pa_subscription_post()] Dropped redundant event due to change event.
( 44.447| 0.000) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.458| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.484| 0.026) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.487| 0.003) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.507| 0.020) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.517| 0.009) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.529| 0.011) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.545| 0.015) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 44.558| 0.013) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1085
filter_cb()] Properties changed in transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
( 44.558| 0.000) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 44.567| 0.008) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
â¦
( 56.419| 0.015) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 56.432| 0.012) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 56.444| 0.012) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 56.457| 0.012) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 56.465| 0.007) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 56.476| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 56.492| 0.016) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
( 56.517| 0.012) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 56.504| 0.012) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 56.544| 0.027) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]:
profiles/audio/player.c:media_player_set_duration() 243853
Memory block too large for pool: 29696 > 4096
( 56.555| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] bluetoothd[2283]:
profiles/audio/player.c:media_player_set_position() 117731
Could not peek into queue
( 56.576| 0.021) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
( 56.606| 0.008) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 56.597| 0.021) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 56.629| 0.023) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
â¦
( 59.413| 0.012) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.429| 0.016) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.443| 0.013) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
( 59.462| 0.019) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received
SUSPEND_CMD
( 59.483| 0.020) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]: profiles/audio/a2dp.c:suspend_ind()
Sink 0xc9810: Suspend_Ind
Memory block too large for pool: 29696 > 4096
( 59.508| 0.025) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]:
profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: STREAMING ->
OPEN
Memory block too large for pool: 29696 > 4096
bluetoothd[2283]: profiles/audio/source.c:source_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_PLAYING ->
SOURCE_STATE_CONNECTED
( 59.545| 0.037) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/transport.c:transport_update_playing()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 State=TRANSPORT_STATE_ACTIVE Playing=0
( 59.574| 0.015) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/transport.c:media_transport_remove_owner()
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 Owner :1.12
( 59.600| 0.025) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]:
profiles/audio/transport.c:media_owner_free() Owner :1.12
Memory block too large for pool: 29696 > 4096
( 59.559| 0.014) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] bluetoothd[2283]:
profiles/audio/transport.c:transport_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_ACTIVE ->
TRANSPORT_STATE_IDLE
Could not peek into queue
( 59.643| 0.042) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0xc9810
unlocked
( 59.657| 0.014) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.667| 0.009) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1085
filter_cb()] Properties changed in transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
( 59.679| 0.011) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.689| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.708| 0.018) D: [pulseaudio][modules/bluetooth/bluez5-util.c:178
pa_bluetooth_transport_set_state()] Transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 state changed from playing to idle
( 59.717| 0.009) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.729| 0.012) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.745| 0.015) D: [pulseaudio][pulsecore/card.c:76
pa_card_profile_set_available()] Setting card bluez_card.98_D6_F7_34_98_E8
profile a2dp_source to availability status unknown
( 59.757| 0.012) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.768| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.788| 0.020) D:
[pulseaudio][modules/bluetooth/module-bluetooth-policy.c:199
profile_available_hook_callback()] Setting card 'bluez_card.98_D6_F7_34_98_E8'
to profile 'off'
( 59.797| 0.009) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.810| 0.013) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.828| 0.017) D: [pulseaudio][modules/module-rescue-streams.c:254
find_evacuation_source()] No evacuation source found
( 59.828| 0.017) D: [pulseaudio][modules/module-rescue-streams.c:254
find_evacuation_source()] No evacuation source found.
( 59.836| 0.008) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.848| 0.011) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.873| 0.024) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.876| 0.003) D: [pulseaudio][modules/module-suspend-on-idle.c:114
restart()] Source bluez_source.98_D6_F7_34_98_E8 becomes idle, timeout in 5
seconds.
( 59.876| 0.000) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.898| 0.022) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.919| 0.020) D: [pulseaudio][modules/module-suspend-on-idle.c:114
restart()] Source bluez_source.98_D6_F7_34_98_E8 becomes idle, timeout in 5
seconds.
( 59.919| 0.000) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.929| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.948| 0.018) I: [pulseaudio][pulsecore/source-output.c:621
source_output_free()] Freeing output 0 "Loopback to Dummy Output"
( 59.957| 0.008) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 59.967| 0.010) I: [null-sink][modules/module-loopback.c:471
sink_input_pop_cb()] Could not peek into queue
( 59.994| 0.026) D: [pulseaudio][modules/module-suspend-on-idle.c:112
restart()] Sink auto_null becomes idle, timeout in 5 seconds.
( 60.007| 0.012) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 60.007| 0.000) D: [null-sink][modules/module-null-sink.c:142
process_rewind()] Requested to rewind 352800 bytes.
( 60.027| 0.020) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 60.039| 0.011) D: [pulseaudio][modules/module-suspend-on-idle.c:112
restart()] Sink auto_null becomes idle, timeout in 5 seconds.
( 60.055| 0.016) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 60.067| 0.011) D: [pulseaudio][pulsecore/core.c:265
pa_core_maybe_vacuum()] Hmm, no streams around, trying to vacuum.
( 60.076| 0.008) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 60.088| 0.011) I: [pulseaudio][pulsecore/sink-input.c:728
sink_input_free()] Freeing input 0 "Loopback from Nexus 4"
( 60.104| 0.016) D: [pulseaudio][pulsecore/core-subscribe.c:234
pa_subscription_post()] Dropped redundant event due to remove event.
( 60.117| 0.012) D:
[bluetooth][modules/bluetooth/module-bluez5-device.c:1504 thread_func()] IO
thread shutdown requested, stopping cleanly
( 60.130| 0.013) D: [bluetooth][modules/bluetooth/module-bluez5-device.c:758
transport_release()] Releasing transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
( 60.145| 0.014) I: [bluetooth][modules/bluetooth/bluez5-util.c:273
bluez5_transport_release_cb()] Transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 auto-released by BlueZ or already
released
( 60.162| 0.017) D: [bluetooth][modules/bluetooth/module-bluez5-device.c:730
teardown_stream()] Audio stream torn down
( 60.173| 0.011) D:
[bluetooth][modules/bluetooth/module-bluez5-device.c:1517 thread_func()] IO
thread shutting down
( 60.185| 0.011) I: [pulseaudio][pulsecore/source.c:663 source_free()]
Freeing source 1 "bluez_source.98_D6_F7_34_98_E8"
( 60.196| 0.011) D: [pulseaudio][pulsecore/core-subscribe.c:242
pa_subscription_post()] Dropped redundant event due to change event.
( 60.208| 0.012) I: [pulseaudio][pulsecore/card.c:284 pa_card_set_profile()]
Changed profile of card 0 "bluez_card.98_D6_F7_34_98_E8" to off
( 60.222| 0.013) D: [pulseaudio][pulsecore/device-port.c:80
pa_device_port_set_available()] Setting port phone-input to status unknown
( 60.234| 0.012) D: [pulseaudio][pulsecore/core-subscribe.c:242
pa_subscription_post()] Dropped redundant event due to change event.
( 60.247| 0.012) I: [pulseaudio][pulsecore/module.c:222 pa_module_free()]
Unloading "module-loopback" (index: #21).
( 60.258| 0.010) I: [pulseaudio][pulsecore/module.c:232 pa_module_free()]
Unloaded "module-loopback" (index: #21).
[Nexus 4]# ex( 65.044| 4.785) I:
[pulseaudio][modules/module-suspend-on-idle.c:90 timeout_cb()] Sink auto_null
idle for too long, suspending ...
( 65.056| 0.012) D: [pulseaudio][pulsecore/sink.c:872 pa_sink_suspend()]
Suspend cause of sink auto_null is 0x0004, suspending
( 65.069| 0.012) D: [pulseaudio][pulsecore/core.c:265
pa_core_maybe_vacuum()] Hmm, no streams around, trying to vacuum.
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received START_CMD
bluetoothd[2283]: profiles/audio/a2dp.c:start_ind() Sink 0xc9810: Start_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=3
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state
changed: OPEN -> STREAMING
bluetoothd[2283]: profiles/audio/source.c:source_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_CONNECTED ->
SOURCE_STATE_PLAYING
bluetoothd[2283]: profiles/audio/transport.c:transport_update_playing()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 State=TRANSPORT_STATE_IDLE Playing=1
bluetoothd[2283]: profiles/audio/transport.c:transport_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_IDLE ->
TRANSPORT_STATE_PENDING
( 85.968| 20.899) D: [pulseaudio][modules/bluetooth/bluez5-util.c:1085
filter_cb()] Properties changed in transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
( 85.985| 0.017) D: [pulseaudio][modules/bluetooth/bluez5-util.c:178
pa_bluetooth_transport_set_state()] Transport
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 state changed from idle to playing
( 86.003| 0.017) D: [pulseaudio][pulsecore/card.c:76
pa_card_profile_set_available()] Setting card bluez_card.98_D6_F7_34_98_E8
profile a2dp_source to availability status yes
( 86.020| 0.016) D:
[pulseaudio][modules/bluetooth/module-bluetooth-policy.c:199
profile_available_hook_callback()] Setting card 'bluez_card.98_D6_F7_34_98_E8'
to profile 'a2dp_source'
( 86.037| 0.016) D:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:739 transport_acquire()]
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() playing
Acquiring transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1
bluetoothd[2283]: profiles/audio/transport.c:media_owner_create() Owner
created: sender=:1.12
bluetoothd[2283]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0xc9810 locked
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=4
bluetoothd[2283]: profiles/audio/a2dp.c:setup_ref() 0xd2438: ref=1
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_unref() 0xcc840: ref=3
bluetoothd[2283]: profiles/audio/transport.c:media_request_create() Request
created: method=TryAcquire id=3
bluetoothd[2283]: profiles/audio/transport.c:media_owner_add() Owner :1.12
Request TryAcquire
bluetoothd[2283]: profiles/audio/transport.c:media_transport_set_owner()
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 Owner :1.12
bluetoothd[2283]: profiles/audio/player.c:media_player_set_duration() 243853
bluetoothd[2283]: profiles/audio/player.c:media_player_set_position() 118266
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() playing
( 86.163| 0.126) I:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:746 transport_acquire()]
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 acquired: fd
18bluetoothd[2283]: profiles/audio/transport.c:media_owner_remove() Owner :1.12
Request TryAcquire
( 86.187| 0.024) I:
[pulseaudio][modules/bluetooth/module-bluez5-device.c:1258 transport_config()]
bluetoothd[2283]: profiles/audio/transport.c:transport_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_PENDING ->
TRANSPORT_STATE_ACTIVE
SBC parameters: allocation=0, subbands=1, blocks=3, bitpool=2bluetoothd[2283]:
profiles/audio/a2dp.c:setup_unref() 0xd2438: ref=0
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluetoothd[2283]: profiles/audio/a2dp.c:setup_free() 0xd2438
Created source 2 "bluez_source.98_D6_F7_34_98_E8" with sample spec s16le 2ch
44100Hz and channel map front-left,front-rightbluetoothd[2283]:
profiles/audio/avdtp.c:avdtp_unref() 0xcc840: ref=2
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluetooth.protocol = "a2dp_source"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.description = "Nexus 4"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.string = "98:D6:F7:34:98:E8"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.api = "bluez"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.class = "sound"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.bus = "bluetooth"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.form_factor = "phone"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluez.path = "/org/bluez/hci0/dev_98_D6_F7_34_98_E8"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluez.class = "0x5a020c"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
bluez.alias = "Nexus 4"
( 86.227| 0.039) I: [pulseaudio][pulsecore/source.c:347 pa_source_new()]
device.icon_name = "audio-card-bluetooth"
( 86.379| 0.152) D:
[bluetooth][modules/bluetooth/module-bluez5-device.c:1334 thread_func()] IO
Thread starting up
( 86.435| 0.055) I: [bluetooth][pulsecore/core-util.c:839
pa_make_realtime()] Failed to acquire real-time scheduling: No such file or
directory
( 86.452| 0.016) I: [bluetooth][modules/bluetooth/module-bluez5-device.c:802
setup_stream()] Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 resuming
( 86.467| 0.015) D: [bluetooth][modules/bluetooth/module-bluez5-device.c:813
setup_stream()] Stream properly set up, we're ready to roll!
( 86.484| 0.016) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 86.515| 0.030) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
â¦
( 91.840| 0.030) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 91.875| 0.034) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 91.895| 0.020) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 91.919| 0.024) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
( 91.949| 0.029) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 91.990| 0.040) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]:
profiles/audio/player.c:media_player_set_duration() 243853
Memory block too large for pool: 29696 > 4096
( 92.026| 0.036) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/player.c:media_player_set_position() 123671
( 92.062| 0.035) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() paused
( 92.093| 0.031) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
â¦
( 94.859| 0.028) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 94.879| 0.019) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()]
bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
Memory block too large for pool: 29696 > 4096
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received SUSPEND_CMD
( 94.922| 0.043) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
bluetoothd[2283]: profiles/audio/a2dp.c:suspend_ind() Sink 0xc9810: Suspend_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state
changed: STREAMING -> OPEN
( 94.964| 0.042) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/source.c:source_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_PLAYING ->
SOURCE_STATE_CONNECTED
( 95.015| 0.051) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/transport.c:transport_update_playing()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 State=TRANSPORT_STATE_ACTIVE Playing=0
( 95.083| 0.067) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/transport.c:media_transport_remove_owner()
Transport /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 Owner :1.12
( 95.135| 0.052) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/transport.c:media_owner_free() Owner :1.12
( 95.165| 0.029) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/transport.c:transport_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_ACTIVE ->
TRANSPORT_STATE_IDLE
( 95.205| 0.040) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0xc9810
unlocked
( 95.242| 0.036) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
â¦
( 98.355| 0.019) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 98.385| 0.030) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
[Nexus 4]# bluetoothd[2283]: profiles/audio/avdtp.c:session_cb()
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received START_CMD
bluetoothd[2283]: profiles/audio/a2dp.c:start_ind() Sink 0xc9810: Start_Ind
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_ref() 0xcc840: ref=3
bluetoothd[2283]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state
changed: OPEN -> STREAMING
bluetoothd[2283]: profiles/audio/source.c:source_set_state() State changed
/org/bluez/hci0/dev_98_D6_F7_34_98_E8: SOURCE_STATE_CONNECTED ->
SOURCE_STATE_PLAYING
bluetoothd[2283]: profiles/audio/transport.c:transport_update_playing()
/org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1 State=TRANSPORT_STATE_IDLE Playing=1
bluetoothd[2283]: profiles/audio/transport.c:transport_set_state() State
changed /org/bluez/hci0/dev_98_D6_F7_34_98_E8/fd1: TRANSPORT_STATE_IDLE ->
TRANSPORT_STATE_PENDING
bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() playing
( 113.582| 15.196) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 113.610| 0.028) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]:
profiles/audio/player.c:media_player_set_duration() 243853
Memory block too large for pool: 29696 > 4096
( 113.649| 0.038) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] bluetoothd[2283]:
profiles/audio/player.c:media_player_set_position() 123998
Memory block too large for pool: 29696 > 4096
( 113.685| 0.036) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 >
4096bluetoothd[2283]: profiles/audio/player.c:media_player_set_status() playing
( 113.712| 0.026) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 113.745| 0.033) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
( 113.775| 0.029) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
â¦
( 122.199| 0.019) D: [bluetooth][pulsecore/memblock.c:363
pa_memblock_new_pool()] Memory block too large for pool: 29696 > 4096
[Nexus 4]# exit
Agent unregistered
[DEL] Controller 00:50:43:21:EE:DF BlueZ 5.30 [default]
bluetoothd[2283]: src/agent.c:agent_disconnect() Agent :1.14 disconnected
pulse@OpenWrt:/$ bluetoothd[2283]: src/agent.c:remove_default_agent() Default
agent cleared
bluetoothd[2283]: src/agent.c:agent_destroy() agent :1.14
bluetoothd[2283]: src/agent.c:agent_unref() 0xc4bd0: ref=0
pulse@OpenWrt:/$
pulse@OpenWrt:/$
pulse@OpenWrt:/$ pacmd list
Daemon not responding.
pulse@OpenWrt:/$
pulse@OpenWrt:/$ pacmd list
Daemon not responding.
pulse@OpenWrt:/$ pacmd list
Daemon not responding.
pulse@OpenWrt:/$
pulse@OpenWrt:/$
pulse@OpenWrt:/$ pacmd list
Daemon not responding.
pulse@OpenWrt:/$
pulse@OpenWrt:/$ pacmd list
Daemon not responding.
_______________________________________________
pulseaudio-discuss mailing list
[email protected]
http://lists.freedesktop.org/mailman/listinfo/pulseaudio-discuss
