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

[bluetooth-dev] Dev kit reboots when running OpenBT



Hi all,

I'm running the latest source of the stack and the experimental apps on an Etrax 100 LX devboard. Everthing seems to work nice until I try to connect to another OpenBT stack running on a PC (redhat 6.2).

I seems like I get an working baseband and L2CAP connections but then the dev board reboots (probably because some segmentation fault).

Anyone who got any idea of what causes this problem?

TIA
Peter


Heres the log on the connecting machine:

BT (driver) bt_open on line 7
BT (driver) Registering tty on line 7 (btmain)
BT (driver) Now 1 open fd:s for ttyBTC [btmain]
BT (driver) bt_close on line 7
BT (driver) Unregistering tty on line 7
Jan  1 00:17:07BT (ldisc) bt_tty_open
BT SYS: Setting BT driver to use serial tty
Jan  1 00:17:07 lap btmain: Registered bluetooth line discipline on /dev/ttyS0
BT (driver) bt_open on line 7
BT (driver) Registering tty on line 7 (btmain)
BT (driver) Now 1 open fd:s for ttyBTC [btmain]
Jan  1 00:17:07BT SYS: Initialising Bluetooth Stack
BT SYS: Current HW: CSR (H4)
BT SYS: Initialising BTMEM [2500 bytes]
BT SYS: Initialising HCI
BT SYS: HCI emulator off
BT SYS: Initialising HCI inbuffers [800]
HCI: init_cmd_buf: Initializing
BT SYS: Reading buffer sizes in HW module
HCI: hci_read_buffer_size
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (4):
0x01 0x05 0x10 0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (14):
0x04 0x0e 0x0b 0x01 0x05 0x10 0x00 0x80 0x00 0x40 0x08 0x00 0x08 0x00

process_event (11):
0x01 0x05 0x10 0x00 0x80 0x00 0x40 0x08 0x00 0x08 0x00
HCI: process_event: COMMAND_COMPLETE
HCI: release_cmd_timer
HCI: process_return_param: READ_BUFFER_SIZE

HW module contains...
8 ACL buffers at 128 bytes
8 SCO buffers at 64 bytes

HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue
BT SYS: Reading firmware info in HW module
HCI: hci_read_firmware_rev_info [CSR] BuildID/ChipVer/ChipRev
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (27):
0x01 0x00 0xfc 0x17 0xc2 0x00 0x00 0x0b 0x00 0x00 0x00 0x19 0x28 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (26):
0x04 0xff 0x17 0xc2 0x01 0x00 0x0b 0x00 0x00 0x00 0x19 0x28 0x00 0x00 0x64 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00

process_event (23):
0xc2 0x01 0x00 0x0b 0x00 0x00 0x00 0x19 0x28 0x00 0x00 0x64 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00
HCI: release_cmd_timer
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (27):
0x01 0x00 0xfc 0x17 0xc2 0x00 0x00 0x0b 0x00 0x01 0x00 0x1a 0x28 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (26):
0x04 0xff 0x17 0xc2 0x01 0x00 0x0b 0x00 0x01 0x00 0x1a 0x28 0x00 0x00 0x01 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00

process_event (23):
0xc2 0x01 0x00 0x0b 0x00 0x01 0x00 0x1a 0x28 0x00 0x00 0x01 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00
HCI: release_cmd_timer
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (27):
0x01 0x00 0xfc 0x17 0xc2 0x00 0x00 0x0b 0x00 0x02 0x00 0x1b 0x28 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (26):
0x04 0xff 0x17 0xc2 0x01 0x00 0x0b 0x00 0x02 0x00 0x1b 0x28 0x00 0x00 0x64 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00

process_event (23):
0xc2 0x01 0x00 0x0b 0x00 0x02 0x00 0x1b 0x28 0x00 0x00 0x64 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00
HCI: release_cmd_timer
BT SYS: Host flow control not enabled
BT SYS: M/S switch disabled
BT SYS: Force M/S switch set to 0
BT SYS: Initialising L2CAP
HCI: hci_read_local_bd
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (4):
0x01 0x09 0x10 0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (13):
0x04 0x0e 0x0a 0x01 0x09 0x10 0x00 0x32 0x06 0xff 0x5b 0x02 0x00

process_event (10):
0x01 0x09 0x10 0x00 0x32 0x06 0xff 0x5b 0x02 0x00
HCI: process_event: COMMAND_COMPLETE
HCI: release_cmd_timer
HCI: process_return_param: READ_BD_ADDR

