strace 了一下监听过程。 不确定原因。

cactus0509

strace -f -tt -x   -o a.txt

1876  16:06:44.670083 fcntl(17, F_SETFD, FD_CLOEXEC) = 0
1876  16:06:44.670155 write(17, "17-JUN-2013 16:06:44 * service_u"..., 51) = 51
1876  16:06:44.670243 close(17)         = 0
1876  16:06:44.670321 poll([{fd=8, events=POLLIN|POLLRDNORM}, {fd=11, events=POLLIN|POLLRDNORM}, {fd=16, events=POLLIN|POLLRDNORM}, {fd=12, events=POLLIN|POLLOUT|POLLRDNORM}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}], 20, -1) = 1 ([{fd=12, revents=POLLOUT}])
1876  16:06:44.670532 poll([{fd=8, events=POLLIN|POLLRDNORM}, {fd=11, events=POLLIN|POLLRDNORM}, {fd=16, events=POLLIN|POLLRDNORM}, {fd=12, events=POLLIN|POLLRDNORM}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}, {fd=-1}], 20, -1 <unfinished ...>
39751 16:07:08.993785 <... read resumed> "\x00\x9b\x00\x00\x06\x00\x00\x00\x00\x00\x03\x76\x00\x01\x01\x04\x01\x01\x01\x01\x05\x01\x01\x04\x54\x45\x53\x54\x01\x0d\x0d\x41"..., 8208) = 155
39751 16:07:08.994666 mmap(0x7fa2905e0000, 65536, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED, 7, 0) = 0x7fa2905e0000


何解？为什么这么长时间呢？
按照监听处理逻辑，  1876 是监听进程pid,   39751  是 fork到的第2个子进程。