Bluez 5.23 help to tether iPhone

[AlessandroFerri]

Hello to all,
I installed Bluez 5.23, following this procedure:

viewtopic.php?f=29&t=87138&start=100

Everything works perfectly. But now I want to connect my raspberry to 'personal hotspot of my iPad or iPhone, using Bluetooth to access the Internet.
I tried to find something on the web, but nothing worked, primarily because the version I installed is 5.23.

Is there anyone who can help me solve the problem.
Thank you in advance.

[Douglas6]

Setting up the Network Access Protocol (NAP) is not easy. I did it once with Wheezy and BlueZ 4.99, but things have changed a bit. I'd Google 'BlueZ 5 nap bnep' for hints, like this one: http://blog.fraggod.net/2015/03/28/blue ... ez-5x.html.

[AlessandroFerri]

Thanks for the reply,
I have already looked at the link you gave me. I try to deal with one problem at a time. First, I do not know if I enable the bnep device correctly. I add just one line with bnep in / etc / modules. it's correct? In effect at boot I see that the device starts correctly, but if I run
ifconfig
not exist.
In the link that you gave me, what mean this piece of code?

Code: Select all

[Unit]
After=bluetooth.service
PartOf=bluetooth.service

[Service]
ExecStart=/usr/local/bin/bt-pan client 00:02:72:XX:XX:XX

[Install]
WantedBy=bluetooth.target

[Douglas6]

That code looks to be part of the configuration that systemd uses to start the bt-pan script when the bluetooth service is started. You should be able to start bt-pan manually for starters. With the recent changes in dhcpd and systemd, I'm afraid I won't be able to help you with this.

[AlessandroFerri]

and about bnep device, can you help me?

[Stygmate]

Hello,

I've managed to follow this tutorial:
http://blog.fraggod.net/2015/03/28/blue ... ez-5x.html

I launch the bt-pan client xx:xx:xx:xx command by hand.

But i'm disapointed the connection is etablished and the bnep interface created then after 1or 2 second killed...

More curious if anorher client is connected to the phone then it work without disconnection!

Any idea ?

Stygmate++

[AlessandroFerri]

Hi everyone,
I try to make same test and I try also to install bluez 5.35 and everything works fine, but I have the same problem with pan.
Now I launched bluetooth with the command

Code: Select all

sudo /usr/local/libexec/Bluetooth/bluetoothd -d -n

then I pair my iPhone using simple-agent:

Code: Select all

sudo python /bluez-5.35/test/simple-agent

Once I connected I did run bt-pan:

Code: Select all

sudo python bt-pan client 58:55:CA:D2:6C:3A

, these results bluetooth comments:

Code: Select all

bluetoothd[3126]: profiles/audio/player.c:media_player_set_status() paused
bluetoothd[3126]: src/device.c:connect_profiles() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A (all), client :1.16
bluetoothd[3126]: profiles/audio/a2dp.c:a2dp_source_connect() path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
bluetoothd[3126]: a2dp-source profile connect failed for 58:55:CA:D2:6C:3A: Operation already in progress
bluetoothd[3126]: profiles/network/connection.c:connection_connect() id 4374
bluetoothd[3126]: bnep: Could not bring up bnep0: Cannot assign requested address(99)
bluetoothd[3126]: profiles/network/connection.c:bnep_conn_cb() 
bluetoothd[3126]: connect failed Input/output error

The first line is the last after I pair my iPhone, later ones are after I did run bt-pan. it seems that attempts to reconnect Bluetooth A2DP-Source, but fails because it is in progress, and then fails to bring up the bpnep0.
You have an idea?

I also tested in another way. I did run bluetooth:

Code: Select all

sudo /usr/local/libexec/Bluetooth/bluetoothd -d -n

then I did run bt-pan without pair my iPhone:

Code: Select all

sudo python bt-pan client 58:55:CA:D2:6C:3A

Eventually bt-pan goes in the same error:

Code: Select all

pi@raspberry ~/fgtk-master $ sudo python bt-pan client 58:55:CA:D2:6C:3A
Traceback (most recent call last):
  File "bt-pan", line 208, in <module>
    if __name__ == '__main__': sys.exit(main())
  File "bt-pan", line 180, in main
    try: iface = net.Connect(opts.uuid)
  File "/usr/lib/python2.7/dist-packages/dbus/proxies.py", line 145, in __call__
    **keywords)
  File "/usr/lib/python2.7/dist-packages/dbus/connection.py", line 651, in call_blocking
    message, timeout)
dbus.exceptions.DBusException: org.bluez.Error.Failed: Input/output error

but my iPhone is now paired.
Here's bluetooth comments:

Code: Select all