process_return_param: READ_BD_ADDR :  (6):
0x32 0x06 0xff 0x5b 0x02 0x00
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue
BT SYS: Local bd [00:02:5b:ff:06:32]
BT SYS: Initialising RFCOMM
          RFCOMM rfcomm_reset_con: line 0
          RFCOMM rfcomm_reset_con: line 1
          RFCOMM rfcomm_reset_con: line 2
          RFCOMM rfcomm_reset_con: line 3
          RFCOMM rfcomm_reset_con: line 4
          RFCOMM rfcomm_reset_con: line 5
          RFCOMM rfcomm_reset_con: line 6
BT SYS: Initialising SDP
BT SYS: Init SDP as server
BT SYS: Initialising TCS
Jan  1 00:17:08BT (driver) bt_ioctl: HCIWRITECLASSOFDEVICE
 lap btmain: bt_set_classofdevice : srv class 0x10, maj class 0x3, min class 0x0, format 0x0
HCI: hci_write_class_of_device: Service class 0x10, major:0x3, minor:0x0
HCI: hci_write_class_of_device: 2:3:0
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (7):
0x01 0x24 0x0c 0x03 0x00 0x03 0x02
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (7):
0x04 0x0e 0x04 0x01 0x24 0x0c 0x00

process_event (4):
0x01 0x24 0x0c 0x00
HCI: process_event: COMMAND_COMPLETE
HCI: release_cmd_timer
HCI: process_return_param: WRITE_CLASS_OF_DEVICE
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue
Jan  1 00:17:08BT (driver) Setting write scan enable : [0x3]
 lap btmain: Setting write_scan_enable: 0x3
HCI: hci_write_scan_enable: enable 3
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (5):
0x01 0x1a 0x0c 0x01 0x03
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (7):
0x04 0x0e 0x04 0x01 0x1a 0x0c 0x00

process_event (4):
0x01 0x1a 0x0c 0x00
HCI: process_event: COMMAND_COMPLETE
HCI: release_cmd_timer
HCI: process_return_param: WRITE_SCAN_ENABLE
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue
Jan  1 00:17:09BT (driver) Setting write page scan activity : [0x800,0x12]
 lap btmain: Setting write_pagescan_activity: int:0x800 wind:0x12
HCI: hci_write_pagescan_activity
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (8):
0x01 0x1c 0x0c 0x04 0x00 0x08 0x12 0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (7):
0x04 0x0e 0x04 0x01 0x1c 0x0c 0x00

process_event (4):
0x01 0x1c 0x0c 0x00
HCI: process_event: COMMAND_COMPLETE
HCI: release_cmd_timer
HCI: process_return_param: WRITE_PAGESCAN_ACTIVITY
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue
Jan  1 00:17:09BT (driver) bt_ioctl: HCISET_EVENT_FILTER
 lap btmain: Setting event filter [0x2 0x0 0x1]
HCI: hci_set_event_filter
HCI: start_cmd_timer
HCI: insert_cmd
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (7):
0x01 0x05 0x0c 0x03 0x39 0xfe 0xff
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (7):
0x04 0x0e 0x04 0x01 0x05 0x0c 0x12

process_event (4):
0x01 0x05 0x0c 0x12
HCI: process_event: COMMAND_COMPLETE
HCI: release_cmd_timer
HCI: process_return_param: SET_EVENT_FILTER
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue
BT (driver) bt_open on line 7
BT (driver) Registering tty on line 7 (btmain)
BT (driver) Now 2 open fd:s for ttyBTC [btmain]
BT (driver) bt_ioctl: BTFIRMWAREINFO
BT (driver) bt_close on line 7
BT (driver) Unregistering tty on line 7
Jan  1 00:17:10BT (driver) bt_ioctl: Setting baudrate in host controller to 115200
 lap btmain: bt_set_baudrate: 115200 baud
HCI: hci_set_baudrate [CSR] (115200 baud)
BT (ldisc) bt_tty_ioctl: forwarding ioctl 0x5401 to n_tty line disc
BT (ldisc) bt_tty_ioctl: forwarding ioctl 0x5402 to n_tty line disc
BT (ldisc) bt_tty_ioctl: forwarding ioctl 0x5401 to n_tty line disc
BT (ldisc) bt_tty_ioctl: forwarding ioctl 0x540b to n_tty line disc

hci_receive_data:  (22):
0x04 0xff 0x13 0xc2 0x01 0x00 0x09 0x00 0x03 0x00 0x02 0x68 0x00 0x00 0xd8 0x01
0x00 0x00 0x00 0x00 0x00 0x00

process_event (19):
0xc2 0x01 0x00 0x09 0x00 0x03 0x00 0x02 0x68 0x00 0x00 0xd8 0x01 0x00 0x00 0x00
0x00 0x00 0x00
HCI: release_cmd_timer

