LinuxQuestions.org
Review your favorite Linux distribution.
Go Back   LinuxQuestions.org > Forums > Linux Forums > Linux - Software > Linux - Kernel
User Name
Password
Linux - Kernel This forum is for all discussion relating to the Linux kernel.

Notices

Reply
 
Search this Thread
Old 12-18-2006, 11:14 PM   #1
rajesh_b_2k
LQ Newbie
 
Registered: Jul 2006
Posts: 3

Rep: Reputation: 0
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 ...>
 
  


Reply


Thread Tools Search this Thread
Search this Thread:

Advanced Search

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is Off
HTML code is Off


Similar Threads
Thread Thread Starter Forum Replies Last Post
interpreting output of pmap on Linux kernel 2.4 rhegde Linux - General 0 08-20-2006 02:32 AM
Need help interpreting tcpdump output line wrw3 Linux - Networking 0 10-29-2005 08:47 PM
Send string to std output without interpreting (script) ta0kira Programming 6 06-29-2005 09:50 AM
Interpreting the output of 'lspci' command. drminix Linux - Hardware 2 06-14-2005 04:20 AM
interpreting iwconfig output: signal strength, etc ahz10 Linux - Wireless Networking 1 04-11-2005 07:55 PM


All times are GMT -5. The time now is 02:05 AM.

Main Menu
My LQ
Write for LQ
LinuxQuestions.org is looking for people interested in writing Editorials, Articles, Reviews, and more. If you'd like to contribute content, let us know.
Main Menu
Syndicate
RSS1  Latest Threads
RSS1  LQ News
Twitter: @linuxquestions
identi.ca: @linuxquestions
Facebook: linuxquestions Google+: linuxquestions
Open Source Consulting | Domain Registration