bluetoothd[8977]: Endpoint registered: sender=:1.2 path=/MediaEndpoint/A2DPSink
bluetoothd[8977]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
bluetoothd[8977]: src/adapter.c:dev_class_changed_callback() Class: 0x0c0000
bluetoothd[8977]: src/adapter.c:property_set_mode_complete() Success (0x00)
bluetoothd[8977]: src/adapter.c:new_settings_callback() Settings: 0x000002db
bluetoothd[8977]: src/adapter.c:settings_changed() Changed settings: 0x00000001
bluetoothd[8977]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
bluetoothd[8977]: src/adapter.c:trigger_passive_scanning() 
bluetoothd[8977]: src/device.c:connect_profiles() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A (all), client :1.27
bluetoothd[8977]: profiles/audio/a2dp.c:a2dp_source_connect() path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_ref() 0x3d7288: ref=1
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_ref() 0x3d7288: ref=2
bluetoothd[8977]: profiles/audio/a2dp.c:setup_ref() 0x3cd550: ref=1
bluetoothd[8977]: profiles/audio/source.c:source_set_state() State changed /org/bluez/hci0/dev_58_55_CA_D2_6C_3A: SOURCE_STATE_DISCONNECTED -> SOURCE_STATE_CONNECTING
bluetoothd[8977]: profiles/audio/source.c:source_connect() stream creation in progress
bluetoothd[8977]: src/service.c:change_state() 0x3d03c8: device 58:55:CA:D2:6C:3A profile a2dp-source state changed: disconnected -> connecting (0)
bluetoothd[8977]: src/adapter.c:connected_callback() hci0 device 58:55:CA:D2:6C:3A connected eir_len 22
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to 58:55:CA:D2:6C:3A
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=672
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 0 media 0 in use 0
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 2 type 0 media 0 in use 0
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 3 type 0 media 0 in use 0
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 4 type 0 media 0 in use 0
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 5 type 0 media 0 in use 0
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 6 type 0 media 0 in use 0
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 1 type 0 media 0
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 2 type 0 media 0
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 3 type 0 media 0
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 4 type 0 media 0
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 5 type 0 media 0
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() GET_CAPABILITIES request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_get_capabilities_resp() seid 6 type 0 media 0
bluetoothd[8977]: profiles/audio/a2dp.c:discover_cb() err (nil)
bluetoothd[8977]: profiles/audio/source.c:discovery_complete() Discovery complete
bluetoothd[8977]: profiles/audio/a2dp.c:setup_ref() 0x3cd550: ref=2
bluetoothd[8977]: profiles/audio/media.c:media_endpoint_async_call() Calling SelectConfiguration: name = :1.2 path = /MediaEndpoint/A2DPSink
bluetoothd[8977]: profiles/audio/a2dp.c:setup_unref() 0x3cd550: ref=1
bluetoothd[8977]: profiles/audio/a2dp.c:a2dp_config() a2dp_config: selected SEP 0x3c54b0
bluetoothd[8977]: profiles/audio/a2dp.c:setup_ref() 0x3cd550: ref=2
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_set_configuration() 0x3d7288: int_seid=2, acp_seid=1
bluetoothd[8977]: profiles/audio/a2dp.c:setup_unref() 0x3cd550: ref=1
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request succeeded
bluetoothd[8977]: profiles/audio/a2dp.c:setconf_cfm() Sink 0x3c54b0: Set_Configuration_Cfm
bluetoothd[8977]: profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name = :1.2 path = /MediaEndpoint/A2DPSink
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED
bluetoothd[8977]: profiles/audio/avdtp.c:session_cb() 
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_parse_resp() OPEN request succeeded
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected transport channel to 58:55:CA:D2:6C:3A
bluetoothd[8977]: profiles/audio/a2dp.c:open_cfm() Sink 0x3c54b0: Open_Cfm
bluetoothd[8977]: profiles/audio/a2dp.c:setup_unref() 0x3cd550: ref=0
bluetoothd[8977]: profiles/audio/a2dp.c:setup_free() 0x3cd550
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_unref() 0x3d7288: ref=1
bluetoothd[8977]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN
bluetoothd[8977]: src/service.c:change_state() 0x3d03c8: device 58:55:CA:D2:6C:3A profile a2dp-source state changed: connecting -> connected (0)
bluetoothd[8977]: src/device.c:device_profile_connected() a2dp-source Success (0)
bluetoothd[8977]: src/device.c:device_profile_connected() returning response to :1.27
bluetoothd[8977]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A profile audio-avrcp-target
bluetoothd[8977]: profiles/audio/avrcp.c:avrcp_connect() path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
bluetoothd[8977]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
bluetoothd[8977]: src/service.c:change_state() 0x3d03a8: device 58:55:CA:D2:6C:3A profile audio-avrcp-target state changed: disconnected -> connecting (0)
bluetoothd[8977]: src/service.c:btd_service_ref() 0x3d03c8: ref=3
bluetoothd[8977]: plugins/policy.c:service_cb() Added a2dp-source reconnect 1
bluetoothd[8977]: profiles/audio/source.c:source_set_state() State changed /org/bluez/hci0/dev_58_55_CA_D2_6C_3A: SOURCE_STATE_CONNECTING -> SOURCE_STATE_CONNECTED
bluetoothd[8977]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A/fd0 State=TRANSPORT_STATE_IDLE Playing=0
bluetoothd[8977]: profiles/network/connection.c:connection_connect() id 4374
bluetoothd[8977]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 58:55:CA:D2:6C:3A
bluetoothd[8977]: profiles/audio/avctp.c:init_uinput() AVRCP: uinput initialized for 58:55:CA:D2:6C:3A
bluetoothd[8977]: profiles/audio/avrcp.c:controller_init() 0x3d4588 version 0x0104
bluetoothd[8977]: src/service.c:change_state() 0x3d03a8: device 58:55:CA:D2:6C:3A profile audio-avrcp-target state changed: connecting -> connected (0)
bluetoothd[8977]: src/device.c:device_profile_connected() audio-avrcp-target Success (0)
bluetoothd[8977]: profiles/audio/player.c:media_player_controller_create() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A/player0
bluetoothd[8977]: profiles/audio/avctp.c:avctp_set_state() AVCTP Browsing Connecting
bluetoothd[8977]: profiles/audio/avrcp.c:target_init() 0x3dbf28 version 0x0104
bluetoothd[8977]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected
bluetoothd[8977]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x10, company 0x001958 len 0x0001
bluetoothd[8977]: profiles/audio/avrcp.c:avrcp_handle_get_capabilities() id=3
bluetoothd[8977]: bnep: Could not bring up bnep0: Cannot assign requested address(99)
bluetoothd[8977]: profiles/network/connection.c:bnep_conn_cb() 
bluetoothd[8977]: connect failed Input/output error
bluetoothd[8977]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x31, company 0x001958 len 0x0005
bluetoothd[8977]: profiles/audio/avctp.c:avctp_connect_browsing_cb() AVCTP Browsing: connected to 58:55:CA:D2:6C:3A
bluetoothd[8977]: profiles/audio/avctp.c:avctp_set_state() AVCTP Browsing Connected
bluetoothd[8977]: profiles/audio/player.c:media_player_set_type() Audio
bluetoothd[8977]: profiles/audio/player.c:media_player_set_subtype() None
bluetoothd[8977]: profiles/audio/player.c:media_player_set_status() paused
bluetoothd[8977]: profiles/audio/player.c:media_player_set_browsable() true
bluetoothd[8977]: profiles/audio/player.c:media_folder_create_item() /Filesystem type folder uid 0
bluetoothd[8977]: profiles/audio/player.c:media_folder_create_item() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A/player0/Filesystem
bluetoothd[8977]: profiles/audio/player.c:media_folder_create_item() /NowPlaying type folder uid 0
bluetoothd[8977]: profiles/audio/player.c:media_folder_create_item() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A/player0/NowPlaying
bluetoothd[8977]: profiles/audio/player.c:media_player_set_playlist() /NowPlaying
bluetoothd[8977]: profiles/audio/player.c:media_player_set_name() Musica
bluetoothd[8977]: src/device.c:search_cb() 58:55:CA:D2:6C:3A: No service update
bluetoothd[8977]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A err 0
bluetoothd[8977]: profiles/audio/player.c:media_player_set_setting() Repeat: alltracks
bluetoothd[8977]: profiles/audio/player.c:media_player_set_setting() Shuffle: alltracks
bluetoothd[8977]: profiles/audio/player.c:media_player_set_folder() /Filesystem number of items 7
bluetoothd[8977]: profiles/audio/player.c:media_player_set_playlist_item() 9433943860815688982
bluetoothd[8977]: profiles/audio/player.c:media_folder_create_item() (null) type audio uid 9433943860815688982
bluetoothd[8977]: profiles/audio/player.c:media_folder_create_item() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A/player0/NowPlaying/item9433943860815688982
bluetoothd[8977]: profiles/audio/player.c:media_player_set_metadata() Title: Europa (Santana)
bluetoothd[8977]: profiles/audio/player.c:media_player_set_metadata() Artist: Alessandro Ferri
bluetoothd[8977]: profiles/audio/player.c:media_player_set_metadata() Album: Laboratorio Audio
bluetoothd[8977]: profiles/audio/player.c:media_player_set_metadata() TrackNumber: 2
bluetoothd[8977]: profiles/audio/player.c:media_player_set_metadata() NumberOfTracks: 14
bluetoothd[8977]: profiles/audio/player.c:media_player_set_metadata() Duration: 251400
bluetoothd[8977]: profiles/audio/player.c:media_player_set_duration() 251400
bluetoothd[8977]: profiles/audio/player.c:media_player_set_position() 78291
bluetoothd[8977]: profiles/audio/player.c:media_player_set_status() paused
bluetoothd[8977]: profiles/audio/player.c:media_player_set_duration() 251400
bluetoothd[8977]: profiles/audio/player.c:media_player_set_position() 78291
bluetoothd[8977]: profiles/audio/player.c:media_player_set_status() paused

