Asdo wrote:
Ilpo Järvinen wrote:
...I'd next try strace the sftp server to see what it was doing during the stall.
Thanks for your help Ilpo

Isn't the strace equivalent to the stack trace I obtained via cat /proc/pid/stack reported previously? That was at the time of the stall

I'm thinking the strace would slow down sftp-server very deeply...

I found out that if I attach the strace I can see at least the last function call.
The SFTP it's hanged right now so I did that:

r...@mystorage:/root# strace -p 11475
Process 11475 attached - interrupt to quit
select(5, [3], [], NULL, NULL

(stuck here forever... doesn't move)
(it's strange the first option of select is 5, shouldn't it be 4 from man select? A bug of strace maybe?)

r...@mystorage:/root# cat /proc/11475/stack
[<ffffffff8112e644>] poll_schedule_timeout+0x34/0x50
[<ffffffff8112ef4f>] do_select+0x58f/0x6b0
[<ffffffff8112f8b5>] core_sys_select+0x185/0x2b0
[<ffffffff8112fc32>] sys_select+0x42/0x110
[<ffffffff8101225b>] tracesys+0xd9/0xde
[<ffffffffffffffff>] 0xffffffffffffffff

And this is from cat /proc/net/tcp 2: 0F12A8C0:0016 2512A8C0:0FBD 01 00000000:00000000 02:00009144 00000000 0 0 5326251 2 ffff88085408ce00 26 4 1 9 4

The select refers to open files so here they are:


r...@mystorage:/proc/11475/fd# ll
total 0
lr-x------ 1 ccosentino wetlab 64 2009-11-25 14:43 0 -> pipe:[5326309]
l-wx------ 1 ccosentino wetlab 64 2009-11-25 14:43 1 -> pipe:[5326310]
l-wx------ 1 ccosentino wetlab 64 2009-11-25 14:43 2 -> pipe:[5326311]
lr-x------ 1 ccosentino wetlab 64 2009-11-25 14:43 3 -> pipe:[5326309]
l-wx------ 1 ccosentino wetlab 64 2009-11-25 14:43 4 -> pipe:[5326310]
l-wx------ 1 ccosentino wetlab 64 2009-11-25 14:43 5 -> /path/to/file_being_saved.filepart

I tried to send SIGSTOP and then SIGCONT to see if I could make it make a loop and then reenter into the select. I'm not sure it really did that, what do you think? This is the strace:
r...@mystorage:/root# strace -p 11475 2>&1 | tee sftpstrace.dmp
Process 11475 attached - interrupt to quit
select(5, [3], [], NULL, NULL)          = ? ERESTARTNOHAND (To be restarted)
--- SIGSTOP (Stopped (signal)) @ 0 (0) ---
--- SIGSTOP (Stopped (signal)) @ 0 (0) ---
select(5, [3], [], NULL, NULL)          = ? ERESTARTNOHAND (To be restarted)
--- SIGCONT (Continued) @ 0 (0) ---
select(5, [3], [], NULL, NULL

(hanged again here)


Do you think this info is enough or I really have to strace it since the beginning? If it is a race condition it might not happen if the sftp-server is deeply slowed down by the strace. If I had a way to make it continue right now we could get the rest of the strace... But it's not so easy, I tried starting a Samba transfer but it did not unlock the SFTP this time. SIGSTOP + SIGCONT also didn't work.

BTW people using the Storage also experienced data loss while pushing files in it: appartently data disappeared from the middle of a file they were saving to the Storage. To me looks like another hint that application-level data which has been received via network by TCP stack is trapped there and not being pushed to the application. Or the data might even be trapped into the anonymous sockets between sshd and sftp-server.

Thanks for your help



------------------------------------------------------------------------------
Let Crystal Reports handle the reporting - Free Crystal Reports 2008 30-Day 
trial. Simplify your report design, integration and deployment - and focus on 
what you do best, core application coding. Discover what's new with
Crystal Reports now.  http://p.sf.net/sfu/bobj-july
_______________________________________________
E1000-devel mailing list
E1000-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/e1000-devel

Reply via email to