Mailinglist Archive: opensuse-bugs (6630 mails)

< Previous Next >
[Bug 530440] mysterious delay in processing of DNS replies in aria2c
  • From: bugzilla_noreply@xxxxxxxxxx
  • Date: Wed, 12 Aug 2009 09:57:54 -0600
  • Message-id: <20090812155754.CC15A245513@xxxxxxxxxxxxxxxxxxxxxx>
http://bugzilla.novell.com/show_bug.cgi?id=530440

User poeml@xxxxxxxxxx added comment
http://bugzilla.novell.com/show_bug.cgi?id=530440#c2





--- Comment #2 from Peter Poeml <poeml@xxxxxxxxxx> 2009-08-12 09:57:53 MDT ---
After rebooting my updated Factory, I still can't reproduce the hangs here. Too
bad.

Anyway, here's what data I have collected so far:

Tcpdump showing the pauses between individual flows:

16:44:11.720504 IP 192.168.1.100.40990 > 192.168.1.254.domain: 5036+ A?
ftp.hosteurope.de. (35)
16:44:11.720516 IP 192.168.1.100.40990 > 192.168.1.254.domain: 21345+ AAAA?
ftp.hosteurope.de. (35)
16:44:11.763775 IP 192.168.1.254.domain > 192.168.1.100.40990: 21345 0/1/0 (86)
16:44:16.724809 IP 192.168.1.100.40990 > 192.168.1.254.domain: 5036+ A?
ftp.hosteurope.de. (35)
16:44:16.725913 IP 192.168.1.100.52457 > 192.168.1.254.domain: 41816+ A?
ftp.hosteurope.de. (35)
16:44:16.725923 IP 192.168.1.100.52457 > 192.168.1.254.domain: 33685+ AAAA?
ftp.hosteurope.de. (35)
16:44:16.768285 IP 192.168.1.254.domain > 192.168.1.100.52457: 41816 1/0/0
A[|domain]
16:44:21.729850 IP 192.168.1.100.40990 > 192.168.1.254.domain: 5036+ A?
ftp.hosteurope.de. (35)
16:44:21.730270 IP 192.168.1.100.52457 > 192.168.1.254.domain: 41816+ A?
ftp.hosteurope.de. (35)
16:44:21.771732 IP 192.168.1.254.domain > 192.168.1.100.40990: 5036 1/0/0
A[|domain]
16:44:21.771770 IP 192.168.1.100.40990 > 192.168.1.254.domain: 21345+ AAAA?
ftp.hosteurope.de. (35)
16:44:21.776133 IP 192.168.1.254.domain > 192.168.1.100.52457: 41816 1/0/0
A[|domain]
16:44:21.776155 IP 192.168.1.100.52457 > 192.168.1.254.domain: 33685+ AAAA?
ftp.hosteurope.de. (35)
16:44:21.812710 IP 192.168.1.254.domain > 192.168.1.100.40990: 21345 0/1/0 (86)
16:44:26.735176 IP 192.168.1.100.55218 > 192.168.1.254.domain: 7019+ A?
sunsite.rwth-aachen.de. (40)
16:44:26.735193 IP 192.168.1.100.55218 > 192.168.1.254.domain: 15183+ AAAA?
sunsite.rwth-aachen.de. (40)
16:44:26.783206 IP 192.168.1.254.domain > 192.168.1.100.55218: 15183 1/1/0
(127)
16:44:31.734925 IP 192.168.1.100.55218 > 192.168.1.254.domain: 7019+ A?
sunsite.rwth-aachen.de. (40)
16:44:31.776658 IP 192.168.1.254.domain > 192.168.1.100.55218: 7019
2/0/0[|domain]
16:44:31.776696 IP 192.168.1.100.55218 > 192.168.1.254.domain: 15183+ AAAA?
sunsite.rwth-aachen.de. (40)
16:44:31.837851 IP 192.168.1.254.domain > 192.168.1.100.55218: 15183 1/1/0
(127)
16:44:31.838132 IP 192.168.1.100.35705 > 192.168.1.254.domain: 31718+ A?
ftp.halifax.rwth-aachen.de. (44)
16:44:31.838147 IP 192.168.1.100.35705 > 192.168.1.254.domain: 914+ AAAA?
ftp.halifax.rwth-aachen.de. (44)
16:44:31.881902 IP 192.168.1.254.domain > 192.168.1.100.35705: 31718 1/0/0 (60)
16:44:36.842907 IP 192.168.1.100.35705 > 192.168.1.254.domain: 31718+ A?
ftp.halifax.rwth-aachen.de. (44)
16:44:36.902900 IP 192.168.1.254.domain > 192.168.1.100.35705: 31718 1/0/0 (60)
16:44:36.902938 IP 192.168.1.100.35705 > 192.168.1.254.domain: 914+ AAAA?
ftp.halifax.rwth-aachen.de. (44)
16:44:36.946850 IP 192.168.1.254.domain > 192.168.1.100.35705: 914 0/1/0 (98)
16:44:36.947133 IP 192.168.1.100.37436 > 192.168.1.254.domain: 21271+ A?
ftp.uni-bremen.de. (35)
16:44:36.947150 IP 192.168.1.100.37436 > 192.168.1.254.domain: 9133+ AAAA?
ftp.uni-bremen.de. (35)
16:44:37.008985 IP 192.168.1.254.domain > 192.168.1.100.37436: 9133 1/1/0
CNAME[|domain]
16:44:41.951993 IP 192.168.1.100.37436 > 192.168.1.254.domain: 21271+ A?
ftp.uni-bremen.de. (35)
16:44:41.993507 IP 192.168.1.254.domain > 192.168.1.100.37436: 21271 2/0/0
CNAME[|domain]
16:44:41.993553 IP 192.168.1.100.37436 > 192.168.1.254.domain: 9133+ AAAA?
ftp.uni-bremen.de. (35)
16:44:42.037741 IP 192.168.1.254.domain > 192.168.1.100.37436: 9133 1/1/0
CNAME[|domain]
16:44:42.038017 IP 192.168.1.100.39617 > 192.168.1.254.domain: 60322+ A?
ftp.rz.uni-wuerzburg.de. (41)
16:44:42.038032 IP 192.168.1.100.39617 > 192.168.1.254.domain: 2988+ AAAA?
ftp.rz.uni-wuerzburg.de. (41)
16:44:42.097972 IP 192.168.1.254.domain > 192.168.1.100.39617: 60322 1/0/0 (57)
16:44:47.042966 IP 192.168.1.100.39617 > 192.168.1.254.domain: 60322+ A?
ftp.rz.uni-wuerzburg.de. (41)
16:44:47.084376 IP 192.168.1.254.domain > 192.168.1.100.39617: 60322 1/0/0 (57)
16:44:47.084408 IP 192.168.1.100.39617 > 192.168.1.254.domain: 2988+ AAAA?
ftp.rz.uni-wuerzburg.de. (41)
16:44:47.128776 IP 192.168.1.254.domain > 192.168.1.100.39617: 2988 0/1/0 (95)