If you use test-network (/Bluez-5.35/test/Testnetwork) instead of the program bt-pan, this creates the same error of bt-pan, but bluetoothd not see any comments.

I have the impression that there is a conflict between the service A2DP, which is always active, and service the NAP / NAP.
How can I disable the A2DP service, for testing?

Anyone can help me?

[Stygmate]

Hello,

Can you report the content of /var/log/syslog ?
When i check mine i see it disconnect just after managing IPV6 and DHCP stuffs.
But when another client is already connected to the iphone PAN therefore it report error message with ipv6 but it negociate ipv4 with success.

[AlessandroFerri]

Hi Stygmate,
The content of /var/log/syslog at boot is:

Code: Select all

Oct 26 11:20:00 raspberry kernel: imklog 5.8.11, log source = /proc/kmsg started.
Oct 26 11:20:00 raspberry rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="1904" x-info="http://www.rsyslog.com"] start
Oct 26 11:20:00 raspberry kernel: [    0.000000] Booting Linux on physical CPU 0xf00
Oct 26 11:20:00 raspberry kernel: [    0.000000] Initializing cgroup subsys cpu
Oct 26 11:20:00 raspberry kernel: [    0.000000] Initializing cgroup subsys cpuacct
Oct 26 11:20:00 raspberry kernel: [    0.000000] Linux version 3.18.11-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.8.3 20140303 (prerelease) (crosstool-NG linaro-1.13.1+bzr2650 - Linaro GCC 2014.03) ) #781 SMP PREEMPT Tue Apr 21 18:07:59 BST 2015
Oct 26 11:20:00 raspberry kernel: [    0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
Oct 26 11:20:00 raspberry kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Oct 26 11:20:00 raspberry kernel: [    0.000000] Machine model: Raspberry Pi 2 Model B
Oct 26 11:20:00 raspberry kernel: [    0.000000] cma: Reserved 8 MiB at 0x3a800000
Oct 26 11:20:00 raspberry kernel: [    0.000000] Memory policy: Data cache writealloc
Oct 26 11:20:00 raspberry kernel: [    0.000000] On node 0 totalpages: 241664
Oct 26 11:20:00 raspberry kernel: [    0.000000] free_area_init_node: node 0, pgdat 8080f480, node_mem_map ba093000
Oct 26 11:20:00 raspberry kernel: [    0.000000]   Normal zone: 1888 pages used for memmap
Oct 26 11:20:00 raspberry kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct 26 11:20:00 raspberry kernel: [    0.000000]   Normal zone: 241664 pages, LIFO batch:31
Oct 26 11:20:00 raspberry kernel: [    0.000000] [bcm2709_smp_init_cpus] enter (8620->f3003010)
Oct 26 11:20:00 raspberry kernel: [    0.000000] [bcm2709_smp_init_cpus] ncores=4
Oct 26 11:20:00 raspberry kernel: [    0.000000] PERCPU: Embedded 10 pages/cpu @ba061000 s11456 r8192 d21312 u40960
Oct 26 11:20:00 raspberry kernel: [    0.000000] pcpu-alloc: s11456 r8192 d21312 u40960 alloc=10*4096
Oct 26 11:20:00 raspberry kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct 26 11:20:00 raspberry kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 239776
Oct 26 11:20:00 raspberry kernel: [    0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1360 bcm2708_fb.fbheight=768 bcm2709.boardrev=0xa01041 bcm2709.serial=0x1fcf81b6 smsc95xx.macaddr=B8:27:EB:CF:81:B6 bcm2708_fb.fbswap=1 bcm2709.disk_led_gpio=47 bcm2709.disk_led_active_low=0 sdhci-bcm2708.emmc_clock_freq=250000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait
Oct 26 11:20:00 raspberry kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)

…….

Oct 26 11:20:00 raspberry kernel: [    6.443570] Bluetooth: Core ver 2.19
Oct 26 11:20:00 raspberry kernel: [    6.458036] NET: Registered protocol family 31
Oct 26 11:20:00 raspberry kernel: [    6.471961] Bluetooth: HCI device and connection manager initialized
Oct 26 11:20:00 raspberry kernel: [    6.499079] Bluetooth: HCI socket layer initialized
Oct 26 11:20:00 raspberry kernel: [    6.510746] Bluetooth: L2CAP socket layer initialized
Oct 26 11:20:00 raspberry kernel: [    6.522805] Bluetooth: SCO socket layer initialized
Oct 26 11:20:00 raspberry kernel: [    6.555072] usbcore: registered new interface driver btusb
Oct 26 11:20:00 raspberry kernel: [    6.729193] usbcore: registered new interface driver rtl8192cu

……

Oct 26 11:20:01 raspberry bluetoothd[2165]: Bluetooth daemon 5.35
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112).
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Successfully dropped root privileges.
Oct 26 11:20:01 raspberry avahi-daemon[2181]: avahi-daemon 0.6.31 starting up.
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Successfully called chroot().
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Successfully dropped remaining capabilities.
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Loading service file /services/udisks.service.
Oct 26 11:20:01 raspberry bluetoothd[2165]: Starting SDP server
Oct 26 11:20:01 raspberry avahi-daemon[2181]: socket() failed: Address family not supported by protocol
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Failed to create IPv6 socket, proceeding in IPv4 only mode
Oct 26 11:20:01 raspberry avahi-daemon[2181]: socket() failed: Address family not supported by protocol
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Network interface enumeration completed.
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Registering HINFO record with values 'ARMV7L'/'LINUX'.
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Server startup complete. Host name is raspberry.local. Local service cookie is 3375307331.
Oct 26 11:20:01 raspberry avahi-daemon[2181]: Service "raspberry" (/services/udisks.service) successfully established.
Oct 26 11:20:01 raspberry kernel: [   18.257770] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Oct 26 11:20:01 raspberry kernel: [   18.257777] Bluetooth: BNEP filters: protocol multicast
Oct 26 11:20:01 raspberry kernel: [   18.257816] Bluetooth: BNEP socket layer initialized
Oct 26 11:20:01 raspberry bluetoothd[2165]: Bluetooth management interface 1.7 initialized
Oct 26 11:20:01 raspberry bluetoothd[2165]: Failed to obtain handles for "Service Changed" characteristic
Oct 26 11:20:02 raspberry pulseaudio[2148]: [pulseaudio] authkey.c: Failed to open cookie file '/var/run/pulse/.config/pulse/cookie': No such file or directory
Oct 26 11:20:02 raspberry pulseaudio[2148]: [pulseaudio] authkey.c: Failed to load authorization key '/var/run/pulse/.config/pulse/cookie': No such file or directory
Oct 26 11:20:02 raspberry pulseaudio[2148]: [pulseaudio] authkey.c: Failed to open cookie file '/var/run/pulse/.pulse-cookie': No such file or directory
Oct 26 11:20:02 raspberry pulseaudio[2148]: [pulseaudio] authkey.c: Failed to load authorization key '/var/run/pulse/.pulse-cookie': No such file or directory
Oct 26 11:20:02 raspberry pulseaudio[2148]: [pulseaudio] socket-server.c: socket(PF_INET6): Address family not supported by protocol
Oct 26 11:20:02 raspberry bluetoothd[2165]: Endpoint registered: sender=:1.2 path=/MediaEndpoint/A2DPSource
Oct 26 11:20:02 raspberry bluetoothd[2165]: Endpoint registered: sender=:1.2 path=/MediaEndpoint/A2DPSink
Oct 26 11:20:02 raspberry ntpd_intres[2104]: host name not found EAI_NODATA: 0.debian.pool.ntp.org
Oct 26 11:20:02 raspberry ntpd_intres[2104]: host name not found EAI_NODATA: 1.debian.pool.ntp.org
Oct 26 11:20:02 raspberry ntpd_intres[2104]: host name not found EAI_NODATA: 2.debian.pool.ntp.org
Oct 26 11:20:02 raspberry ntpd_intres[2104]: host name not found EAI_NODATA: 3.debian.pool.ntp.org

