Bug #206579 “Bluetooth CUPS backend takes infinite time on devic...” : Bugs : bluez package : Ubuntu

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-03-25:

#1

This problem makes also the impression that bug 165197 is still there, even on small networks.

Changed in bluez-utils:
importance:	Undecided → Medium
milestone:	none → ubuntu-8.04

Revision history for this message

Steve Langasek (vorlon) wrote on 2008-03-26:

#2

$ time /usr/lib/cups/backend/bluetooth

real 0m10.252s
user 0m0.004s
sys 0m0.000s
$

So what's different between your system and mine?

What does strace show the backend is doing during this time?

I note that if I have my bluetooth interface disabled, the bluetooth backend returns immediately.

I don't think 10 seconds is too long to wait for network discovery here; but of course, that's not currently how long it's taking for you.

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-03-26:

#3

Download full text (11.8 KiB)

Now I have done

strace /usr/lib/cups/backend/bluetooth

and got the output shown below. The strace output came together in a few seconds (< 30 sec) and then it was simply hanging at the "poll(" in the end of the shown output. So in the middle of that poll the backend was hanging indefinitely. The "<unfinished ...>" was added when I have killed the backend with Ctrl + C after several minutes (~10 min).

Till

-------------------------

execve("/usr/lib/cups/backend/bluetooth", ["/usr/lib/cups/backend/bluetooth"], [/* 20 vars */]) = 0
brk(0) = 0x60b000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfeb000
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfe9000
access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=115877, ...}) = 0
mmap(NULL, 115877, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f5acbfcc000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/libdbus-1.so.3", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0pd\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=248432, ...}) = 0
mmap(NULL, 2344488, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acbb92000
mprotect(0x7f5acbbcd000, 2097152, PROT_NONE) = 0
mmap(0x7f5acbdcd000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3b000) = 0x7f5acbdcd000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/libglib-2.0.so.0", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240M\1\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=775416, ...}) = 0
mmap(NULL, 2872584, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acb8d4000
mprotect(0x7f5acb990000, 2097152, PROT_NONE) = 0
mmap(0x7f5acbb90000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xbc000) = 0x7f5acbb90000
mmap(0x7f5acbb91000, 1288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f5acbb91000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/libbluetooth.so.2", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0Pn\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=99800, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfcb000
mmap(NULL, 2195064, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acb6bc000
mprotect(0x7f5acb6d2000, 2097152, PROT_NONE) = 0
mmap(0x7f5acb8d2000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7f5acb8d2000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/libc.so.6", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0...

Now I have done

strace /usr/lib/cups/backend/bluetooth

and got the output shown below. The strace output came together in a few seconds (< 30 sec) and then it was simply hanging at the "poll(" in the end of the shown output. So in the middle of that poll the backend was hanging indefinitely. The "<unfinished ...>" was added when I have killed the backend with Ctrl + C after several minutes (~10 min).

Till

-------------------------

execve("/usr/lib/cups/backend/bluetooth", ["/usr/lib/cups/backend/bluetooth"], [/* 20 vars */]) = 0
brk(0)                                  = 0x60b000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfeb000
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfe9000
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY)      = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=115877, ...}) = 0
mmap(NULL, 115877, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f5acbfcc000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libdbus-1.so.3", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0pd\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=248432, ...}) = 0
mmap(NULL, 2344488, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acbb92000
mprotect(0x7f5acbbcd000, 2097152, PROT_NONE) = 0
mmap(0x7f5acbdcd000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3b000) = 0x7f5acbdcd000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libglib-2.0.so.0", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240M\1\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=775416, ...}) = 0
mmap(NULL, 2872584, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acb8d4000
mprotect(0x7f5acb990000, 2097152, PROT_NONE) = 0
mmap(0x7f5acbb90000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xbc000) = 0x7f5acbb90000
mmap(0x7f5acbb91000, 1288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f5acbb91000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libbluetooth.so.2", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0Pn\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=99800, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfcb000
mmap(NULL, 2195064, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acb6bc000
mprotect(0x7f5acb6d2000, 2097152, PROT_NONE) = 0
mmap(0x7f5acb8d2000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7f5acb8d2000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/lib/libc.so.6", O_RDONLY)        = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\342"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=1436976, ...}) = 0
mmap(NULL, 3543640, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acb35a000
mprotect(0x7f5acb4b2000, 2097152, PROT_NONE) = 0
mmap(0x7f5acb6b2000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x158000) = 0x7f5acb6b2000
mmap(0x7f5acb6b7000, 16984, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f5acb6b7000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libpcre.so.3", O_RDONLY) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\23\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=154200, ...}) = 0
mmap(NULL, 2249472, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acb134000
mprotect(0x7f5acb159000, 2097152, PROT_NONE) = 0
mmap(0x7f5acb359000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x25000) = 0x7f5acb359000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/lib/libselinux.so.1", O_RDONLY)  = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240Q\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=109368, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfca000
mmap(NULL, 2209176, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acaf18000
mprotect(0x7f5acaf31000, 2097152, PROT_NONE) = 0
mmap(0x7f5acb131000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19000) = 0x7f5acb131000
mmap(0x7f5acb133000, 1432, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f5acb133000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/lib/libdl.so.2", O_RDONLY)       = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \16\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=14624, ...}) = 0
mmap(NULL, 2109728, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f5acad14000
mprotect(0x7f5acad16000, 2097152, PROT_NONE) = 0
mmap(0x7f5acaf16000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f5acaf16000
close(3)                                = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfc9000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfc8000
arch_prctl(ARCH_SET_FS, 0x7f5acbfc8780) = 0
mprotect(0x7f5acb6b2000, 12288, PROT_READ) = 0
munmap(0x7f5acbfcc000, 115877)          = 0
brk(0)                                  = 0x60b000
brk(0x62c000)                           = 0x62c000
open("/etc/selinux/config", O_RDONLY)   = -1 ENOENT (No such file or directory)
statfs("/selinux", 0x7fffd3fec5e0)      = -1 ENOENT (No such file or directory)
open("/proc/mounts", O_RDONLY)          = 3
fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f5acbfe8000
read(3, "rootfs / rootfs rw 0 0\nnone /sys"..., 1024) = 1024
read(3, "efault_permissions,allow_other 0"..., 1024) = 312
read(3, "", 1024)                       = 0
close(3)                                = 0
munmap(0x7f5acbfe8000, 4096)            = 0
rt_sigaction(SIGPIPE, {SIG_IGN}, {SIG_DFL}, 8) = 0
socket(PF_FILE, SOCK_STREAM, 0)         = 3
connect(3, {sa_family=AF_FILE, path="/var/run/dbus/system_bus_socket"}, 33) = 0
fcntl(3, F_GETFL)                       = 0x2 (flags O_RDWR)
fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK)    = 0
fcntl(3, F_GETFD)                       = 0
fcntl(3, F_SETFD, FD_CLOEXEC)           = 0
geteuid()                               = 1000
rt_sigaction(SIGPIPE, {SIG_IGN}, {SIG_IGN}, 8) = 0
poll([{fd=3, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1
write(3, "\0", 1)                       = 1
write(3, "AUTH EXTERNAL 31303030\r\n", 24) = 24
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, -1) = 1
read(3, "OK 5bc05bca70d9025efa048d5347e96"..., 2048) = 37
poll([{fd=3, events=POLLOUT, revents=POLLOUT}], 1, -1) = 1
write(3, "BEGIN\r\n", 7)                = 7
poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, -1) = 1
writev(3, [{"l\1\0\1\0\0\0\0\1\0\0\0n\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 128}, {"", 0}], 2) = 128
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\f\0\0\0\1\0\0\0=\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 264
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
fstat(3, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
fcntl(3, F_GETFL)                       = 0x802 (flags O_RDWR|O_NONBLOCK)
writev(3, [{"l\1\0\1\16\0\0\0\2\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org"..., 144}, {"\t\0\0\0org.bluez\0", 14}], 2) = 158
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\4\0\0\0\3\0\0\0=\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 84
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\0\0\0\0\3\0\0\0g\0\0\0\1\1o\0\n\0\0\0/org/blu"..., 120}, {"", 0}], 2) = 120
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\24\0\0\0\"\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 84
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1Z\0\0\0\4\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/f"..., 144}, {"U\0\0\0type=\'signal\',interface=\'org"..., 90}], 2) = 234
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\0\0\0\0\4\0\0\0005\0\0\0\6\1s\0\7\0\0\0:1.7325"..., 2048) = 72
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\0\0\0\0\5\0\0\0|\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 144}, {"", 0}], 2) = 144
poll([{fd=3, events=POLLIN}], 1, 0)     = 0
writev(3, [{"l\1\0\1\0\0\0\0\6\0\0\0j\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 128}, {"", 0}], 2) = 128
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\0\0\0\0$\0\0\0&\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 56
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 24993) = 1
read(3, "l\2\1\1b\0\0\0%\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 282
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\26\0\0\0\7\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:02:76:01:75:BA\0", 22}], 2) = 158
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\r\0\0\0\'\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 77
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\26\0\0\0\10\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/b"..., 136}, {"\21\0\0\00000:04:61:82:97:9C\0", 22}], 2) = 158
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\r\0\0\0(\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 77
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\26\0\0\0\t\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:04:61:82:0B:10\0", 22}], 2) = 158
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\f\0\0\0)\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 76
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\26\0\0\0\n\0\0\0o\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 128}, {"\21\0\0\00000:04:61:82:0B:10\0", 22}], 2) = 150
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\33\0\0\0*\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 91
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1A\0\0\0\v\0\0\0x\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 136}, {"\21\0\0\00000:04:61:82:0B:10\0\0\0$\0\0\0000000"..., 65}], 2) = 201
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\4\1\1\0\0\0\0+\0\0\0f\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 120
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 24987) = 1
read(3, "l\3\1\1\21\0\0\0-\0\0\0^\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 129
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
writev(3, [{"l\1\0\1\26\0\0\0\f\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:17:83:88:09:03\0", 22}], 2) = 158
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\20\0\0\0.\0\0\0.\0\0\0\6\1s\0\7\0\0\0:1.7325\0"..., 2048) = 80
read(3, 0x60d1b0, 2048)                 = -1 EAGAIN (Resource temporarily unavailable)
poll( <unfinished ...>

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-03-26:

#4

My system is 64-bit (amd64) Hardy, updated yesterday and rebooted to run the current kernel.

till@till-laptop:~$ uname -a
Linux till-laptop 2.6.24-12-generic #1 SMP Wed Mar 12 22:31:43 UTC 2008 x86_64 GNU/Linux
till@till-laptop:~$ lspci
00:00.0 Host bridge: Intel Corporation Mobile 945GM/PM/GMS, 943/940GML and 945GT Express Memory Controller Hub (rev 03)
00:02.0 VGA compatible controller: Intel Corporation Mobile 945GM/GMS, 943/940GML Express Integrated Graphics Controller (rev 03)
00:02.1 Display controller: Intel Corporation Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics Controller (rev 03)
00:1b.0 Audio device: Intel Corporation 82801G (ICH7 Family) High Definition Audio Controller (rev 02)
00:1c.0 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 1 (rev 02)
00:1c.1 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 2 (rev 02)
00:1c.2 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 3 (rev 02)
00:1c.3 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 4 (rev 02)
00:1d.0 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #1 (rev 02)
00:1d.1 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #2 (rev 02)
00:1d.2 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #3 (rev 02)
00:1d.3 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #4 (rev 02)
00:1d.7 USB Controller: Intel Corporation 82801G (ICH7 Family) USB2 EHCI Controller (rev 02)
00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev e2)
00:1f.0 ISA bridge: Intel Corporation 82801GBM (ICH7-M) LPC Interface Bridge (rev 02)
00:1f.1 IDE interface: Intel Corporation 82801G (ICH7 Family) IDE Controller (rev 02)
00:1f.2 IDE interface: Intel Corporation 82801GBM/GHM (ICH7 Family) SATA IDE Controller (rev 02)
00:1f.3 SMBus: Intel Corporation 82801G (ICH7 Family) SMBus Controller (rev 02)
06:00.0 Network controller: Intel Corporation PRO/Wireless 3945ABG Network Connection (rev 02)
07:00.0 Ethernet controller: Marvell Technology Group Ltd. 88E8036 PCI-E Fast Ethernet Controller (rev 16)
09:04.0 CardBus bridge: Texas Instruments PCIxx12 Cardbus Controller
09:04.1 FireWire (IEEE 1394): Texas Instruments PCIxx12 OHCI Compliant IEEE 1394 Host Controller
09:04.2 Mass storage controller: Texas Instruments 5-in-1 Multimedia Card Reader (SD/MMC/MS/MS PRO/xD)
till@till-laptop:~$

My system is 64-bit (amd64) Hardy, updated yesterday and rebooted to run the current kernel.

till@till-laptop:~$ uname -a
Linux till-laptop 2.6.24-12-generic #1 SMP Wed Mar 12 22:31:43 UTC 2008 x86_64 GNU/Linux
till@till-laptop:~$ lspci
00:00.0 Host bridge: Intel Corporation Mobile 945GM/PM/GMS, 943/940GML and 945GT Express Memory Controller Hub (rev 03)
00:02.0 VGA compatible controller: Intel Corporation Mobile 945GM/GMS, 943/940GML Express Integrated Graphics Controller (rev 03)
00:02.1 Display controller: Intel Corporation Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics Controller (rev 03)
00:1b.0 Audio device: Intel Corporation 82801G (ICH7 Family) High Definition Audio Controller (rev 02)
00:1c.0 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 1 (rev 02)
00:1c.1 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 2 (rev 02)
00:1c.2 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 3 (rev 02)
00:1c.3 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 4 (rev 02)
00:1d.0 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #1 (rev 02)
00:1d.1 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #2 (rev 02)
00:1d.2 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #3 (rev 02)
00:1d.3 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #4 (rev 02)
00:1d.7 USB Controller: Intel Corporation 82801G (ICH7 Family) USB2 EHCI Controller (rev 02)
00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev e2)
00:1f.0 ISA bridge: Intel Corporation 82801GBM (ICH7-M) LPC Interface Bridge (rev 02)
00:1f.1 IDE interface: Intel Corporation 82801G (ICH7 Family) IDE Controller (rev 02)
00:1f.2 IDE interface: Intel Corporation 82801GBM/GHM (ICH7 Family) SATA IDE Controller (rev 02)
00:1f.3 SMBus: Intel Corporation 82801G (ICH7 Family) SMBus Controller (rev 02)
06:00.0 Network controller: Intel Corporation PRO/Wireless 3945ABG Network Connection (rev 02)
07:00.0 Ethernet controller: Marvell Technology Group Ltd. 88E8036 PCI-E Fast Ethernet Controller (rev 16)
09:04.0 CardBus bridge: Texas Instruments PCIxx12 Cardbus Controller
09:04.1 FireWire (IEEE 1394): Texas Instruments PCIxx12 OHCI Compliant IEEE 1394 Host Controller
09:04.2 Mass storage controller: Texas Instruments 5-in-1 Multimedia Card Reader (SD/MMC/MS/MS PRO/xD)
till@till-laptop:~$

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-03-26:

#5

Download full text (9.4 KiB)

"sudo lsusb -vvv" output of my Bluetooth controller below. Note that the controller is supported as I can actually print via Bluetooth and also use a Bluetooth mouse with my computer.

--------------------------

Bus 004 Device 002: ID 044e:300d Alps Electric Co., Ltd
Device Descriptor:
  bLength 18
  bDescriptorType 1
  bcdUSB 2.00
  bDeviceClass 224 Wireless
  bDeviceSubClass 1 Radio Frequency
  bDeviceProtocol 1 Bluetooth
  bMaxPacketSize0 64
  idVendor 0x044e Alps Electric Co., Ltd
  idProduct 0x300d
  bcdDevice 19.15
  iManufacturer 1 ALPS
  iProduct 2 UGX
  iSerial 3 0116f414
  bNumConfigurations 1
  Configuration Descriptor:
    bLength 9
    bDescriptorType 2
    wTotalLength 193
    bNumInterfaces 3
    bConfigurationValue 1
    iConfiguration 0
    bmAttributes 0x80
      (Bus Powered)
    MaxPower 100mA
    Interface Descriptor:
      bLength 9
      bDescriptorType 4
      bInterfaceNumber 0
      bAlternateSetting 0
      bNumEndpoints 3
      bInterfaceClass 224 Wireless
      bInterfaceSubClass 1 Radio Frequency
      bInterfaceProtocol 1 Bluetooth
      iInterface 0
      Endpoint Descriptor:
        bLength 7
        bDescriptorType 5
        bEndpointAddress 0x81 EP 1 IN
        bmAttributes 3
          Transfer Type Interrupt
          Synch Type None
          Usage Type Data
        wMaxPacketSize 0x0010 1x 16 bytes
        bInterval 1
      Endpoint Descriptor:
        bLength 7
        bDescriptorType 5
        bEndpointAddress 0x02 EP 2 OUT
        bmAttributes 2
          Transfer Type Bulk
          Synch Type None
          Usage Type Data
        wMaxPacketSize 0x0040 1x 64 bytes
        bInterval 1
      Endpoint Descriptor:
        bLength 7
        bDescriptorType 5
        bEndpointAddress 0x82 EP 2 IN
        bmAttributes 2
          Transfer Type Bulk
          Synch Type None
          Usage Type Data
        wMaxPacketSize 0x0040 1x 64 bytes
        bInterval 1
    Interface Descriptor:
      bLength 9
      bDescriptorType 4
      bInterfaceNumber 1
      bAlternateSetting 0
      bNumEndpoints 2
      bInterfaceClass 224 Wireless
      bInterfaceSubClass 1 Radio Frequency
      bInterfaceProtocol 1 Bluetooth
      iInterface 0
      Endpoint Descriptor:
        bLength 7
        bDescriptorType 5
        bEndpointAddress 0x03 EP 3 OUT
        bmAttributes 1
          Transfer Type Isochronous
          Synch Type None
          Usage Type Data
        wMaxPacketSize ...

"sudo lsusb -vvv" output of my Bluetooth controller below. Note that the controller is supported as I can actually print via Bluetooth and also use a Bluetooth mouse with my computer.

--------------------------

Bus 004 Device 002: ID 044e:300d Alps Electric Co., Ltd 
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass          224 Wireless
  bDeviceSubClass         1 Radio Frequency
  bDeviceProtocol         1 Bluetooth
  bMaxPacketSize0        64
  idVendor           0x044e Alps Electric Co., Ltd
  idProduct          0x300d 
  bcdDevice           19.15
  iManufacturer           1 ALPS
  iProduct                2 UGX
  iSerial                 3 0116f414
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength          193
    bNumInterfaces          3
    bConfigurationValue     1
    iConfiguration          0 
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0010  1x 16 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0040  1x 64 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x82  EP 2 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0040  1x 64 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0000  1x 0 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0000  1x 0 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       1
      bNumEndpoints           2
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0009  1x 9 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0009  1x 9 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       2
      bNumEndpoints           2
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0011  1x 17 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0011  1x 17 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       3
      bNumEndpoints           2
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0019  1x 25 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0019  1x 25 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       4
      bNumEndpoints           2
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0021  1x 33 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0021  1x 33 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       5
      bNumEndpoints           2
      bInterfaceClass       224 Wireless
      bInterfaceSubClass      1 Radio Frequency
      bInterfaceProtocol      1 Bluetooth
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0031  1x 49 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            1
          Transfer Type            Isochronous
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0031  1x 49 bytes
        bInterval               1
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        2
      bAlternateSetting       0
      bNumEndpoints           0
      bInterfaceClass       254 Application Specific Interface
      bInterfaceSubClass      1 Device Firmware Update
      bInterfaceProtocol      0 
      iInterface              0 
      ** UNRECOGNIZED:  07 21 07 88 13 ff 03
Device Status:     0x0000
  (Bus Powered)

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-03-26:

#6

Download full text (18.7 KiB)

On another machine (Hardy 32-bit i386) the Bluetooth backend needs 30 seconds and in the end it returns info of device which it has cached somewhere as for the tests I have no Bluetooth device turned on.

-------------------------

till@till-laptop:~$ uname -a
Linux till-laptop 2.6.24-11-generic #1 SMP Fri Feb 29 22:08:31 UTC 2008 i686 GNU/Linux
till@till-laptop:~$

-------------------------

execve("/usr/lib/cups/backend/bluetooth", ["/usr/lib/cups/backend/bluetooth"], [/* 22 vars */]) = 0
brk(0) = 0x8052000
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
mmap2(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7eea000
access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=114461, ...}) = 0
mmap2(NULL, 114461, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7ece000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/libdbus-1.so.3", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000M\0\000"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=214620, ...}) = 0
mmap2(NULL, 217920, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7e98000
mmap2(0xb7ecc000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x33) = 0xb7ecc000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/libglib-2.0.so.0", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\26\1"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=712748, ...}) = 0
mmap2(NULL, 716580, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7de9000
mmap2(0xb7e97000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xad) = 0xb7e97000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/usr/lib/libbluetooth.so.2", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\220G\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=78968, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7de8000
mmap2(NULL, 77756, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7dd5000
mmap2(0xb7de7000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x12) = 0xb7de7000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/tls/i686/cmov/libc.so.6", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260e\1"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=1364388, ...}) = 0
mmap2(NULL, 1369712, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7c86000
mmap2(0xb7dcf000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x149) = 0xb7dcf000
mmap2(0xb7dd2000, 9840, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7dd2000
close(3) ...

On another machine (Hardy 32-bit i386) the Bluetooth backend needs 30 seconds and in the end it returns info of device which it has cached somewhere as for the tests I have no Bluetooth device turned on.

-------------------------

till@till-laptop:~$ uname -a
Linux till-laptop 2.6.24-11-generic #1 SMP Fri Feb 29 22:08:31 UTC 2008 i686 GNU/Linux
till@till-laptop:~$

-------------------------

execve("/usr/lib/cups/backend/bluetooth", ["/usr/lib/cups/backend/bluetooth"], [/* 22 vars */]) = 0
brk(0)                                  = 0x8052000
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
mmap2(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7eea000
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY)      = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=114461, ...}) = 0
mmap2(NULL, 114461, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7ece000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libdbus-1.so.3", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000M\0\000"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=214620, ...}) = 0
mmap2(NULL, 217920, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7e98000
mmap2(0xb7ecc000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x33) = 0xb7ecc000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libglib-2.0.so.0", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\26\1"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=712748, ...}) = 0
mmap2(NULL, 716580, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7de9000
mmap2(0xb7e97000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xad) = 0xb7e97000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libbluetooth.so.2", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\220G\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=78968, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7de8000
mmap2(NULL, 77756, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7dd5000
mmap2(0xb7de7000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x12) = 0xb7de7000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/lib/tls/i686/cmov/libc.so.6", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260e\1"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=1364388, ...}) = 0
mmap2(NULL, 1369712, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7c86000
mmap2(0xb7dcf000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x149) = 0xb7dcf000
mmap2(0xb7dd2000, 9840, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7dd2000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/usr/lib/libpcre.so.3", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360\16"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=157240, ...}) = 0
mmap2(NULL, 156036, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7c5f000
mmap2(0xb7c85000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x26) = 0xb7c85000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/lib/libselinux.so.1", O_RDONLY)  = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\300@\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=95948, ...}) = 0
mmap2(NULL, 101180, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7c46000
mmap2(0xb7c5d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16) = 0xb7c5d000
close(3)                                = 0
access("/etc/ld.so.nohwcap", F_OK)      = -1 ENOENT (No such file or directory)
open("/lib/tls/i686/cmov/libdl.so.2", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0p\n\0\000"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0644, st_size=9684, ...}) = 0
mmap2(NULL, 12412, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7c42000
mmap2(0xb7c44000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1) = 0xb7c44000
close(3)                                = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7c41000
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7c40000
set_thread_area({entry_number:-1 -> 6, base_addr:0xb7c40720, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
mprotect(0xb7dcf000, 4096, PROT_READ)   = 0
munmap(0xb7ece000, 114461)              = 0
brk(0)                                  = 0x8052000
brk(0x8073000)                          = 0x8073000
open("/etc/selinux/config", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory)
statfs64("/selinux", 84, 0xbfd803ec)    = -1 ENOENT (No such file or directory)
open("/proc/mounts", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ee9000
read(3, "rootfs / rootfs rw 0 0\nnone /sys"..., 1024) = 1024
read(3, "/2.6.24-12-generic/volatile tmpf"..., 1024) = 41
read(3, "", 1024)                       = 0
close(3)                                = 0
munmap(0xb7ee9000, 4096)                = 0
rt_sigaction(SIGPIPE, {SIG_IGN}, {SIG_DFL}, 8) = 0
socket(PF_FILE, SOCK_STREAM, 0)         = 3
connect(3, {sa_family=AF_FILE, path="/var/run/dbus/system_bus_socket"}, 33) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
fcntl64(3, F_GETFD)                     = 0
fcntl64(3, F_SETFD, FD_CLOEXEC)         = 0
geteuid32()                             = 500
rt_sigaction(SIGPIPE, {SIG_IGN}, {SIG_IGN}, 8) = 0
poll([{fd=3, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1
write(3, "\0", 1)                       = 1
write(3, "AUTH EXTERNAL 353030\r\n", 22) = 22
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, -1) = 1
read(3, "OK 13f1a9e546c2abe38312994047cc3"..., 2048) = 37
poll([{fd=3, events=POLLOUT, revents=POLLOUT}], 1, -1) = 1
write(3, "BEGIN\r\n", 7)                = 7
poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, -1) = 1
writev(3, [{"l\1\0\1\0\0\0\0\1\0\0\0n\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 128}, {"", 0}], 2) = 128
gettimeofday({1206525425, 86018}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\16\0\0\0\1\0\0\0E\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 284
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
fstat64(3, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
fcntl64(3, F_GETFL)                     = 0x802 (flags O_RDWR|O_NONBLOCK)
gettimeofday({1206525425, 86708}, NULL) = 0
gettimeofday({1206525425, 86923}, NULL) = 0
writev(3, [{"l\1\0\1\16\0\0\0\2\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org"..., 144}, {"\t\0\0\0org.bluez\0", 14}], 2) = 158
gettimeofday({1206525425, 87453}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\4\0\0\0\3\0\0\0E\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 92
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 87879}, NULL) = 0
writev(3, [{"l\1\0\1\0\0\0\0\3\0\0\0g\0\0\0\1\1o\0\n\0\0\0/org/blu"..., 120}, {"", 0}], 2) = 120
gettimeofday({1206525425, 88610}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\24\0\0\0=\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.3251"..., 2048) = 92
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 89050}, NULL) = 0
writev(3, [{"l\1\0\1Z\0\0\0\4\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/f"..., 144}, {"U\0\0\0type=\'signal\',interface=\'org"..., 90}], 2) = 234
gettimeofday({1206525425, 89527}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\0\0\0\0\4\0\0\0=\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 80
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 89965}, NULL) = 0
writev(3, [{"l\1\0\1\0\0\0\0\5\0\0\0|\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 144}, {"", 0}], 2) = 144
gettimeofday({1206525425, 90503}, NULL) = 0
gettimeofday({1206525425, 90580}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 0)     = 0
gettimeofday({1206525425, 90786}, NULL) = 0
writev(3, [{"l\1\0\1\0\0\0\0\6\0\0\0j\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 128}, {"", 0}], 2) = 128
gettimeofday({1206525425, 91154}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\0\0\0\0?\0\0\0.\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 426
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 96235}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\7\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:02:76:01:75:BA\0", 22}], 2) = 158
gettimeofday({1206525425, 97063}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\r\0\0\0B\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 85
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 97481}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\10\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/b"..., 136}, {"\21\0\0\00000:10:C6:C6:8B:3B\0", 22}], 2) = 158
gettimeofday({1206525425, 98268}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\22\0\0\0C\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.3251"..., 2048) = 90
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 98681}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\t\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:12:D1:0F:2B:D1\0", 22}], 2) = 158
gettimeofday({1206525425, 99465}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\r\0\0\0D\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 85
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 99890}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\n\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:12:47:25:F5:09\0", 22}], 2) = 158
gettimeofday({1206525425, 100673}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\r\0\0\0E\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 85
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 101084}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\v\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:17:83:35:0B:18\0", 22}], 2) = 158
gettimeofday({1206525425, 101949}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\20\0\0\0F\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.3251"..., 2048) = 88
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 102362}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\f\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:1C:26:E6:10:2B\0", 22}], 2) = 158
gettimeofday({1206525425, 103204}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\v\0\0\0G\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 83
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 103615}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\r\0\0\0w\0\0\0\1\1o\0\17\0\0\0/org/bl"..., 136}, {"\21\0\0\00000:04:61:82:0B:10\0", 22}], 2) = 158
gettimeofday({1206525425, 104411}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\f\0\0\0H\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 84
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 104590}, NULL) = 0
writev(3, [{"l\1\0\1\26\0\0\0\16\0\0\0o\0\0\0\1\1o\0\17\0\0\0/org/b"..., 128}, {"\21\0\0\00000:04:61:82:0B:10\0", 22}], 2) = 150
gettimeofday({1206525425, 104944}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\2\1\1\33\0\0\0I\0\0\0006\0\0\0\6\1s\0\t\0\0\0:1.3251"..., 2048) = 99
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 105158}, NULL) = 0
writev(3, [{"l\1\0\1A\0\0\0\17\0\0\0x\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 136}, {"\21\0\0\00000:04:61:82:0B:10\0\0\0$\0\0\0000000"..., 65}], 2) = 201
gettimeofday({1206525425, 121922}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 25000) = 1
read(3, "l\4\1\1\36\0\0\0J\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 655602}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 24467) = 1
read(3, "l\4\1\1\36\0\0\0K\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525425, 735395}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 24387) = 1
read(3, "l\4\1\1\36\0\0\0L\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525426, 176746}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 23946) = 1
read(3, "l\4\1\1\36\0\0\0M\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525426, 417754}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 23705) = 1
read(3, "l\4\1\1\36\0\0\0N\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525426, 889564}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 23233) = 1
read(3, "l\4\1\1\36\0\0\0O\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525427, 99831}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 23022) = 1
read(3, "l\4\1\1\36\0\0\0P\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525427, 300596}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 22822) = 1
read(3, "l\4\1\1\36\0\0\0Q\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525427, 550582}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 22572) = 1
read(3, "l\4\1\1\36\0\0\0R\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525427, 601556}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 22521) = 1
read(3, "l\4\1\1\36\0\0\0S\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525430, 754999}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 19367) = 1
read(3, "l\4\1\1\36\0\0\0T\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525431, 345170}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 18777) = 1
read(3, "l\4\1\1\36\0\0\0U\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525431, 655990}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 18466) = 1
read(3, "l\4\1\1\36\0\0\0V\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525431, 795833}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 18327) = 1
read(3, "l\4\1\1\36\0\0\0W\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525432, 396255}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 17726) = 1
read(3, "l\4\1\1\36\0\0\0X\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525433, 938418}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 16184) = 1
read(3, "l\4\1\1\36\0\0\0Y\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525434, 50192}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 16071) = 1
read(3, "l\4\1\1\36\0\0\0Z\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525434, 550067}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 15572) = 1
read(3, "l\4\1\1\36\0\0\0[\0\0\0v\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 166
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525435, 22186}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 15099) = 1
read(3, "l\4\1\1\0\0\0\0\\\0\0\0f\0\0\0\1\1o\0\17\0\0\0/org/blu"..., 2048) = 120
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525435, 378506}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 14744) = 0
gettimeofday({1206525450, 120116}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 1)     = 0
gettimeofday({1206525450, 123858}, NULL) = 0
poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, -1) = 1
read(3, "l\3\1\1\21\0\0\0^\0\0\0f\0\0\0\6\1s\0\t\0\0\0:1.32519"..., 2048) = 137
read(3, 0x8053a58, 2048)                = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1206525455, 889053}, NULL) = 0
gettimeofday({1206525455, 889256}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 0)     = 0
fstat64(1, {st_mode=S_IFREG|0644, st_size=18433, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ee9000
write(1, "network bluetooth://000461820B10"..., 80network bluetooth://000461820B10 "Unknown" "Photosmart A710 series (Bluetooth)"
) = 80
exit_group(0)                           = ?
Process 20828 detached

Revision history for this message

Steve Langasek (vorlon) wrote on 2008-04-17:

#7

the polling that hangs here is on a dbus socket. well, that doesn't tell us much about why it's hanging, then; we'll need someone familiar with dbus debugging to look at this.

Steve Langasek (vorlon) on 2008-04-30

Changed in bluez-utils:
milestone:	ubuntu-8.04 → none

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-07-31:

#8

The problem still persists in Intrepid.

bluez-utils 3.36 has changes in how DBUS is used. Perhaps someone should update the bluez-utils in Intrepid.

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-08-01:

#9

I have raised the priority as this prevents Bluetooth printers from "just working" without letting device discovery taking an annoyingly long time. A program should simply not run infinitely long time.

Changed in bluez-utils:
importance:	Medium → Critical
milestone:	none → ubuntu-8.10-beta

Revision history for this message

Mario Limonciello (superm1) wrote on 2008-10-10:

#10

Till:

Can you confirm this is still the same situation on the BlueZ 4.x packages that entered Intrepid?

Changed in bluez:
status:	New → Incomplete

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-10-13:

#11

Now there is no infinite loop any more, but

/usr/lib/cups/backend/bluetooth

exits immediately with exit status 1. This happens independent whether there is a Bluetooth printer or not. So the printer setup tool finds all the other printer (USB, network, ...) quickly now, but no Bluetooth printers.

Revision history for this message

Till Kamppeter (till-kamppeter) wrote on 2008-10-13:

#12

The Bluetooth Daemon

/usr/sbin/bluetoothd

is running.

Revision history for this message

Baptiste Mille-Mathias (bmillemathias) wrote on 2009-06-07:

#13

Till,

in the future, avoid usign the same bug for various bug.
Is your latest problem still occur?

Revision history for this message

Mario Limonciello (superm1) wrote on 2009-08-07:

#14

I've just tested with bluez 4.47, and it appears that the backend properly exits now with a zero return code.

Changed in bluez (Ubuntu):
status:	Incomplete → Fix Released

Ubuntu
bluez package

Bluetooth CUPS backend takes infinite time on device discovery

Bug Description

Other bug subscribers

Remote bug watches

Ubuntubluez package

Bluetooth CUPS backend takes infinite time on device discovery

Bug Description

Other bug subscribers

Remote bug watches

Ubuntu
bluez package