strace (from strace -f -tt -o aria2c.strace aria2c --file-allocation=none
http://download.opensuse.org/distribution/11.1/repo/oss/suse/x86_64/kernel-source-2.6.27.7-9.1.x86_64.rpm):


11961 16:18:49.023661 stat("/etc/resolv.conf", {st_mode=S_IFREG|0644,
st_size=820, ...}) = 0
11961 16:18:49.023725 open("/etc/resolv.conf", O_RDONLY) = 10
11961 16:18:49.023770 fstat(10, {st_mode=S_IFREG|0644, st_size=820, ...}) = 0
11961 16:18:49.023825 mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f310e69e000
11961 16:18:49.023865 read(10, "### /etc/resolv.conf file autoge"..., 4096) =
820
11961 16:18:49.023915 read(10, "", 4096) = 0
11961 16:18:49.023952 close(10) = 0
11961 16:18:49.023989 munmap(0x7f310e69e000, 4096) = 0
11961 16:18:49.024029 uname({sys="Linux", node="Goliath", ...}) = 0
11961 16:18:49.024132 socket(PF_INET, 0x802 /* SOCK_??? */, IPPROTO_IP) = 10
11961 16:18:49.024179 connect(10, {sa_family=AF_INET, sin_port=htons(53),
sin_addr=inet_addr("192.168.1.254")}, 28) = 0
11961 16:18:49.024231 poll([{fd=10, events=POLLOUT}], 1, 0) = 1 ([{fd=10,
revents=POLLOUT}])
11961 16:18:49.024277 sendto(10,
"I\233\1\0\0\1\0\0\0\0\0\0\3ftp\vtu-chemnitz\2de\0"..., 36, MSG_NOSIGNAL, NULL,
0) = 36
11961 16:18:49.024345 poll([{fd=10, events=POLLIN|POLLOUT}], 1, 5000) = 1
([{fd=10, revents=POLLOUT}])
11961 16:18:49.024390 sendto(10,
"9A\1\0\0\1\0\0\0\0\0\0\3ftp\vtu-chemnitz\2de\0"..., 36, MSG_NOSIGNAL, NULL, 0)
= 36
11961 16:18:49.024455 poll([{fd=10, events=POLLIN}], 1, 4999) = 1 ([{fd=10,
revents=POLLIN}])
11961 16:18:49.067486 ioctl(10, FIONREAD, [94]) = 0
11961 16:18:49.067544 recvfrom(10,
"9A\201\200\0\1\0\0\0\1\0\0\3ftp\vtu-chemnitz\2de\0"..., 2048, 0,
{sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.1.254")},
[16]) = 94
11961 16:18:49.067618 poll([{fd=10, events=POLLIN}], 1, 4956) = 0 (Timeout)
11961 16:18:54.062894 poll([{fd=10, events=POLLOUT}], 1, 0) = 1 ([{fd=10,
revents=POLLOUT}])
11961 16:18:54.062953 sendto(10,
"I\233\1\0\0\1\0\0\0\0\0\0\3ftp\vtu-chemnitz\2de\0"..., 36, MSG_NOSIGNAL, NULL,
0) = 36
11961 16:18:54.063030 poll([{fd=10, events=POLLIN}], 1, 5000) = 1 ([{fd=10,
revents=POLLIN}])
11961 16:18:54.104056 ioctl(10, FIONREAD, [52]) = 0
11961 16:18:54.104101 recvfrom(10,
"I\233\201\200\0\1\0\1\0\0\0\0\3ftp\vtu-chemnitz\2de\0"..., 2048, 0,
{sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.1.254")},
[16]) = 52
11961 16:18:54.104172 poll([{fd=10, events=POLLOUT}], 1, 4958) = 1 ([{fd=10,
revents=POLLOUT}])
11961 16:18:54.104217 sendto(10,
"9A\1\0\0\1\0\0\0\0\0\0\3ftp\vtu-chemnitz\2de\0"..., 36, MSG_NOSIGNAL, NULL, 0)
= 36
11961 16:18:54.104278 poll([{fd=10, events=POLLIN}], 1, 4958) = 1 ([{fd=10,
revents=POLLIN}])
11961 16:18:54.148234 ioctl(10, FIONREAD, [94]) = 0
11961 16:18:54.148278 recvfrom(10,
"9A\201\200\0\1\0\0\0\1\0\0\3ftp\vtu-chemnitz\2de\0"..., 1996, 0,
{sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.1.254")},
[16]) = 94
11961 16:18:54.148350 close(10) = 0
11961 16:18:54.148437 write(4, "2009-08-12 16:18:54.148416 INFO "..., 105) =
105
11961 16:18:54.148508 write(4, "2009-08-12 16:18:54.148494 INFO "..., 74) = 74

The strace rather looks as if aria2c is still polling for data even though it
already got the DNS response.

Not sure what to make of this.

--
Configure bugmail: http://bugzilla.novell.com/userprefs.cgi?tab=email
------- You are receiving this mail because: -------
You are on the CC list for the bug.

< Previous Next >
References