[AlessandroFerri]

and the content of /var/log/syslog when I run Bluetooth and bt_pan is:

Code: Select all

Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_init() sending read version command
Oct 26 11:28:06 raspberry bluetoothd[4390]: Starting SDP server
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:register_device_id() Adding device id record for 0002:1d6b:0246:0523
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:plugin_init() Loading builtin plugins
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading hostname plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading wiimote plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading autopair plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading policy plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading a2dp plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading avrcp plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading network plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading input plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading hog plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading gap plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading scanparam plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:add_plugin() Loading deviceinfo plugin
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/plugin.c:plugin_init() Loading plugins /usr/local/lib/bluetooth/plugins
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/input/suspend-none.c:suspend_init() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/manager.c:read_config() Config options: Security=true
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/main.c:main() Entering main loop
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/rfkill.c:rfkill_event() RFKILL event idx 0 type 2 op 0 soft 0 hard 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: Bluetooth management interface 1.7 initialized
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_version_complete() sending read supported commands command
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_version_complete() sending read index list command
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_commands_complete() Number of commands: 55
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_commands_complete() Number of events: 29
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_commands_complete() enabling kernel-side connection control
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_index_list_complete() Number of controllers: 1
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_index_list_complete() Found index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:index_added() index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_new() System name: BlueZ 5.35
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_new() Major class: 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_new() Minor class: 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_new() Modalias: usb:v1D6Bp0246d0523
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_new() Discoverable timeout: 180 seconds
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_new() Pairable timeout: 0 seconds
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:index_added() sending read info command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:read_info_complete() index 0 status 0x00
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:clear_uuids() sending clear uuids command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:clear_devices() sending clear devices command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_add() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10001
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001800-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:add_uuid() sending add uuid command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/gatt-database.c:gatt_db_service_added() GATT Service added to local database
Oct 26 11:28:06 raspberry bluetoothd[4390]: Failed to obtain handles for "Service Changed" characteristic
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_add() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10002
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000007-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001801-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:add_uuid() sending add uuid command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/gatt-database.c:gatt_db_service_added() GATT Service added to local database
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:media_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: plugins/hostname.c:hostname_probe() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/manager.c:panu_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/server.c:server_register() Registered interface org.bluez.NetworkServer1 on path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/manager.c:gn_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/manager.c:nap_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_controller_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_add() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10003
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110f-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:add_uuid() sending add uuid command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_target_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_add() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10004
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110c-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:adapter_service_insert() /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:add_uuid() sending add uuid command for index 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_sink_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_source_server_probe() path /org/bluez/hci0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:btd_adapter_unblock_address() hci0 00:00:00:00:00:00
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:get_ltk_info() 94:E9:6A:33:30:3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_create_from_storage() address 94:E9:6A:33:30:3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() address 94:E9:6A:33:30:3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_94_E9_6A_33_30_3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:btd_device_set_temporary() temporary 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_set_bonded() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:get_ltk_info() 34:FC:EF:47:A0:25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_create_from_storage() address 34:FC:EF:47:A0:25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() address 34:FC:EF:47:A0:25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:btd_device_set_temporary() temporary 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_set_bonded() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:get_ltk_info() 90:B9:31:A4:A7:48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_create_from_storage() address 90:B9:31:A4:A7:48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() address 90:B9:31:A4:A7:48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:btd_device_set_temporary() temporary 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_set_bonded() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:get_ltk_info() 58:55:CA:D2:6C:3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_create_from_storage() address 58:55:CA:D2:6C:3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() address 58:55:CA:D2:6C:3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:btd_device_set_temporary() temporary 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_set_bonded() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:get_ltk_info() 20:C9:D0:96:D7:0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_create_from_storage() address 20:C9:D0:96:D7:0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() address 20:C9:D0:96:D7:0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_20_C9_D0_96_D7_0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:btd_device_set_temporary() temporary 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_set_bonded() 
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:load_link_keys() hci0 keys 5 debug_keys 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:load_ltks() hci0 keys 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:load_irks() hci0 irks 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/adapter.c:load_conn_params() hci0 conn params 0
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_probe_profiles() Probing profiles for device 94:E9:6A:33:30:3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_94_E9_6A_33_30_3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_94_E9_6A_33_30_3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46d918: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46d918: device 94:E9:6A:33:30:3F profile avrcp-controller state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_94_E9_6A_33_30_3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x461c50: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x461c50: device 94:E9:6A:33:30:3F profile audio-avrcp-target state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_94_E9_6A_33_30_3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_94_E9_6A_33_30_3F
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x461c70: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x461c70: device 94:E9:6A:33:30:3F profile a2dp-source state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_probe_profiles() Probing profiles for device 34:FC:EF:47:A0:25
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/gap/gas.c:gap_driver_probe() GAP profile probe (34:FC:EF:47:A0:25)
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a7e0: device 34:FC:EF:47:A0:25 profile gap-profile state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_34_FC_EF_47_A0_25 id 4373
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:create_peer() Registered interface org.bluez.Network1 on path /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46c448: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() id 4373 registered
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46c448: device 34:FC:EF:47:A0:25 profile network-panu state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_34_FC_EF_47_A0_25 id 4374
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46c4d8: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() id 4374 registered
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46c4d8: device 34:FC:EF:47:A0:25 profile network-nap state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46c530: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46c530: device 34:FC:EF:47:A0:25 profile avrcp-controller state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a688: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a688: device 34:FC:EF:47:A0:25 profile audio-avrcp-target state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_34_FC_EF_47_A0_25
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a6a8: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a6a8: device 34:FC:EF:47:A0:25 profile a2dp-source state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_probe_profiles() Probing profiles for device 90:B9:31:A4:A7:48
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_90_B9_31_A4_A7_48 id 4374
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:create_peer() Registered interface org.bluez.Network1 on path /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a738: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() id 4374 registered
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a738: device 90:B9:31:A4:A7:48 profile network-nap state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a8a0: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a8a0: device 90:B9:31:A4:A7:48 profile avrcp-controller state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a918: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a918: device 90:B9:31:A4:A7:48 profile audio-avrcp-target state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a938: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a938: device 90:B9:31:A4:A7:48 profile a2dp-source state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_probe_profiles() Probing profiles for device 58:55:CA:D2:6C:3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A id 4374
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:create_peer() Registered interface org.bluez.Network1 on path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x46a9c8: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() id 4374 registered
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x46a9c8: device 58:55:CA:D2:6C:3A profile network-nap state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x462500: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x462500: device 58:55:CA:D2:6C:3A profile avrcp-controller state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_target_probe() path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x462590: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x462590: device 58:55:CA:D2:6C:3A profile audio-avrcp-target state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_source_probe() path /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/source.c:source_init() /org/bluez/hci0/dev_58_55_CA_D2_6C_3A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x4625b0: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x4625b0: device 58:55:CA:D2:6C:3A profile a2dp-source state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/device.c:device_probe_profiles() Probing profiles for device 20:C9:D0:96:D7:0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() /org/bluez/hci0/dev_20_C9_D0_96_D7_0A id 4375
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:create_peer() Registered interface org.bluez.Network1 on path /org/bluez/hci0/dev_20_C9_D0_96_D7_0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:btd_service_ref() 0x4626c0: ref=2
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_register() id 4375 registered
Oct 26 11:28:06 raspberry bluetoothd[4390]: src/service.c:change_state() 0x4626c0: device 20:C9:D0:96:D7:0A profile network-gn state changed: unavailable -> disconnected (0)
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/avrcp.c:avrcp_controller_probe() path /org/bluez/hci0/dev_20_C9_D0_96_D7_0A
Oct 26 11:28:06 raspberry bluetoothd[4390]: profiles/audio/control.c:control_init() Registered interface org.bluez.MediaControl1 on path /org/bluez/hci0/dev_20_C9_D0_96_D7_0A
Oct 26 11:28:06 raspberry rsyslogd-2177: imuxsock begins to drop messages from pid 4390 due to rate-limiting
Oct 26 11:28:59 raspberry rsyslogd-2177: imuxsock lost 55 messages from pid 4390 due to rate-limiting
Oct 26 11:28:59 raspberry bluetoothd[4390]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/adapter.c:dev_class_changed_callback() Class: 0x0c0000
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/adapter.c:property_set_mode_complete() Success (0x00)
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/adapter.c:new_settings_callback() Settings: 0x000002db
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/adapter.c:settings_changed() Changed settings: 0x00000001
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/adapter.c:trigger_passive_scanning() 
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/agent.c:agent_ref() 0x46b050: ref=1
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/agent.c:register_agent() agent :1.14
Oct 26 11:29:00 raspberry bluetoothd[4390]: src/agent.c:add_default_agent() Default agent set to :1.14 /test/agent
Oct 26 11:29:01 raspberry /USR/SBIN/CRON[4625]: (root) CMD (/bin/sh /root/shairportsync-watchdog.sh > /dev/null)
Oct 26 11:30:01 raspberry /USR/SBIN/CRON[4885]: (root) CMD (/bin/sh /root/shairportsync-watchdog.sh > /dev/null)
Oct 26 11:30:01 raspberry /USR/SBIN/CRON[4884]: (root) CMD (/bin/sh /root/avahi-watchdog.sh > /dev/null)
Oct 26 11:31:01 raspberry /USR/SBIN/CRON[5177]: (root) CMD (/bin/sh /root/shairportsync-watchdog.sh > /dev/null)
Oct 26 11:31:32 raspberry dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
Oct 26 11:31:37 raspberry dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 12
Oct 26 11:31:49 raspberry dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 16
Oct 26 11:32:01 raspberry /USR/SBIN/CRON[5394]: (root) CMD (/bin/sh /root/shairportsync-watchdog.sh > /dev/null)
Oct 26 11:32:05 raspberry dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 12
Oct 26 11:32:17 raspberry dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 12
Oct 26 11:32:29 raspberry dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 4
Oct 26 11:32:33 raspberry dhclient: No DHCPOFFERS received.
Oct 26 11:32:33 raspberry dhclient: No working leases in persistent database - sleeping.
Oct 26 11:33:01 raspberry /USR/SBIN/CRON[5605]: (root) CMD (/bin/sh /root/shairportsync-watchdog.sh > /dev/null)
Oct 26 11:33:09 raspberry bluetoothd[4390]: src/adapter.c:connected_callback() hci0 device 90:B9:31:A4:A7:48 connected eir_len 5
Oct 26 11:33:10 raspberry bluetoothd[4390]: profiles/audio/avctp.c:avctp_confirm_cb() AVCTP: incoming connect from 90:B9:31:A4:A7:48
Oct 26 11:33:10 raspberry bluetoothd[4390]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
Oct 26 11:33:10 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:confirm_cb() AVDTP: incoming connect from 90:B9:31:A4:A7:48
Oct 26 11:33:11 raspberry bluetoothd[4390]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 90:B9:31:A4:A7:48
Oct 26 11:33:11 raspberry bluetoothd[4390]: profiles/audio/avctp.c:init_uinput() AVRCP: uinput initialized for 90:B9:31:A4:A7:48
Oct 26 11:33:11 raspberry kernel: [  792.533189] input: 90:B9:31:A4:A7:48 as /devices/virtual/input/input4
….
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/avdtp.c:session_cb() 
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/avdtp.c:avdtp_parse_cmd() Received SUSPEND_CMD
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:suspend_ind() Sink 0x462a70: Suspend_Ind
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: STREAMING -> OPEN
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/source.c:source_set_state() State changed /org/bluez/hci0/dev_90_B9_31_A4_A7_48: SOURCE_STATE_PLAYING -> SOURCE_STATE_CONNECTED
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_90_B9_31_A4_A7_48/fd0 State=TRANSPORT_STATE_ACTIVE Playing=0
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/transport.c:media_transport_remove_owner() Transport /org/bluez/hci0/dev_90_B9_31_A4_A7_48/fd0 Owner :1.2
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/transport.c:media_owner_free() Owner :1.2
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_90_B9_31_A4_A7_48/fd0: TRANSPORT_STATE_ACTIVE -> TRANSPORT_STATE_IDLE
Oct 26 11:33:44 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0x462a70 unlocked
Oct 26 11:34:01 raspberry /USR/SBIN/CRON[5770]: (root) CMD (/bin/sh /root/shairportsync-watchdog.sh > /dev/null)
Oct 26 11:34:31 raspberry bluetoothd[4390]: src/device.c:connect_profiles() /org/bluez/hci0/dev_90_B9_31_A4_A7_48 (all), client :1.16
Oct 26 11:34:31 raspberry bluetoothd[4390]: profiles/audio/a2dp.c:a2dp_source_connect() path /org/bluez/hci0/dev_90_B9_31_A4_A7_48
Oct 26 11:34:31 raspberry bluetoothd[4390]: a2dp-source profile connect failed for 90:B9:31:A4:A7:48: Operation already in progress
Oct 26 11:34:31 raspberry bluetoothd[4390]: profiles/network/connection.c:connection_connect() id 4374
Oct 26 11:34:31 raspberry bluetoothd[4390]: bnep: Could not bring up bnep0: Cannot assign requested address(99)
Oct 26 11:34:31 raspberry bluetoothd[4390]: profiles/network/connection.c:bnep_conn_cb() 
Oct 26 11:34:31 raspberry bluetoothd[4390]: connect failed Input/output error
Oct 26 11:34:31 raspberry avahi-daemon[2191]: Withdrawing workstation service for bnep0.
Oct 26 11:34:31 raspberry dhclient: Internet Systems Consortium DHCP Client 4.2.2
Oct 26 11:34:31 raspberry dhclient: Copyright 2004-2011 Internet Systems Consortium.
Oct 26 11:34:31 raspberry dhclient: All rights reserved.
Oct 26 11:34:31 raspberry dhclient: For info, please visit https://www.isc.org/software/dhcp/
Oct 26 11:34:31 raspberry dhclient: 
Oct 26 11:34:31 raspberry dhclient: Bind socket to interface: No such device
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_playlist_item() 6645257676377269771
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Title: Me, Myself and I
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Artist: Beyoncé
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Album: Dangerously In Love
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() TrackNumber: 1
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() NumberOfTracks: 15
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Genre: Pop
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Duration: 301186
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_duration() 301186
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_position() 3758
Oct 26 11:34:32 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_status() paused
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_playlist_item() 6645257676377269771
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Title: Me, Myself and I
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Artist: Beyoncé
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Album: Dangerously In Love
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() TrackNumber: 1
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() NumberOfTracks: 15
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Genre: Pop
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_metadata() Duration: 301186
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_duration() 301186
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_position() 3758
Oct 26 11:34:33 raspberry bluetoothd[4390]: profiles/audio/player.c:media_player_set_status() paused
……
Oct 26 11:35:31 raspberry bluetoothd[4390]: Terminating
….

