[BUG Report] Missing HCI event REMOTE_NAME Causing Subsequent Bluetooth Connection Failures

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi All,

We round a possible bug during 2 consecutive rounds of RFCOMM connections in kernel 6.7.9:

(1) During the connection process, the host sends a HCI command REMOTE_NAME_REQ to request the name of the remote device. Normally, the controller responds to it with a HCI event COMMAND_STATUS(REMOTE_NAME_REQ) and then sends a HCI event REMOTE_NAME containing the remote device's name.

(2) Upon receiving COMMAND_STATUS(REMOTE_NAME_REQ), function hci_cmd_status_evt(net/bluetooth/hci_event.c:4356) is called, which in turn invokes function handle_cmd_cnt_and_timer to clear the timer.

(3) Since the timer has been clear, the host indefinitely waits for the HCI event REMOTE_NAME. If the controller fails to send this packet, the only recourse is for the user to manually terminate the connection process.

(4.1) Because the connection state is only set to CONNECTED upon receiving the HCI event REMOTE_NAME (hci_remote_name_evt ->hci_check_pending_name->mgmt_device_connected), the remote device remains unconnected in (3).

(4.2) We tried to reconnect for multiple times, but host is unable to send the HCI command CREATE_CONNECTION, resulting in subsequent failures to establish a connection with the device.

We believe it might be necessary to set a timeout while waiting for the HCI event REMOTE_NAME.

Thank you for reading this. Appreciating for any possible reply.

Attachment 1: Log file containing HCI packet contents.
Attachment 2: ftrace records of bluetooth and rfcomm modules.

Best Wishes,
Yuxuan Hu
================================================
                    Round 0
================================================
[QServer] begin connect_bt()
[ 1620.628969] -> [CMD] 05 04 0D A8 25 D1 EB 27 B8 18 CC 02 00 00 00 01 
[ 1620.629393] <- [EVT] 0F 04 00 01 05 04 
[ 1620.630742] <- [EVT] 12 08 00 A8 25 D1 EB 27 B8 01 
[ 1620.632746] <- [EVT] 03 0B 00 0B 00 A8 25 D1 EB 27 B8 01 00 
[ 1620.633480] -> [CMD] 1B 04 02 0B 00 
[ 1620.634774] <- [EVT] 1B 03 0B 00 05 
[ 1620.651784] <- [ACL] 0B 20 0A 00 06 00 01 00 0A 01 02 00 02 00 
[ 1620.652394] <- [EVT] 0F 04 00 01 1B 04 
[ 1620.652742] -> [CMD] 1A 0C 01 00 
[ 1620.653863] <- [EVT] 0B 0B 00 0B 00 BF FE CF FE DB FF 7B 87 
[ 1620.654876] <- [EVT] 0E 04 01 1A 0C 00 
[ 1620.655218] -> [CMD] 1C 04 03 0B 00 01 
[ 1620.655584] <- [EVT] 0E 04 01 1A 0C 00 
[ 1620.704819] <- [EVT] 0F 04 00 01 1C 04 
[ 1620.706780] <- [EVT] 23 0D 00 0B 00 01 02 0B 00 00 00 00 00 00 00 
[ 1620.706902] -> [CMD] 19 04 0A A8 25 D1 EB 27 B8 02 00 00 00 
[ 1620.707791] <- [EVT] 0F 04 00 01 19 04 
[QServer] Child terminated by signal Killed

================================================
                    Round 1
================================================
Device is not connected
[QServer] begin connect_bt()
[QServer] Child terminated by signal Killed

================================================
                    Round 2
