Ok, I caught it with the new strace. If I leave strace attached,
it looks like it unsticks itself with one SIGPIPE so next time I'll
leave it unattached.
time([917982437]) = 917982437
recvfrom(7, "\1\1\6\1\0224Vx\0\0\0\0\0\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.58.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982437]) = 917982437
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:17 dhcpd: BOOTR"..., 79, 0) = 79
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982437]) = 917982437
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:17 dhcpd: Ignor"..., 97, 0) = ? ERESTARTSYS (To be restarted)
At this point, I've SIGPIPEd it myself but look at all the other
SIGPIPEs it is getting! Very different output, if I'm reading it
correctly. None of them were making it to the syslog daemon, of course.
Now, this next chunk came in a wave...
--- SIGPIPE (Broken pipe) ---
close(0) = 0
sigreturn() = ? (mask now [])
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
select(10, [7 8 9], [], [], NULL) = 3 (in [7 8 9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\336\0\0=\1\340\273\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
recvfrom(7, "\1\1\6\0 t\0\0\0\0\257\1\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.175.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
socket(PF_UNIX, SOCK_STREAM, 0) = 0
fcntl(0, F_SETFD, FD_CLOEXEC) = 0
connect(0, {sun_family=AF_UNIX, sun_path="/dev/log"}, 16) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: BOOTR"..., 80, 0) = 80
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: Ignor"..., 98, 0) = 98
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
select(10, [7 8 9], [], [], NULL) = 3 (in [7 8 9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\337\0\0=\1\340\272\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
recvfrom(7, "\1\1\6\0 t\0\0L\35\0\321\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.175.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: BOOTR"..., 80, 0) = 80
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: Ignor"..., 98, 0) = 98
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
select(10, [7 8 9], [], [], NULL) = 3 (in [7 8 9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\340\0\0=\1\340\271\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(8389), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
recvfrom(7, "\1\1\6\1\0224Vx\0\0\0\0\0\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.58.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: BOOTR"..., 79, 0) = 79
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: Ignor"..., 97, 0) = 97
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
select(10, [7 8 9], [], [], NULL) = 3 (in [7 8 9])
time([917982472]) = 917982472
recvfrom(9, "E\300\0i\211\357\0\0\377\0012\342"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("127.0.0.1")}, [16]) = 105
recvfrom(7, "\1\1\6\0 t\0\0\230:\257\1\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.175.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: BOOTR"..., 80, 0) = 80
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: Ignor"..., 98, 0) = 98
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
select(10, [7 8 9], [], [], NULL) = 3 (in [7 8 9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\341\0\0=\1\340\270\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
recvfrom(7, "\1\1\6\0 t\0\0\344W\0\321\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.175.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: BOOTR"..., 80, 0) = 80
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: Ignor"..., 98, 0) = 98
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
select(10, [7 8 9], [], [], NULL) = 3 (in [7 8 9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\342\0\0=\1\340\267\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
recvfrom(7, "\1\1\6\1\0224Vx\0\0\0\0\0\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.58.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: BOOTR"..., 79, 0) = 79
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982472]) = 917982472
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:07:52 dhcpd: Ignor"..., 97, 0) = 97
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
I have about 12 things logged for 11:07:52. 11:08:03 is a little
bit more interesting because it only had two entries...
select(10, [7 8 9], [], [], NULL) = 1 (in [9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\343\0\0=\1\340\266\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
select(10, [7 8 9], [], [], NULL) = 1 (in [9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\344\0\0=\1\340\265\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
select(10, [7 8 9], [], [], NULL) = 1 (in [9])
time([917982472]) = 917982472
recvfrom(9, "E\0\0T\4\345\0\0=\1\340\264\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
select(10, [7 8 9], [], [], NULL) = 1 (in [9])
time([917982477]) = 917982477
recvfrom(9, "E\0\0T\4\346\0\0=\1\340\263\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
select(10, [7 8 9], [], [], NULL) = 1 (in [9])
time([917982482]) = 917982482
recvfrom(9, "E\0\0T\4\347\0\0=\1\340\262\204\361"..., 1500, 0, {sin_family=AF_INET, sin_port=htons(4288), sin_addr=inet_addr("132.241.60.35")}, [16]) = 84
select(10, [7 8 9], [], [], NULL) = 2 (in [7 8])
time([917982483]) = 917982483
recvfrom(7, "\1\1\6\1\0224Vx\0\0\0\0\0\0\0\0\0"..., 1540, 0, {sin_family=AF_INET, sin_port=htons(68), sin_addr=inet_addr("132.241.58.1")}, [16]) = 300
read(8, "\0`\227\337I\25\0`/q\372@\10\0E\0"..., 1500) = 342
time([917982483]) = 917982483
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:08:03 dhcpd: BOOTR"..., 79, 0) = 79
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
time([917982483]) = 917982483
rt_sigaction(SIGPIPE, {0x806ceb0, [], 0}, {SIG_DFL}, 8) = 0
send(0, "<30>Feb 2 11:08:03 dhcpd: Ignor"..., 97, 0) = 97
rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0
Those entries ended up being this:
Feb 2 11:08:03 hircine dhcpd: BOOTREQUEST from 00:00:85:00:7e:db via 132.241.58.1
Feb 2 11:08:03 hircine dhcpd: Ignoring BOOTP request from client 00:00:85:00:7e:db via 132.241.58.1
So, even though they're being sysloged, I'm getting a SIGPIPE?
-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@vger.rutgers.edu
Please read the FAQ at http://www.tux.org/lkml/