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.