LQ Newbie
Registered: Jul 2006
Posts: 3
Rep:
|
Help interpreting Strace output
I have program which has some lock contentions. I wanted to locate the source code for it. So I tried strace -tt -f -i -i <pid> and here is the output.
I am wondering what is causing "<... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)" lots of time. Is there anything looking wrong from the following strace output?
Is it possible to get the source code from the instruction pointer being printed here?. Actually the program uses a .so file and which makes use of socket read, write etc.
Any expert opinion on this strace will be of great help.
[pid 8399] 21:19:19.428729 [ffffe402] futex(0x85fd808, FUTEX_WAKE, 1 <unfinished ...>
[pid 8407] 21:19:19.428810 [ffffe402] <... futex resumed> ) = 0
[pid 8405] 21:19:19.428876 [ffffe402] <... futex resumed> ) = 0
[pid 8315] 21:19:19.428947 [ffffe402] <... gettimeofday resumed> {1166563159, 428030}, NULL) = 0
[pid 8354] 21:19:19.429050 [ffffe402] ioctl(69, FIONREAD <unfinished ...>
[pid 8397] 21:19:19.429156 [ffffe402] futex(0x84ac9a4, FUTEX_WAKE, 1 <unfinished ...>
[pid 8403] 21:19:19.429243 [ffffe402] futex(0x85fd808, FUTEX_WAKE, 1 <unfinished ...>
[pid 8363] 21:19:19.429313 [ffffe402] <... futex resumed> ) = 0
[pid 8395] 21:19:19.429379 [ffffe402] <... futex resumed> ) = 0
[pid 8406] 21:19:19.429451 [ffffe402] <... futex resumed> ) = 1
[pid 8404] 21:19:19.429534 [ffffe402] futex(0x85fd80c, FUTEX_WAIT, 34641, NULL <unfinished ...>
[pid 8408] 21:19:19.429625 [ffffe402] futex(0x81b6980, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8358] 21:19:19.429703 [ffffe402] gettimeofday( <unfinished ...>
[pid 8400] 21:19:19.429778 [ffffe402] <... futex resumed> ) = 1
[pid 8399] 21:19:19.429840 [ffffe402] <... futex resumed> ) = 1
[pid 8407] 21:19:19.429925 [ffffe402] futex(0x85fd808, FUTEX_WAKE, 1 <unfinished ...>
[pid 8405] 21:19:19.430015 [ffffe402] futex(0x85fd808, FUTEX_WAKE, 1 <unfinished ...>
[pid 8315] 21:19:19.430099 [ffffe402] clock_gettime(CLOCK_REALTIME, <unfinished ...>
[pid 8354] 21:19:19.430176 [ffffe402] <... ioctl resumed> , [145]) = 0
[pid 8397] 21:19:19.430258 [ffffe402] <... futex resumed> ) = 1
[pid 8403] 21:19:19.430328 [ffffe402] <... futex resumed> ) = 1
[pid 8363] 21:19:19.430408 [ffffe402] futex(0x84ac9a0, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8395] 21:19:19.430499 [ffffe402] futex(0x85fd80c, FUTEX_WAKE, 1 <unfinished ...>
[pid 8406] 21:19:19.430586 [ffffe402] futex(0x81b6980, FUTEX_WAKE, 1 <unfinished ...>
[pid 8398] 21:19:19.430657 [ffffe402] <... futex resumed> ) = 0
[pid 8404] 21:19:19.430725 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8408] 21:19:19.430800 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8358] 21:19:19.430871 [ffffe402] <... gettimeofday resumed> {1166563159, 429744}, NULL) = 0
[pid 8400] 21:19:19.430986 [ffffe402] futex(0x85fd7f0, FUTEX_WAKE, 1 <unfinished ...>
[pid 8399] 21:19:19.431073 [ffffe402] futex(0x85fd80c, FUTEX_WAIT, 34641, NULL <unfinished ...>
[pid 8407] 21:19:19.431151 [ffffe402] <... futex resumed> ) = 0
[pid 8405] 21:19:19.431214 [ffffe402] <... futex resumed> ) = 0
[pid 8315] 21:19:19.431285 [ffffe402] <... clock_gettime resumed> {1166563159, 430146000}) = 0
[pid 8354] 21:19:19.431398 [ffffe402] recvfrom(69, <unfinished ...>
[pid 8397] 21:19:19.431504 [ffffe402] futex(0x84ac9a0, FUTEX_WAKE, 1 <unfinished ...>
[pid 8403] 21:19:19.431591 [ffffe402] futex(0x85fd80c, FUTEX_WAIT, 34641, NULL <unfinished ...>
[pid 8363] 21:19:19.431666 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8395] 21:19:19.431736 [ffffe402] <... futex resumed> ) = 1
[pid 8406] 21:19:19.431802 [ffffe402] <... futex resumed> ) = 0
[pid 8398] 21:19:19.431889 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8404] 21:19:19.431982 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8408] 21:19:19.432065 [ffffe402] futex(0x81b6980, FUTEX_WAKE, 1 <unfinished ...>
[pid 8358] 21:19:19.432146 [ffffe402] gettimeofday( <unfinished ...>
[pid 8400] 21:19:19.432221 [ffffe402] <... futex resumed> ) = 0
[pid 8399] 21:19:19.432286 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8407] 21:19:19.432375 [ffffe402] futex(0x85fd80c, FUTEX_WAIT, 34641, NULL <unfinished ...>
[pid 8405] 21:19:19.432465 [ffffe402] futex(0x85fd80c, FUTEX_WAIT, 34641, NULL <unfinished ...>
[pid 8315] 21:19:19.432555 [ffffe402] futex(0x9eb22ccc, FUTEX_WAIT, 1, {0, 47884000} <unfinished ...>
[pid 8354] 21:19:19.432646 [ffffe402] <... recvfrom resumed> "%!\205\200\0\1\0\1\0\2\0\0\003165\0013\00210\00210\7in"..., 1024, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("10.10.2.6")}, [16]) = 145
[pid 8397] 21:19:19.432887 [ffffe402] <... futex resumed> ) = 0
[pid 8403] 21:19:19.432955 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8363] 21:19:19.433042 [ffffe402] futex(0x84ac9a0, FUTEX_WAKE, 1 <unfinished ...>
[pid 8395] 21:19:19.433139 [ffffe402] futex(0x85fd808, FUTEX_WAKE, 1 <unfinished ...>
[pid 8406] 21:19:19.433217 [ffffe402] gettimeofday( <unfinished ...>
[pid 8398] 21:19:19.433287 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8404] 21:19:19.433354 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8408] 21:19:19.433424 [ffffe402] <... futex resumed> ) = 0
[pid 8358] 21:19:19.433492 [ffffe402] <... gettimeofday resumed> {1166563159, 432189}, NULL) = 0
[pid 8400] 21:19:19.433620 [ffffe402] gettimeofday( <unfinished ...>
[pid 8399] 21:19:19.433709 [ffffe402] futex(0x85fd7f0, FUTEX_WAKE, 1 <unfinished ...>
[pid 8407] 21:19:19.433786 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8405] 21:19:19.433859 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8354] 21:19:19.433939 [ffffe402] close(69 <unfinished ...>
[pid 8397] 21:19:19.434049 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8403] 21:19:19.434137 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8363] 21:19:19.434208 [ffffe402] <... futex resumed> ) = 0
[pid 8395] 21:19:19.434274 [ffffe402] <... futex resumed> ) = 0
[pid 8406] 21:19:19.434342 [ffffe402] <... gettimeofday resumed> {1166563159, 433264}, NULL) = 0
[pid 8398] 21:19:19.434436 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8404] 21:19:19.434531 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8408] 21:19:19.434618 [ffffe402] futex(0xbae6c200, FUTEX_WAKE, 1 <unfinished ...>
[pid 8358] 21:19:19.434696 [ffffe402] gettimeofday( <unfinished ...>
[pid 8400] 21:19:19.434772 [ffffe402] <... gettimeofday resumed> {1166563159, 433679}, NULL) = 0
[pid 8399] 21:19:19.434850 [ffffe402] <... futex resumed> ) = 0
[pid 8407] 21:19:19.434937 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8405] 21:19:19.435022 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8354] 21:19:19.435101 [ffffe402] <... close resumed> ) = 0
[pid 8363] 21:19:19.435239 [ffffe402] futex(0xbae229a8, FUTEX_WAKE, 1 <unfinished ...>
[pid 8395] 21:19:19.435324 [ffffe402] gettimeofday( <unfinished ...>
[pid 8406] 21:19:19.435400 [ffffe402] ioctl(28, NCP_IOC_NCPREQUEST <unfinished ...>
[pid 8408] 21:19:19.435470 [ffffe402] <... futex resumed> ) = 0
[pid 8358] 21:19:19.435535 [ffffe402] <... gettimeofday resumed> {1166563159, 434739}, NULL) = 0
[pid 8400] 21:19:19.436015 [ffffe402] gettimeofday( <unfinished ...>
[pid 8399] 21:19:19.436103 [ffffe402] futex(0x85fd808, FUTEX_WAKE, 1 <unfinished ...>
[pid 8397] 21:19:19.436188 [ffffe402] <... futex resumed> ) = 0
[pid 8407] 21:19:19.436309 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8405] 21:19:19.436382 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8354] 21:19:19.436480 [ffffe402] socket(PF_ROSE, SOCK_STREAM, 6 <unfinished ...>
[pid 8363] 21:19:19.436572 [ffffe402] <... futex resumed> ) = 0
[pid 8395] 21:19:19.436638 [ffffe402] <... gettimeofday resumed> {1166563159, 435372}, NULL) = 0
[pid 8406] 21:19:19.436723 [ffffe402] <... ioctl resumed> , 0x1) = 0
[pid 8408] 21:19:19.436887 [ffffe402] gettimeofday( <unfinished ...>
[pid 8358] 21:19:19.436971 [ffffe402] futex(0x85fd7f0, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8400] 21:19:19.437051 [ffffe402] <... gettimeofday resumed> {1166563159, 436071}, NULL) = 0
[pid 8399] 21:19:19.437126 [ffffe402] <... futex resumed> ) = 1
[pid 8397] 21:19:19.437225 [ffffe402] futex(0x85fd7f0, FUTEX_WAKE, 1 <unfinished ...>
[pid 8407] 21:19:19.437315 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8405] 21:19:19.437419 [ffffe402] futex(0x85fd808, FUTEX_WAIT, 2, NULL <unfinished ...>
[pid 8354] 21:19:19.437498 [ffffe402] <... socket resumed> ) = 69
[pid 8363] 21:19:19.437590 [ffffe402] futex(0x84ac9a4, FUTEX_WAIT, 667, NULL <unfinished ...>
[pid 8395] 21:19:19.437670 [ffffe402] gettimeofday( <unfinished ...>
[pid 8406] 21:19:19.437746 [ffffe402] gettimeofday( <unfinished ...>
[pid 8408] 21:19:19.437815 [ffffe402] <... gettimeofday resumed> {1166563159, 436939}, NULL) = 0
[pid 8358] 21:19:19.437894 [ffffe402] <... futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable)
[pid 8400] 21:19:19.437978 [ffffe402] gettimeofday( <unfinished ...>
|