[Stygmate]

Re-hello,

...So...
I have made a lot of try and after doing:

Code: Select all

sudo apt-get install pulseaudio-module-bluetooth

it seems to work for pan network ....

let me know if it work for you !

[AlessandroFerri]

Re hello,
I installed PulseAudio 5.0 and Bluez 5.35 by following these steps:
First I install the dependencies:

Code: Select all

sudo apt-get install -y libusb-dev libdbus-1-dev libglib2.0-dev libudev-dev libical-dev libreadline-dev libltdl-dev libsamplerate0-dev libsndfile1-dev libasound2-dev libavahi-client-dev libspeexdsp-dev liborc-0.4-dev intltool libtdb-dev libssl-dev libjson0-dev libsbc-dev libcap-dev libspeex-dev

and download and unpack the PA5 source code. I do my building in the user home directory

Code: Select all

cd
wget : http://freedesktop.org/software/pulseaudio/releases/pulseaudio-5.0.tar.xz
tar xvf pulseaudio-5.0.tar.xz

after

Code: Select all

cd pulseaudio-5.0
./configure --sysconfdir=/etc --localstatedir=/var --disable-bluez4
make
sudo make install

and at the end

Code: Select all

sudo ldconfig

and start PA5 in system wide mode.
For Bluez 5.35, firstly, I install libraries:

Code: Select all

sudo apt-get install libdbus-1-dev libdbus-glib-1-dev libglib2.0-dev libical-dev libreadline-dev libudev-dev libusb-dev make

Then, download the source:

Code: Select all

wget https://www.kernel.org/pub/linux/bluetooth/bluez-5.35.tar.xz
tar xvf bluez-5.35.tar.xz

To build it, do:

Code: Select all

cd bluez-5.35
./configure --disable-systemd
make
sudo make install

you think I have forgotten the module?

Code: Select all

pulseaudio-module-bluetooth

If I now install the module with:

Code: Select all

sudo apt-get install pulseaudio-module-bluetooth

I install the old version and is not good.

[Stygmate]

Hello,

I use bluez 5.23 the one in the actual Jessie Raspbian...
I also removed my iphone from associated device with bluetoothctl and reassociated it...
A2DP still not work but PAN is.

...

Maybe some specialist will come to rescue you.

Stygmate.