================================================
Device is not connected
[QServer] begin connect_bt()
[QServer] Child terminated by signal Killed
root@hyx-QiTianM540-A739:/sys/kernel/debug/tracing# cat trace
# tracer: function
#
# entries-in-buffer/entries-written: 540/540   #P:2
#
#                                _-----=> irqs-off/BH-disabled
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| / _-=> migrate-disable
#                              |||| /     delay
#           TASK-PID     CPU#  |||||  TIMESTAMP  FUNCTION
#              | |         |   |||||     |         |
         QServer-367     [000] ...1.  1620.629830: bt_sock_create <-__sock_create
         QServer-367     [000] ...2.  1620.629834: rfcomm_sock_create <-bt_sock_create
         QServer-367     [000] ...2.  1620.629835: bt_sock_alloc <-rfcomm_sock_create
         QServer-367     [000] ...2.  1620.629883: rfcomm_dlc_alloc <-rfcomm_sock_create
         QServer-367     [000] ...2.  1620.629902: bt_sock_link <-rfcomm_sock_create
         QServer-367     [000] ...1.  1620.630000: rfcomm_sock_connect <-__sys_connect
         QServer-367     [000] ...1.  1620.630002: rfcomm_dlc_open <-rfcomm_sock_connect
         QServer-367     [000] ...1.  1620.630037: bt_sock_create <-__sock_create
         QServer-367     [000] ...2.  1620.630039: l2cap_sock_create <-bt_sock_create
         QServer-367     [000] ...2.  1620.630040: bt_sock_alloc <-l2cap_sock_create
         QServer-367     [000] ...2.  1620.630085: l2cap_chan_create <-l2cap_sock_create
         QServer-367     [000] ...2.  1620.630108: l2cap_chan_hold <-l2cap_sock_create
         QServer-367     [000] ...2.  1620.630109: l2cap_chan_set_defaults <-l2cap_sock_create
         QServer-367     [000] ...2.  1620.630110: bt_sock_link <-l2cap_sock_create
         QServer-367     [000] ...1.  1620.630113: l2cap_sock_bind <-kernel_bind
         QServer-367     [000] ...1.  1620.630114: l2cap_add_psm <-l2cap_sock_bind
         QServer-367     [000] ...1.  1620.630134: l2cap_sock_connect <-kernel_connect
         QServer-367     [000] ...1.  1620.630136: l2cap_chan_connect <-l2cap_sock_connect
         QServer-367     [000] ...1.  1620.630137: hci_get_route <-l2cap_chan_connect
         QServer-367     [000] ...1.  1620.630139: hci_connect_acl <-l2cap_chan_connect
         QServer-367     [000] ...1.  1620.630252: hci_conn_add <-hci_connect_acl
         QServer-367     [000] ...1.  1620.630278: hci_conn_init_sysfs <-hci_conn_add
         QServer-367     [000] ...1.  1620.630282: hci_acl_create_connection <-hci_connect_acl
         QServer-367     [000] ...1.  1620.630283: hci_inquiry_cache_lookup <-hci_acl_create_connection
         QServer-367     [000] ...1.  1620.630284: hci_send_cmd <-hci_acl_create_connection
         QServer-367     [000] ...1.  1620.630285: hci_prepare_cmd <-hci_send_cmd
         QServer-367     [000] ...1.  1620.630368: l2cap_conn_add <-l2cap_chan_connect
         QServer-367     [000] ...1.  1620.630370: hci_chan_create <-l2cap_conn_add
    kworker/u7:1-325     [001] ...1.  1620.630376: hci_cmd_work <-process_one_work
    kworker/u7:1-325     [001] ...1.  1620.630404: hci_req_status_pend <-hci_cmd_work
    kworker/u7:1-325     [001] ...1.  1620.630405: hci_send_frame <-hci_cmd_work
    kworker/u7:1-325     [001] ...1.  1620.630406: hci_send_to_monitor <-hci_send_frame
         QServer-367     [000] ...1.  1620.630413: __l2cap_chan_add <-l2cap_chan_connect
         QServer-367     [000] ...1.  1620.630415: l2cap_sock_state_change_cb <-l2cap_chan_connect
         QServer-367     [000] ...1.  1620.630416: l2cap_sock_get_sndtimeo_cb <-l2cap_chan_connect
         QServer-367     [000] ...1.  1620.630420: bt_sock_wait_state <-l2cap_sock_connect
         QServer-367     [000] ...1.  1620.630423: bt_sock_wait_state <-rfcomm_sock_connect
     kworker/1:2-300     [001] ...1.  1620.630846: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [001] ...1.  1620.631103: hci_rx_work <-process_one_work
    kworker/u7:1-325     [001] ...1.  1620.631104: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [001] ...1.  1620.631104: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [001] ...1.  1620.631134: hci_cmd_status_evt <-hci_event_packet
    kworker/u7:1-325     [001] ...1.  1620.631135: hci_cs_create_conn <-hci_cmd_status_evt
    kworker/u7:1-325     [001] ...1.  1620.631135: hci_sent_cmd_data <-hci_cs_create_conn
    kworker/u7:1-325     [001] ...1.  1620.631137: hci_req_cmd_complete <-hci_cmd_status_evt
     kworker/1:2-300     [001] ...1.  1620.632197: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [000] ...1.  1620.632215: hci_rx_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.632217: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.632218: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.632249: hci_role_change_evt <-hci_event_packet
     kworker/1:2-300     [001] ...1.  1620.634196: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [000] ...1.  1620.634214: hci_rx_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.634216: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.634216: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.634246: hci_conn_complete_evt <-hci_event_packet
    kworker/u7:1-325     [000] ...1.  1620.634247: hci_conn_set_handle <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634263: hci_debugfs_create_conn <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634330: hci_conn_add_sysfs <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634670: hci_send_cmd <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634670: hci_prepare_cmd <-hci_send_cmd
    kworker/u7:1-325     [000] ...1.  1620.634689: hci_update_scan <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634690: hci_cmd_sync_submit <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634712: hci_sco_setup <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634713: hci_conn_check_pending <-hci_conn_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.634718: hci_cmd_sync_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.634719: update_scan_sync <-hci_cmd_sync_work
    kworker/u7:1-325     [000] ...1.  1620.634719: hci_update_scan_sync <-hci_cmd_sync_work
    kworker/u7:1-325     [000] ...1.  1620.634720: mgmt_powering_down <-hci_update_scan_sync
    kworker/u7:1-325     [000] ...1.  1620.634720: mgmt_pending_find <-mgmt_powering_down
    kworker/u7:1-325     [000] ...1.  1620.634721: __hci_cmd_sync_sk <-hci_update_scan_sync
    kworker/u7:1-325     [000] ...1.  1620.634721: hci_req_init <-__hci_cmd_sync_sk
    kworker/u7:0-323     [001] ...1.  1620.634835: hci_cmd_work <-process_one_work
    kworker/u7:0-323     [001] ...1.  1620.634846: hci_req_status_pend <-hci_cmd_work
    kworker/u7:0-323     [001] ...1.  1620.634846: hci_send_frame <-hci_cmd_work
    kworker/u7:0-323     [001] ...1.  1620.634847: hci_send_to_monitor <-hci_send_frame
     kworker/1:2-300     [001] ...1.  1620.636210: hci_recv_frame <-h4_recv_buf
    kworker/u7:2-368     [001] ...1.  1620.636217: hci_rx_work <-process_one_work
    kworker/u7:2-368     [001] ...1.  1620.636217: hci_send_to_monitor <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.636218: hci_event_packet <-hci_rx_work
     kworker/1:2-300     [001] ...1.  1620.653213: hci_recv_frame <-h4_recv_buf
    kworker/u7:2-368     [001] ...1.  1620.653228: hci_rx_work <-process_one_work
    kworker/u7:2-368     [001] ...1.  1620.653229: hci_send_to_monitor <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.653231: hci_conn_enter_active_mode <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.653232: l2cap_recv_acldata <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.653233: l2cap_recv_frame <-hci_rx_work
     kworker/1:2-300     [001] ...1.  1620.653755: hci_recv_frame <-h4_recv_buf
    kworker/u7:2-368     [001] ...1.  1620.653765: hci_rx_work <-process_one_work
    kworker/u7:2-368     [001] ...1.  1620.653766: hci_send_to_monitor <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.653767: hci_event_packet <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.653800: hci_cmd_status_evt <-hci_event_packet
    kworker/u7:2-368     [001] ...1.  1620.653801: hci_cs_read_remote_features <-hci_cmd_status_evt
    kworker/u7:2-368     [001] ...1.  1620.653803: hci_req_cmd_complete <-hci_cmd_status_evt
    kworker/u7:2-368     [001] ...1.  1620.653820: hci_cmd_work <-process_one_work
    kworker/u7:2-368     [001] ...1.  1620.653839: hci_req_status_pend <-hci_cmd_work
    kworker/u7:2-368     [001] ...1.  1620.653839: hci_send_frame <-hci_cmd_work
    kworker/u7:2-368     [001] ...1.  1620.653840: hci_send_to_monitor <-hci_send_frame
     kworker/1:2-300     [001] ...1.  1620.655241: hci_recv_frame <-h4_recv_buf
    kworker/u7:2-368     [001] ...1.  1620.655252: hci_rx_work <-process_one_work
    kworker/u7:2-368     [001] ...1.  1620.655253: hci_send_to_monitor <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.655254: hci_event_packet <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.655276: hci_remote_features_evt <-hci_event_packet
    kworker/u7:2-368     [001] ...1.  1620.655277: hci_send_cmd <-hci_remote_features_evt
    kworker/u7:2-368     [001] ...1.  1620.655277: hci_prepare_cmd <-hci_send_cmd
    kworker/u7:2-368     [001] ...1.  1620.655314: hci_cmd_work <-process_one_work
     kworker/1:0-369     [001] ...1.  1620.656244: hci_recv_frame <-h4_recv_buf
    kworker/u7:2-368     [001] ...1.  1620.656255: hci_rx_work <-process_one_work
    kworker/u7:2-368     [001] ...1.  1620.656256: hci_send_to_monitor <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.656256: hci_event_packet <-hci_rx_work
    kworker/u7:2-368     [001] ...1.  1620.656286: hci_cmd_complete_evt <-hci_event_packet
    kworker/u7:2-368     [001] ...1.  1620.656287: hci_cc_write_scan_enable <-hci_cmd_complete_evt
    kworker/u7:2-368     [001] ...1.  1620.656287: hci_sent_cmd_data <-hci_cc_write_scan_enable
    kworker/u7:2-368     [001] ...1.  1620.656289: hci_req_cmd_complete <-hci_cmd_complete_evt
    kworker/u7:2-368     [001] ...1.  1620.656293: hci_cmd_sync_complete <-hci_event_packet
    kworker/u7:2-368     [001] ...1.  1620.656309: hci_cmd_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.656313: bt_to_errno <-__hci_cmd_sync_sk
    kworker/u7:2-368     [001] ...1.  1620.656322: hci_req_status_pend <-hci_cmd_work
    kworker/u7:2-368     [001] ...1.  1620.656322: hci_send_frame <-hci_cmd_work
    kworker/u7:2-368     [001] ...1.  1620.656323: hci_send_to_monitor <-hci_send_frame
     kworker/1:2-300     [001] ...1.  1620.657024: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [000] ...1.  1620.657053: hci_rx_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.657054: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.657055: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.657089: hci_cmd_complete_evt <-hci_event_packet
    kworker/u7:1-325     [000] ...1.  1620.657090: hci_cc_write_scan_enable <-hci_cmd_complete_evt
    kworker/u7:1-325     [000] ...1.  1620.657090: hci_sent_cmd_data <-hci_cc_write_scan_enable
    kworker/u7:1-325     [000] ...1.  1620.657092: hci_req_cmd_complete <-hci_cmd_complete_evt
     kworker/1:2-300     [001] ...1.  1620.706250: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [000] ...1.  1620.706310: hci_rx_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.706312: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.706313: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.706358: hci_cmd_status_evt <-hci_event_packet
    kworker/u7:1-325     [000] ...1.  1620.706359: hci_cs_read_remote_ext_features <-hci_cmd_status_evt
    kworker/u7:1-325     [000] ...1.  1620.706360: hci_req_cmd_complete <-hci_cmd_status_evt
     kworker/1:2-300     [001] ...1.  1620.708217: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [000] ...1.  1620.708245: hci_rx_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.708246: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.708247: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.708276: hci_remote_ext_features_evt <-hci_event_packet
    kworker/u7:1-325     [000] ...1.  1620.708277: hci_inquiry_cache_lookup <-hci_remote_ext_features_evt
    kworker/u7:1-325     [000] ...1.  1620.708278: hci_send_cmd <-hci_remote_ext_features_evt
    kworker/u7:1-325     [000] ...1.  1620.708278: hci_prepare_cmd <-hci_send_cmd
    kworker/u7:1-325     [000] ...1.  1620.708317: hci_cmd_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.708336: hci_req_status_pend <-hci_cmd_work
    kworker/u7:1-325     [000] ...1.  1620.708337: hci_send_frame <-hci_cmd_work
    kworker/u7:1-325     [000] ...1.  1620.708338: hci_send_to_monitor <-hci_send_frame
     kworker/1:2-300     [001] .N.1.  1620.709223: hci_recv_frame <-h4_recv_buf
    kworker/u7:1-325     [000] ...1.  1620.709616: hci_rx_work <-process_one_work
    kworker/u7:1-325     [000] ...1.  1620.709617: hci_send_to_monitor <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.709618: hci_event_packet <-hci_rx_work
    kworker/u7:1-325     [000] ...1.  1620.709652: hci_cmd_status_evt <-hci_event_packet
    kworker/u7:1-325     [000] ...1.  1620.709653: hci_cs_remote_name_req <-hci_cmd_status_evt
    kworker/u7:1-325     [000] ...1.  1620.709654: hci_req_cmd_complete <-hci_cmd_status_evt
         QServer-367     [000] ...1.  1628.631276: rfcomm_sock_release <-sock_close
         QServer-367     [000] ...1.  1628.631279: rfcomm_sock_shutdown <-rfcomm_sock_release
         QServer-367     [000] ...1.  1628.631280: __rfcomm_sock_close <-rfcomm_sock_shutdown
         QServer-367     [000] ...1.  1628.631281: rfcomm_dlc_close <-__rfcomm_sock_close
         QServer-367     [000] ...1.  1628.631282: __rfcomm_dlc_close <-rfcomm_dlc_close
         QServer-367     [000] ...1.  1628.631283: rfcomm_sk_state_change <-__rfcomm_dlc_close
         QServer-367     [000] ...1.  1628.631286: bt_sock_unlink <-rfcomm_sock_release
         QServer-367     [000] ...1.  1628.631287: rfcomm_sock_destruct <-__sk_destruct
         QServer-367     [000] ...1.  1628.631288: rfcomm_dlc_free <-__sk_destruct
         python3-371     [000] ..s1.  1630.673178: rfcomm_session_timeout <-call_timer_fn
        krfcommd-204     [001] ...1.  1630.673205: rfcomm_process_sessions <-rfcomm_run
        krfcommd-204     [001] ...1.  1630.673209: l2cap_sock_sendmsg <-sock_sendmsg
      bluetoothd-247     [001] ...1.  1630.736472: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736477: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736478: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736479: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736479: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736480: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736481: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736482: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736483: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736544: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736545: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736545: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736546: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736547: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736547: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736548: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736548: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736549: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736627: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736628: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736629: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736631: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736632: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736632: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736633: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736634: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.736634: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737399: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737401: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737402: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737402: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737403: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737404: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737404: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737405: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737405: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737447: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737448: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737449: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737450: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737450: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737451: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737451: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737452: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737452: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737726: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737728: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737729: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737731: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737731: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737732: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737733: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737733: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.737734: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738257: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738259: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738259: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738260: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738261: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738261: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738262: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738266: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738268: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738320: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738322: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738322: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738323: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738323: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738324: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738325: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738325: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738326: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738435: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738436: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738437: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738438: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738439: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738440: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738441: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738441: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738442: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738822: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738824: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738825: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738825: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738826: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738826: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738827: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738828: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738828: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738889: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738890: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738890: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738891: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738892: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738892: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738893: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738893: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.738894: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739019: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739021: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739022: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739023: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739024: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739024: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739025: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739026: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739026: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739832: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739834: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739835: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739835: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739836: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739837: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739837: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739837: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739838: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739882: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739883: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739884: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739884: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739885: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739885: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739886: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739886: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739887: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739925: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739926: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739927: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739929: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739929: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739930: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739931: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739931: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1630.739932: bt_sock_poll <-sock_poll
         QServer-372     [001] ...1.  1633.669049: bt_sock_create <-__sock_create
         QServer-372     [001] ...2.  1633.669052: rfcomm_sock_create <-bt_sock_create
         QServer-372     [001] ...2.  1633.669053: bt_sock_alloc <-rfcomm_sock_create
         QServer-372     [001] ...2.  1633.669092: rfcomm_dlc_alloc <-rfcomm_sock_create
         QServer-372     [001] ...2.  1633.669106: bt_sock_link <-rfcomm_sock_create
         QServer-372     [001] ...1.  1633.669215: rfcomm_sock_connect <-__sys_connect
         QServer-372     [001] ...1.  1633.669216: rfcomm_dlc_open <-rfcomm_sock_connect
         QServer-372     [001] ...1.  1633.669220: bt_sock_wait_state <-rfcomm_sock_connect
         QServer-372     [001] ...1.  1641.670208: rfcomm_sock_release <-sock_close
         QServer-372     [001] ...1.  1641.670211: rfcomm_sock_shutdown <-rfcomm_sock_release
         QServer-372     [001] ...1.  1641.670212: __rfcomm_sock_close <-rfcomm_sock_shutdown
         QServer-372     [001] ...1.  1641.670213: rfcomm_dlc_close <-__rfcomm_sock_close
         QServer-372     [001] ...1.  1641.670214: __rfcomm_dlc_close <-rfcomm_dlc_close
         QServer-372     [001] ...1.  1641.670215: __rfcomm_dlc_disconn <-rfcomm_dlc_close
         QServer-372     [001] ...1.  1641.670218: l2cap_sock_sendmsg <-sock_sendmsg
         QServer-372     [001] ...1.  1641.670220: bt_sock_unlink <-rfcomm_sock_release
         QServer-372     [001] ...1.  1641.670221: rfcomm_sock_destruct <-__sk_destruct
      bluetoothd-247     [001] ...1.  1643.756324: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756329: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756329: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756330: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756331: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756331: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756332: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756333: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756333: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756376: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756377: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756377: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756377: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756378: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756378: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756378: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756379: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756379: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756406: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756407: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756407: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756408: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756409: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756409: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756409: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756410: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756410: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756621: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756622: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756623: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756623: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756624: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756624: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756624: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756624: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756625: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756647: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756648: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756648: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756648: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756649: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756649: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756649: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756649: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756650: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756942: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756943: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756943: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756944: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756945: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756945: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756945: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756946: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.756946: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757089: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757091: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757091: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757091: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757091: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757092: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757092: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757092: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757093: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757115: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757115: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757116: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757116: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757116: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757117: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757117: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757117: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757117: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757520: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757521: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757521: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757522: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757522: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757523: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757523: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757523: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757524: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757795: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757796: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757796: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757797: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757797: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757797: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757798: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757798: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757798: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757821: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757821: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757821: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757822: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757822: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757822: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757823: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757823: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757823: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757847: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757847: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757848: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757849: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757849: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757850: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757850: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757850: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757850: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.757999: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758000: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758000: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758000: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758001: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758001: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758001: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758002: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758002: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758023: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758024: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758024: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758024: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758024: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758025: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758025: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758025: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758026: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758048: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758049: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758049: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758050: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758050: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758051: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758051: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758051: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758052: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758367: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758369: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758369: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758369: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758370: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758370: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758370: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758370: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758371: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758393: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758394: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758394: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758394: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758395: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758395: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758395: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758396: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758396: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758458: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758459: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758460: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758461: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758461: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758461: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758462: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758462: bt_sock_poll <-sock_poll
      bluetoothd-247     [001] ...1.  1643.758462: bt_sock_poll <-sock_poll
         QServer-375     [001] ...1.  1646.719414: bt_sock_create <-__sock_create
         QServer-375     [001] ...2.  1646.719418: rfcomm_sock_create <-bt_sock_create
         QServer-375     [001] ...2.  1646.719419: bt_sock_alloc <-rfcomm_sock_create
         QServer-375     [001] ...2.  1646.719454: rfcomm_dlc_alloc <-rfcomm_sock_create
         QServer-375     [001] ...2.  1646.719467: bt_sock_link <-rfcomm_sock_create
         QServer-375     [001] ...1.  1646.719539: rfcomm_sock_connect <-__sys_connect
         QServer-375     [001] ...1.  1646.719540: rfcomm_dlc_open <-rfcomm_sock_connect
         QServer-375     [001] ...1.  1646.719544: rfcomm_sock_release <-sock_close
         QServer-375     [001] ...1.  1646.719545: rfcomm_sock_shutdown <-rfcomm_sock_release
         QServer-375     [001] ...1.  1646.719546: __rfcomm_sock_close <-rfcomm_sock_shutdown
         QServer-375     [001] ...1.  1646.719546: rfcomm_dlc_close <-__rfcomm_sock_close
         QServer-375     [001] ...1.  1646.719547: bt_sock_wait_state <-rfcomm_sock_shutdown
         QServer-375     [001] ...1.  1654.718982: bt_sock_unlink <-rfcomm_sock_release
         QServer-375     [001] ...1.  1654.718985: rfcomm_sock_destruct <-__sk_destruct
         QServer-375     [001] ...1.  1654.718987: rfcomm_dlc_free <-__sk_destruct
          <idle>-0       [001] ..s2.  1662.097189: rfcomm_dlc_timeout <-call_timer_fn
        krfcommd-204     [001] ...1.  1662.097213: rfcomm_process_sessions <-rfcomm_run
        krfcommd-204     [001] ...1.  1662.097216: __rfcomm_dlc_close <-rfcomm_process_sessions
        krfcommd-204     [001] ...1.  1662.097217: rfcomm_sk_state_change <-__rfcomm_dlc_close
          <idle>-0       [001] ..s2.  1664.145202: rfcomm_session_timeout <-call_timer_fn
        krfcommd-204     [001] ...1.  1664.145232: rfcomm_process_sessions <-rfcomm_run
     kworker/0:2-53      [000] ...1.  1664.145233: l2cap_chan_timeout <-process_one_work
     kworker/0:2-53      [000] ...1.  1664.145236: l2cap_chan_close <-l2cap_chan_timeout
     kworker/0:2-53      [000] ...1.  1664.145236: l2cap_chan_del <-l2cap_chan_close
        krfcommd-204     [001] ...1.  1664.145237: l2cap_sock_sendmsg <-sock_sendmsg
     kworker/0:2-53      [000] ...1.  1664.145238: l2cap_sock_teardown_cb <-l2cap_chan_del
     kworker/0:2-53      [000] ...1.  1664.145239: rfcomm_l2state_change <-l2cap_sock_teardown_cb
     kworker/0:2-53      [000] ...1.  1664.145250: l2cap_chan_put <-l2cap_chan_del
     kworker/0:2-53      [000] ...1.  1664.145251: l2cap_sock_close_cb <-l2cap_chan_timeout
     kworker/0:2-53      [000] ...1.  1664.145252: l2cap_chan_put <-l2cap_chan_timeout
        krfcommd-204     [001] ...1.  1664.145277: rfcomm_process_sessions <-rfcomm_run
        krfcommd-204     [001] ...1.  1664.145279: l2cap_sock_release <-sock_release
        krfcommd-204     [001] ...1.  1664.145280: l2cap_sock_cleanup_listen <-l2cap_sock_release
        krfcommd-204     [001] ...1.  1664.145281: bt_accept_dequeue <-l2cap_sock_cleanup_listen
        krfcommd-204     [001] ...1.  1664.145281: bt_sock_unlink <-l2cap_sock_release
        krfcommd-204     [001] ...1.  1664.145283: l2cap_sock_shutdown <-l2cap_sock_release
        krfcommd-204     [001] ...1.  1664.145284: l2cap_chan_hold <-l2cap_sock_shutdown
        krfcommd-204     [001] ...1.  1664.145285: l2cap_chan_close <-l2cap_sock_shutdown
        krfcommd-204     [001] ...1.  1664.145285: l2cap_sock_teardown_cb <-l2cap_chan_close
        krfcommd-204     [001] ...1.  1664.145286: l2cap_chan_put <-l2cap_sock_shutdown
        krfcommd-204     [001] ...1.  1664.145287: l2cap_chan_hold <-l2cap_sock_release
        krfcommd-204     [001] ...1.  1664.145288: l2cap_chan_put <-l2cap_sock_release
        krfcommd-204     [001] ...1.  1664.145289: l2cap_sock_destruct <-__sk_destruct
        krfcommd-204     [001] ...1.  1664.145289: l2cap_chan_put <-l2cap_sock_destruct
        krfcommd-204     [001] ...1.  1664.145330: l2cap_chan_put <-l2cap_sock_release
root@hyx-QiTianM540-A739:/sys/kernel/debug/tracing# 



[Index of Archives]     [Bluez Devel]     [Linux Wireless Networking]     [Linux Wireless Personal Area Networking]     [Linux ATH6KL]     [Linux USB Devel]     [Linux Media Drivers]     [Linux Audio Users]     [Linux Kernel]     [Linux SCSI]     [Big List of Linux Books]

  Powered by Linux