[5051] in SIPB bug reports
pop and weird AFS behavior
daemon@ATHENA.MIT.EDU (Derek Atkins)
Tue Nov 22 22:37:53 1994
Date: Tue, 22 Nov 1994 22:28:34 -0500
From: Derek Atkins <warlord@incommunicado.cambridge.ma.us>
To: bug-sipb@MIT.EDU, linux-dev@MIT.EDU, linux-afs@MIT.EDU
I dont know if this is specifically a bug in POP, Linux, Linux-AFS, or
some weird combination of them all. It may even be a timing problem
with my slow link!
When I have a fresh AFS Cache, over a 14.4 PPP link, and I run
/afs/sipb/project/sipb/bin/pop from my linux box, it "appears" to
hang. Here is the strace of what's going on... My comments are
included after "<==" at the end of a line below.
I should note that if I run pop a second time, after it is already in
my cache, then it works fine...
I was wondering if anyone else has noticed something like this. The
program wasn't in disk-wait, it was actually running, but its stdio
information was all screwed up!
-derek
(incommunicado-warlord) in /home/warlord
Ready> strace /afs/sipb/project/sipb/bin/pop
uselib("/lib/ld.so") = 0
stat("/etc/ld.so.cache", [dev 3 41 ino 542 nlnks 1 ...]) = 0
open("/etc/ld.so.cache", RDONLY, 27777775343) = 1
mmap(0, 331, READ, SHARED, 1, 0) = 0x50000000
close(1) = 0
uselib("/lib/libc.so.4.5.24") = 0
munmap(0x50000000, , 331, ) = 0
munmap(0x62f00000, , 16384, ) = 0
brk(0) = 0xf958
brk(12958) = 0x12958
brk(13000) = 0x13000
brk(14000) = 0x14000
brk(15000) = 0x15000
brk(16000) = 0x16000
scno out of range: 126
indir(Bogus syscall: 90112
) = 0
sigaction(SIGINT, {0x9488, [], INTERRUPT|NOMASK|ONESHOT}, SIG_DFL) = 0
scno out of range: 126
indir(fork) = 0
fstat(1, 0xbffff150) = -1 (Bad file number) <== Why is stdout a bad fileno?
brk(17000) = 0x17000
write(1, "pop: ", 6) = -1 (Bad file number) <== Same question...
sigaction(SIGCONT, {0x9458, [], INTERRUPT|NOMASK|ONESHOT}, SIG_DFL) = 0
fstat(0, [dev 3 41 ino 2112 nlnks 1 ...]) = 0
ioctl(0, TCGETS, 0xbffff10c) = 0
read(0, ? <== It waited here for input. I typed in a "?"
"?\n", 1024) = 2
sigaction(SIGCONT, SIG_DFL, {0x9458, [], INTERRUPT|NOMASK|ONESHOT}) = 0
scno out of range: 126
indir(oldstat, 0xbffff1ec, 0xbffff1dc) = 0
sigaction(SIGINT, SIG_IGN, {0x9488, [], INTERRUPT|NOMASK|ONESHOT}) = 0
pipe([1,4]) = 0
fork() = 537
close(1) = 0
brk(18000) = 0x18000
fstat(4, [dev 0 0 ino 0 nlnks 1 ...]) = 0
fcntl(4, F_GETFL, 0) = 1
scno out of range: 126
indir(write, 0x3, 0, 0) = 0
fstat(4, [dev 0 0 ino 0 nlnks 1 ...]) = 0
brk(19000) = 0x19000
write(4, "Available pop requests:\n\nconnect".., 788) = 788
close(4) = 0
wait4(-1, EXITED(0), 0, (struct rusage *)0) = 537
sigaction(SIGINT, {0x9488, [], INTERRUPT|NOMASK|ONESHOT}, SIG_IGN) = 0
write(1, "pop: ", 6) = -1 (Bad file number) <== again, where is stdout?
sigaction(SIGCONT, {0x9458, [], INTERRUPT|NOMASK|ONESHOT}, SIG_DFL) = 0
read(0, trace: wait: Interrupted system call <== I Control-C'd here