hci_receive_data:  (28):
0x04 0xff 0x19 0xc3 0x02 0x00 0x0c 0x00 0x8d 0x1e 0x00 0x10 0x00 0x00 0x1c 0x00
0x2d 0x02 0xb4 0xa9 0x01 0x00 0xc4 0xa4 0x00 0x00 0x00 0x00

process_event (25):
0xc3 0x02 0x00 0x0c 0x00 0x8d 0x1e 0x00 0x10 0x00 0x00 0x1c 0x00 0x2d 0x02 0xb4
0xa9 0x01 0x00 0xc4 0xa4 0x00 0x00 0x00 0x00
HCI: release_cmd_timer
BT (driver) bt_close on line 7
BT (driver) Unregistering tty on line 7
BT (driver) bt_open on line 7
BT (driver) Registering tty on line 7 (btmain)
BT (driver) Now 1 open fd:s for ttyBTC [btmain]
BT (driver) bt_ioctl: HCINQUIRY
HCI: hci_inquiry: Sending inquiry()

hci_inquiry (9):
0x01 0x01 0x04 0x05 0x33 0x8b 0x9e 0x0a 0x05
HCI: insert_cmd
HCI: insert_cmd: WRAP
HCI: start_inq_timer
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: get_next_cmd: next_to_send WRAP

send_cmd_queue:  (9):
0x01 0x01 0x04 0x05 0x33 0x8b 0x9e 0x0a 0x05
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (7):
0x04 0x0f 0x04 0x00 0x01 0x01 0x04

process_event (4):
0x00 0x01 0x01 0x04
HCI: release_cmd_timer
HCI: process_event: COMMAND_STATUS
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (18):
0x04 0x02 0x0f 0x01 0x31 0x06 0xff 0x5b 0x02 0x00 0x01 0x00 0x00 0x00 0x03 0x02
0x84 0x78

process_event (15):
0x01 0x31 0x06 0xff 0x5b 0x02 0x00 0x01 0x00 0x00 0x00 0x03 0x02 0x84 0x78
HCI: process_event: INQUIRY_RESULT
BD addr 1

BD (6):
0x00 0x02 0x5b 0xff 0x06 0x31

hci_receive_data:  (5):
0x04 0x01 0x02 0x00 0x01

process_event (2):
0x00 0x01
HCI: INQUIRY_COMPLETE
HCI: release_inq_timer
BT (driver) bt_close on line 7
BT (driver) Unregistering tty on line 7
BT (driver) bt_open on line 7
BT (driver) Registering tty on line 7 (btmain)
BT (driver) Now 1 open fd:s for ttyBTC [btmain]
BT (driver) bt_ioctl: BTCONNECT
BT (driver) bt_connect : Connecting srv ch 7 on line 0
          RFCOMM rfcomm_connect_req: server channel:7, line:0
    L2CAP get_con: look for connections in state ANY_STATE (7)
    L2CAP get_con: connection not found
    L2CAP get_lcon: lcid 64 con_list.count = 0
    L2CAP get_lcon: connection lcid : 64 not found
    L2CAP create_con: rcid 0

bd (6):
0x31 0x06 0xff 0x5b 0x02 0x00
show_con: insert_con (64:0) [CLOSED] [RFCOMM] r_mtu[672] C[yes] link_up[no]
    L2CAP insert_con: now 1 connections
    L2CAP l2ca_connect_req: create new baseband link

lp_connect_req: bd address is:  (6):
0x31 0x06 0xff 0x5b 0x02 0x00
    L2CAP get_con: look for connections in state ANY_STATE (7)
HCI: create_connection
HCI: insert_cmd
l2ca_connect_req: wait baseband, sleep on wq 0x601ee924
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (17):
0x01 0x05 0x04 0x0d 0x31 0x06 0xff 0x5b 0x02 0x00 0x18 0xcc 0x00 0x00 0x00 0x00
0x00
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (7):
0x04 0x0f 0x04 0x00 0x01 0x05 0x04

process_event (4):
0x00 0x01 0x05 0x04
HCI: release_cmd_timer
HCI: process_event: COMMAND_STATUS
HCI: update_nhcp: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1
HCI: send_cmd_queue: end : 0 cmds left in queue

hci_receive_data:  (20):
0x04 0x03 0x0b 0x00 0x27 0x00 0x31 0x06 0xff 0x5b 0x02 0x00 0x01 0x00 0x04 0x1b
0x03 0x27 0x00 0x05

process_event (11):
0x00 0x27 0x00 0x31 0x06 0xff 0x5b 0x02 0x00 0x01 0x00
HCI: process_event: CONNECTION_COMPLETE: Success!
    L2CAP lp_connect_cfm: Success! (hci_handle : 39)
    L2CAP lp_connect_cfm: bd 00:02:5B:FF:06:31
    L2CAP get_con: look for connections in state CLOSED (0)
