Nexus 4 vs Zenphone 5
Case 1. Nexus 4 PANU -> Z5 NAP,
log on N4 side
--------------------------------------
06-19 09:55:12.145 D/BluetoothPan( 2633): connect(10:C3:7B:E9:8A:F4)
06-19 09:55:12.145 D/BluetoothPanServiceJni(29518): connectPanNative(L188): in
06-19 09:55:12.145 D/CachedBluetoothDevice( 2633): Command sent successfully:CONNECT Address:10:C3:7B:E9:8A:F4 Profile:PAN
06-19 09:55:12.145 D/BluetoothPanServiceJni(29518): connection_state_callback(L72): state:1, local_role:2, remote_role:1
06-19 09:55:12.145 D/PanService(29518): handlePanDeviceStateChange preState: 0 state: 1
06-19 09:55:12.145 D/PanService(29518): handlePanDeviceStateChange LOCAL_PANU_ROLE:REMOTE_NAP_ROLE state = 1, prevState = 0
06-19 09:55:12.145 D/PanService(29518): Pan Device state : device: 10:C3:7B:E9:8A:F4 State:0->1
06-19 09:55:12.155 D/BluetoothAdapterProperties(29518): CONNECTION_STATE_CHANGE: 10:C3:7B:E9:8A:F4: 0 -> 1
06-19 09:55:12.165 D/BluetoothAdapterService(1105663160)(29518): Get Bonded Devices being called
06-19 09:55:12.195 D/CachedBluetoothDevice( 2633): onProfileStateChanged: profile PAN newProfileState 1
06-19 09:55:12.275 W/bt-btif (29518): info:x10
06-19 09:55:12.275 D/ (29518): remote version info [10:c3:7b:e9:8a:f4]: 6, f, 4204
06-19 09:55:12.275 D/btif_config_util(29518): btif_config_save_file(L153): in file name:/data/misc/bluedroid/bt_config.new
06-19 09:55:12.365 D/BluetoothPanServiceJni(29518): connection_state_callback(L72): state:0, local_role:2, remote_role:1
06-19 09:55:12.365 D/Tethering( 523): sendTetherStateChangedBroadcast 1, 0, 0
06-19 09:55:12.365 D/Tethering( 523): InitialState.processMessage what=4
06-19 09:55:12.375 D/PanService(29518): handlePanDeviceStateChange preState: 1 state: 2
06-19 09:55:12.375 D/PanService(29518): handlePanDeviceStateChange LOCAL_PANU_ROLE:REMOTE_NAP_ROLE state = 2, prevState = 1
06-19 09:55:12.375 D/PanService(29518): Pan Device state : device: 10:C3:7B:E9:8A:F4 State:1->2
06-19 09:55:12.375 D/BluetoothTethering( 523): got EVENT_NETWORK_CONNECTED, {InterfaceName: bt-pan LinkAddresses: [] Routes: [] DnsAddresses: [] Domains: null}
06-19 09:55:12.375 D/Tethering( 523): sendTetherStateChangedBroadcast 0, 0, 0
06-19 09:55:12.375 D/Tethering( 523): sendTetherStateChangedBroadcast 1, 0, 0
06-19 09:55:12.385 D/CachedBluetoothDevice( 2633): onProfileStateChanged: profile PAN newProfileState 2
06-19 09:55:12.385 D/BluetoothAdapterProperties(29518): CONNECTION_STATE_CHANGE: 10:C3:7B:E9:8A:F4: 1 -> 2
06-19 09:55:12.395 D/BluetoothAdapterService(1105663160)(29518): Get Bonded Devices being called
06-19 09:55:12.395 D/BluetoothAdapterProperties(29518): getBondedDevices: length=1
06-19 09:55:17.581 D/ConnectivityService( 523): ConnectivityChange for BLUETOOTH_TETHER: CONNECTED/CONNECTED
06-19 09:55:20.744 D/PicasaSyncManager(10841): active network: NetworkInfo: type: BLUETOOTH_TETHER[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true
06-19 09:55:20.744 W/ActivityManager( 523): Scheduling restart of crashed service com.nforetek.amiraa.source/com.nforetek.uibc.ProcessUibc in 5000ms
06-19 09:55:20.764 D/PicasaUploaderSyncManager(29765): active network: NetworkInfo: type: BLUETOOTH_TETHER[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true
06-19 09:55:20.794 I/SystemUpdateService( 5701): network: NetworkInfo: type: BLUETOOTH_TETHER[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true; metered: false; mobile allowed: false
06-19 09:55:20.794 I/SystemUpdateService( 5701): now status is 0 (complete)
06-19 09:55:20.814 I/SystemUpdateService( 5701): network: NetworkInfo: type: BLUETOOTH_TETHER[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), roaming: false, failover: false, isAvailable: true; metered: false; mobile allowed: false
06-19 09:55:20.814 I/SystemUpdateService( 5701): now status is 0 (complete)
06-19 09:55:23.757 D/ConnectivityService( 523): handleInetConditionHoldEnd: net=7, condition=100, published condition=0
06-19 09:55:30.024 D/BluetoothPan( 2633): disconnect(10:C3:7B:E9:8A:F4)
06-19 09:55:30.024 D/CachedBluetoothDevice( 2633): Command sent successfully:DISCONNECT Address:10:C3:7B:E9:8A:F4 Profile:PAN
06-19 09:55:30.034 D/Tethering( 523): InitialState.processMessage what=4
06-19 09:55:30.034 D/BluetoothPanServiceJni(29518): connection_state_callback(L72): state:3, local_role:2, remote_role:1
06-19 09:55:30.034 D/Tethering( 523): sendTetherStateChangedBroadcast 0, 0, 0
06-19 09:55:30.034 D/PanService(29518): handlePanDeviceStateChange preState: 2 state: 3
06-19 09:55:30.034 D/PanService(29518): handlePanDeviceStateChange LOCAL_PANU_ROLE:REMOTE_NAP_ROLE state = 3, prevState = 2
06-19 09:55:30.034 D/PanService(29518): Pan Device state : device: 10:C3:7B:E9:8A:F4 State:2->3
06-19 09:55:30.044 D/BluetoothAdapterProperties(29518): CONNECTION_STATE_CHANGE: 10:C3:7B:E9:8A:F4: 2 -> 3
06-19 09:55:30.054 D/BluetoothAdapterService(1105663160)(29518): Get Bonded Devices being called
06-19 09:55:30.054 D/BluetoothAdapterProperties(29518): getBondedDevices: length=1
06-19 09:55:31.395 I/BTIF_PAN(29518): bta_pan_callback_transfer: event = BTA_PAN_CLOSE_EVT handle 1
06-19 09:55:31.395 D/BluetoothPanServiceJni(29518): connection_state_callback(L72): state:2, local_role:2, remote_role:1
06-19 09:55:31.395 D/PanService(29518): handlePanDeviceStateChange preState: 3 state: 0
06-19 09:55:31.395 D/PanService(29518): handlePanDeviceStateChange LOCAL_PANU_ROLE:REMOTE_NAP_ROLE state = 0, prevState = 3
06-19 09:55:31.395 D/PanService(29518): Pan Device state : device: 10:C3:7B:E9:8A:F4 State:3->0
06-19 09:55:31.395 D/BluetoothTethering( 523): got EVENT_NETWORK_DISCONNECTED, {InterfaceName: bt-pan LinkAddresses: [] Routes: [] DnsAddresses: [] Domains: null}
06-19 09:55:31.395 D/CachedBluetoothDevice( 2633): onProfileStateChanged: profile PAN newProfileState 0
06-19 09:55:31.395 D/BluetoothAdapterProperties(29518): CONNECTION_STATE_CHANGE: 10:C3:7B:E9:8A:F4: 3 -> 0
06-19 09:55:31.405 D/BluetoothAdapterService(1105663160)(29518): Get Bonded Devices being called
06-19 09:55:31.405 D/BluetoothAdapterProperties(29518): getBondedDevices: length=1
06-19 09:55:31.595 D/ConnectivityService( 523): ConnectivityChange for BLUETOOTH_TETHER: DISCONNECTED/DISCONNECTED
06-19 09:55:31.595 D/ConnectivityService( 523): Attempting to switch to mobile
06-19 09:55:31.595 D/ConnectivityService( 523): Attempting to switch to WIFI
06-19 09:55:31.605 D/ConnectivityService( 523): resetConnections(bt-pan, 3)
Nexus 4 PANU -> Z5 NAP, log on Z5 side
<<N4 connect PAN...>>
06-19 10:21:22.824 D/PhoneGlobals( 736): onSignalStrengthsChanged(): SIGNAL_STRENGTH_MODERATE
06-19 10:21:23.424 D/ ( 708): remote version info [98:d6:f7:b7:f1:87]: 6, 1d, 7d3
06-19 10:21:23.424 D/btif_config_util( 708): btif_config_save_file(L153): in file name:/data/misc/bluedroid/bt_config.new
06-19 10:21:23.494 W/bt-btif ( 708): No scb for handle 1
06-19 10:21:23.494 D/Tethering( 443): sendTetherStateChangedBroadcast 1, 0, 0
06-19 10:21:23.494 D/Tethering( 443): InitialState.processMessage what=4
06-19 10:21:23.494 D/BluetoothPanServiceJni( 708): connection_state_callback(L72): state:0, local_role:1, remote_role:2
06-19 10:21:23.494 D/Tethering( 443): sendTetherStateChangedBroadcast 0, 0, 0
06-19 10:21:23.504 D/PanService( 708): handlePanDeviceStateChange preState: 0 state: 2
06-19 10:21:23.504 D/PanService( 708): handlePanDeviceStateChange LOCAL_NAP_ROLE:REMOTE_PANU_ROLE
06-19 10:21:23.504 D/Tethering( 443): sendTetherStateChangedBroadcast 1, 0, 0
06-19 10:21:23.524 D/CommandListener( 168): Setting iface cfg
06-19 10:21:23.534 D/CommandListener( 168): Trying to bring up bt-pan
06-19 10:21:23.534 D/Tethering( 443): Tethering bt-pan
06-19 10:21:23.534 D/Tethering( 443): InitialState.processMessage what=2
06-19 10:21:23.534 D/PanService( 708): Pan Device state : device: 98:D6:F7:B7:F1:87 State:0->2
06-19 10:21:23.534 D/BluetoothAdapterService( 708): getAdapterService(): returning com.android.bluetooth.btservice.AdapterService@21c29438
06-19 10:21:23.534 D/Tethering( 443): sendTetherStateChangedBroadcast 0, 0, 0
06-19 10:21:23.534 D/TetherController( 168): tetherInterface(bt-pan)
06-19 10:21:23.534 D/Tethering( 443): Tethered bt-pan
06-19 10:21:23.534 D/BluetoothAdapterService(566400056)( 708): Message: 20
06-19 10:21:23.534 D/BluetoothAdapterService(566400056)( 708): MESSAGE_PROFILE_CONNECTION_STATE_CHANGED
06-19 10:21:23.534 D/CachedBluetoothDevice( 5743): onProfileStateChanged: profile PAN newProfileState 2
06-19 10:21:23.534 D/Tethering( 443): sendTetherStateChangedBroadcast 0, 1, 0
06-19 10:21:23.534 D/BluetoothAdapterProperties( 708): CONNECTION_STATE_CHANGE: 98:D6:F7:B7:F1:87: 0 -> 2
06-19 10:21:23.534 D/BluetoothAdapterService(566400056)( 708): getState(): mAdapterProperties: com.android.bluetooth.btservice.AdapterProperties@21c27d20
06-19 10:21:23.544 D/BluetoothAdapterService(566400056)( 708): Get Bonded Devices being called
06-19 10:21:23.544 D/Tethering( 443): MasterInitialState.processMessage what=1
06-19 10:21:23.544 D/BluetoothAdapterProperties( 708): getBondedDevices: length=2
06-19 10:21:23.544 D/TetherController( 168): Setting IP forward enable = 1
06-19 10:21:23.544 D/TetherController( 168): Starting tethering services
06-19 10:21:23.544 D/TetherController( 168): Sending update msg to dnsmasq [update_ifaces:bt-pan]
06-19 10:21:23.544 D/TetherController( 168): Tethering services running
06-19 10:21:23.544 D/TetherController( 168): setDnsForwarders(0 = '8.8.8.8')
06-19 10:21:23.544 D/TetherController( 168): setDnsForwarders(1 = '8.8.4.4')
06-19 10:21:23.544 D/TetherController( 168): Sending update msg to dnsmasq [update_dns:8.8.8.8:8.8.4.4]
06-19 10:21:23.544 D/Tethering( 443): chooseUpstreamType(true), preferredApn =5, got type=0
06-19 10:21:23.544 I/Tethering( 443): Finding IPv4 upstream interface on: {InterfaceName: rmnet0 LinkAddresses: [100.68.45.134/32,] Routes: [0.0.0.0/0 -> 100.68.45.1,] DnsAddresses: [61.31.233.1,168.95.1.1,] Domains: null}
06-19 10:21:23.544 I/Tethering( 443): Found interface rmnet0
06-19 10:21:23.544 D/TetherController( 168): setDnsForwarders(0 = '61.31.233.1')
06-19 10:21:23.544 D/TetherController( 168): setDnsForwarders(1 = '168.95.1.1')
06-19 10:21:23.544 D/TetherController( 168): Sending update msg to dnsmasq [update_dns:61.31.233.1:168.95.1.1]
06-19 10:21:23.544 D/Tethering( 443): notifying tethered with iface =rmnet0
06-19 10:21:23.544 D/Tethering( 443): TetheredState.processMessage what=12
06-19 10:21:23.554 I/dnsmasq ( 6695): started, version 2.51 cachesize 150
06-19 10:21:23.554 I/dnsmasq ( 6695): compile time options: no-IPv6 GNU-getopt no-DBus no-I18N DHCP no-TFTP
06-19 10:21:23.554 W/dnsmasq ( 6695): warning: no upstream servers configured
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.48.2 -- 192.168.48.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.47.2 -- 192.168.47.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.46.2 -- 192.168.46.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.45.2 -- 192.168.45.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.44.2 -- 192.168.44.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.43.2 -- 192.168.43.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695): DHCP, IP range 192.168.42.2 -- 192.168.42.254, lease time 1h
06-19 10:21:23.554 I/dnsmasq ( 6695):