lp_connect_cfm (pos), wake up wq 0x601ee924
    L2CAP We are client
    L2CAP Current state of (64:0) is W4_L2CAP_CONNECT_RSP
lp_connect_cfm, wake up flag not set
BT SYS: process_event: ACL link is up
HCI: change_connection_packet_type: for connnection handle 0x27
HCI: insert_cmd
HCI: remote_name_request:  for bd address 0x00:02:5b:ff:06:31
HCI: insert_cmd
HCI: write_link_policy_settings
HCI: insert_cmd
HCI: hci_update_load_factor: Load factor changed from 0 to 1
HCI: insert_cmd

process_event (3):
0x27 0x00 0x05
HCI: process_event: MAX_SLOTS_CHANGE to 5
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (8):
0x01 0x0f 0x04 0x04 0x27 0x00 0x18 0xcc
HCI: send_cmd_queue: end : 3 cmds left in queue
l2ca_connect_req: wait baseband, woke up !
l2ca_connect_req: wait rsp, sleep on wq 0x601ee924

send_acl_packet:  (17):
0x02 0x27 0x20 0x0c 0x00 0x08 0x00 0x01 0x00 0x02 0x01 0x04 0x00 0x03 0x00 0x40
0x00

hci_receive_data:  (44):
0x04 0x0f 0x04 0x00 0x01 0x0f 0x04 0x04 0x1d 0x05 0x00 0x27 0x00 0x18 0xcc 0x04
0x13 0x05 0x01 0x27 0x00 0x01 0x00 0x02 0x27 0x20 0x10 0x00 0x0c 0x00 0x01 0x00
0x03 0x01 0x08 0x00 0x40 0x00 0x40 0x00 0x00 0x00 0x00 0x00

process_event (4):
0x00 0x01 0x0f 0x04
HCI: release_cmd_timer
HCI: process_event: COMMAND_STATUS
HCI: update_nhcp: Num_HCI_Command_Packets=1

process_event (5):
0x00 0x27 0x00 0x18 0xcc
HCI: process_event: CHANGE_CONNECTION_PACKET_TYPE

process_event (5):
0x01 0x27 0x00 0x01 0x00
HCI: process_event: NUMBER_OF_COMPLETED_PACKETS
HCI: get_free_inbuffer: inbuffer 0 was free
    L2CAP process_response: Got response: 0x3 id:1
    L2CAP process_response: Got connection response
    L2CAP get_lcon: lcid 64 con_list.count = 1
l2cap con rsp, wake up wq 0x601ee924
    L2CAP Current state of (64:64) is CONFIG
    L2CAP l2ca_connect_cfm: rCID 64, result 0
          RFCOMM rfcomm_connect_cfm: status 0
    L2CAP l2ca_config_req remote cid 64, in_mtu 0
    L2CAP l2cap_config_req: inmtu : 0, local mtu : 672

l2cap options:  (0):
HCI: hci_clear_buffer
HCI: get_inbuffer: Found inbuffer for hci_hdl 39 600f013c
HCI: send_cmd_queue: start: Num_HCI_Command_Packets=1

send_cmd_queue:  (14):
0x01 0x19 0x04 0x0a 0x31 0x06 0xff 0x5b 0x02 0x00 0x00 0x00 0x00 0x00
HCI: send_cmd_queue: end : 2 cmds left in queue

send_acl_packet:  (17):
0x02 0x27 0x20 0x0c 0x00 0x08 0x00 0x01 0x00 0x04 0x02 0x04 0x00 0x40 0x00 0x00
0x00
l2ca_connect_req: wait rsp, woke up !

hci_receive_data:  (309):
0x04 0x0f 0x04 0x00 0x01 0x19 0x04 0x04 0x13 0x05 0x01 0x27 0x00 0x01 0x00 0x02
0x27 0x20 0x0e 0x00 0x0a 0x00 0x01 0x00 0x05 0x02 0x06 0x00 0x40 0x00 0x00 0x00
0x00 0x00 0x02 0x27 0x20 0x0c 0x00 0x08 0x00 0x01 0x00 0x04 0x01 0x04 0x00 0x40
0x00 0x00 0x00 0x04 0x07 0xff 0x00 0x31 0x06 0xff 0x5b 0x02 0x00 0x41 0x58 0x49
0x53 0x20 0x28 0x4a 0x69 0x6d 0x6d 0x79 0x29 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00
0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 ˙Uncompressing Linux...
Done. Now booting the kernel.
Linux version 2.4.5 (root@xxxxxxx.96 20000427 (experimental)) #35 Mon Jul 30 13:25:36 EDT 2001