netdev.vger.kernel.org archive mirror
 help / color / mirror / Atom feed
* regression when connecting to ipv6 localhost
@ 2012-10-14 15:38 Jan Hinnerk Stosch
  2012-10-14 19:29 ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 15:38 UTC (permalink / raw)
  To: netdev

Hi,
I detected a small regression since linux 3.6.0:
When I run "ntpq -p" to query the status of my ntp-daemon I receive a
timeout (localhost.localdomain: timed out, nothing received
***Request timed out). Strace shows that connecting to the ipv6
loopback interface times out. If I explicitly use ipv4 (ntpq -4 -p)
everything works as intended. Downgrading to linux 3.5.6 solves that
issue.
I reported a bug both to my distribution
(https://bugs.archlinux.org/task/31775) and to the kernel bugtracker
(https://bugzilla.kernel.org/show_bug.cgi?id=48741) not knowing
exactly which component caused the regression, so I was told to ask
this here. At the kernel-report you find an strace and tcpdump output.
If you need any further information just tell me, but be patient as me
kernel knowledge tends to zero.
Thanks in advance, Jan Hinnerk

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 15:38 regression when connecting to ipv6 localhost Jan Hinnerk Stosch
@ 2012-10-14 19:29 ` Eric Dumazet
  2012-10-14 19:48   ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 19:29 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 17:38 +0200, Jan Hinnerk Stosch wrote:
> Hi,
> I detected a small regression since linux 3.6.0:
> When I run "ntpq -p" to query the status of my ntp-daemon I receive a
> timeout (localhost.localdomain: timed out, nothing received
> ***Request timed out). Strace shows that connecting to the ipv6
> loopback interface times out. If I explicitly use ipv4 (ntpq -4 -p)
> everything works as intended. Downgrading to linux 3.5.6 solves that
> issue.
> I reported a bug both to my distribution
> (https://bugs.archlinux.org/task/31775) and to the kernel bugtracker
> (https://bugzilla.kernel.org/show_bug.cgi?id=48741) not knowing
> exactly which component caused the regression, so I was told to ask
> this here. At the kernel-report you find an strace and tcpdump output.
> If you need any further information just tell me, but be patient as me
> kernel knowledge tends to zero.
> Thanks in advance, Jan Hinnerk
> --


What gives :

cat /etc/ntp.conf
lsof -p `pidof ntpd`
ntpq -p ::1

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 19:29 ` Eric Dumazet
@ 2012-10-14 19:48   ` Jan Hinnerk Stosch
  2012-10-14 19:54     ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 19:48 UTC (permalink / raw)
  To: Eric Dumazet, netdev

2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> On Sun, 2012-10-14 at 17:38 +0200, Jan Hinnerk Stosch wrote:
>> Hi,
>> I detected a small regression since linux 3.6.0:
>> When I run "ntpq -p" to query the status of my ntp-daemon I receive a
>> timeout (localhost.localdomain: timed out, nothing received
>> ***Request timed out). Strace shows that connecting to the ipv6
>> loopback interface times out. If I explicitly use ipv4 (ntpq -4 -p)
>> everything works as intended. Downgrading to linux 3.5.6 solves that
>> issue.
>> I reported a bug both to my distribution
>> (https://bugs.archlinux.org/task/31775) and to the kernel bugtracker
>> (https://bugzilla.kernel.org/show_bug.cgi?id=48741) not knowing
>> exactly which component caused the regression, so I was told to ask
>> this here. At the kernel-report you find an strace and tcpdump output.
>> If you need any further information just tell me, but be patient as me
>> kernel knowledge tends to zero.
>> Thanks in advance, Jan Hinnerk
>> --
>
>
> What gives :
>
> cat /etc/ntp.conf
> lsof -p `pidof ntpd`
> ntpq -p ::1
>
>
>

ntp.conf is (without comments):
server fritz.box iburst
restrict default noquery nopeer nomodify
restrict 127.0.0.1
restrict ::1
driftfile /var/lib/ntp/ntp.drift
logfile /var/log/ntp.log

"lsof -p `pidof ntpd`" gives nothing

and "ntpq -p ::1" results in the same timeout error "::1: timed out,
nothing received
***Request timed out".

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 19:48   ` Jan Hinnerk Stosch
@ 2012-10-14 19:54     ` Eric Dumazet
  2012-10-14 19:57       ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 19:54 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 21:48 +0200, Jan Hinnerk Stosch wrote:

> ntp.conf is (without comments):
> server fritz.box iburst
> restrict default noquery nopeer nomodify
> restrict 127.0.0.1
> restrict ::1
> driftfile /var/lib/ntp/ntp.drift
> logfile /var/log/ntp.log
> 
> "lsof -p `pidof ntpd`" gives nothing
> 
> and "ntpq -p ::1" results in the same timeout error "::1: timed out,
> nothing received
> ***Request timed out".

So your ntpd is not running ?

ps aux | grep ntpd

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 19:54     ` Eric Dumazet
@ 2012-10-14 19:57       ` Jan Hinnerk Stosch
  2012-10-14 20:25         ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 19:57 UTC (permalink / raw)
  To: Eric Dumazet, netdev

2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> On Sun, 2012-10-14 at 21:48 +0200, Jan Hinnerk Stosch wrote:
>
>> ntp.conf is (without comments):
>> server fritz.box iburst
>> restrict default noquery nopeer nomodify
>> restrict 127.0.0.1
>> restrict ::1
>> driftfile /var/lib/ntp/ntp.drift
>> logfile /var/log/ntp.log
>>
>> "lsof -p `pidof ntpd`" gives nothing
>>
>> and "ntpq -p ::1" results in the same timeout error "::1: timed out,
>> nothing received
>> ***Request timed out".
>
> So your ntpd is not running ?
>
> ps aux | grep ntpd
>
>
>

No, of course it is running:
ntp        368  0.0  0.0  31288  2128 ?        Ss   16:51   0:00
/usr/bin/ntpd -g -u ntp:ntp

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 19:57       ` Jan Hinnerk Stosch
@ 2012-10-14 20:25         ` Eric Dumazet
  2012-10-14 20:26           ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 20:25 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 21:57 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> > On Sun, 2012-10-14 at 21:48 +0200, Jan Hinnerk Stosch wrote:
> >
> >> ntp.conf is (without comments):
> >> server fritz.box iburst
> >> restrict default noquery nopeer nomodify
> >> restrict 127.0.0.1
> >> restrict ::1
> >> driftfile /var/lib/ntp/ntp.drift
> >> logfile /var/log/ntp.log
> >>
> >> "lsof -p `pidof ntpd`" gives nothing
> >>
> >> and "ntpq -p ::1" results in the same timeout error "::1: timed out,
> >> nothing received
> >> ***Request timed out".
> >
> > So your ntpd is not running ?
> >
> > ps aux | grep ntpd
> >
> >
> >
> 
> No, of course it is running:
> ntp        368  0.0  0.0  31288  2128 ?        Ss   16:51   0:00
> /usr/bin/ntpd -g -u ntp:ntp


Hmm

pidof ntpd

should give you the pid of ntpd process : 368

So try 

lsof -p 368  # instead of lsof -p `pidof ntpd`

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:25         ` Eric Dumazet
@ 2012-10-14 20:26           ` Eric Dumazet
  2012-10-14 20:28             ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 20:26 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 22:25 +0200, Eric Dumazet wrote:

> 
> Hmm
> 
> pidof ntpd
> 
> should give you the pid of ntpd process : 368
> 
> So try 
> 
> lsof -p 368  # instead of lsof -p `pidof ntpd`

Also post :

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:26           ` Eric Dumazet
@ 2012-10-14 20:28             ` Eric Dumazet
  2012-10-14 20:39               ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 20:28 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 22:27 +0200, Eric Dumazet wrote:

> Also post :
> 

# grep Udp6 /proc/net/snmp6

Before and after your "ntpq -p" try

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:28             ` Eric Dumazet
@ 2012-10-14 20:39               ` Jan Hinnerk Stosch
  2012-10-14 20:45                 ` Eric Dumazet
  2012-10-14 21:07                 ` Eric Dumazet
  0 siblings, 2 replies; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 20:39 UTC (permalink / raw)
  To: Eric Dumazet, netdev

2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> On Sun, 2012-10-14 at 22:27 +0200, Eric Dumazet wrote:
>
>> Also post :
>>
>
> # grep Udp6 /proc/net/snmp6
>
> Before and after your "ntpq -p" try
>
>

Sorry, my fault. I executed the command as user not root:
lsof: WARNING: can't stat() fuse.gvfs-fuse-daemon file system
/run/user/1000/gvfs
      Output information may be incomplete.
COMMAND PID USER   FD      TYPE             DEVICE SIZE/OFF   NODE NAME
ntpd    368  ntp  cwd       DIR                8,2     4096      2 /
ntpd    368  ntp  rtd       DIR                8,2     4096      2 /
ntpd    368  ntp  txt       REG                8,2   582944 418889 /usr/bin/ntpd
ntpd    368  ntp  mem       REG                8,2    84832 396501
/usr/lib/libresolv-2.16.so
ntpd    368  ntp  mem       REG                8,2    22936 396440
/usr/lib/libnss_dns-2.16.so
ntpd    368  ntp  mem       REG                8,2    52136 396470
/usr/lib/libnss_files-2.16.so
ntpd    368  ntp  mem       REG                8,2    18760 400111
/usr/lib/libattr.so.1.1.0
ntpd    368  ntp  mem       REG                8,2   137998 396481
/usr/lib/libpthread-2.16.so
ntpd    368  ntp  mem       REG                8,2    88656 399537
/usr/lib/libz.so.1.2.7
ntpd    368  ntp  mem       REG                8,2    14648 396487
/usr/lib/libdl-2.16.so
ntpd    368  ntp  mem       REG                8,2  2009849 396474
/usr/lib/libc-2.16.so
ntpd    368  ntp  mem       REG                8,2    16776 400227
/usr/lib/libcap.so.2.22
ntpd    368  ntp  mem       REG                8,2    31760 396469
/usr/lib/librt-2.16.so
ntpd    368  ntp  mem       REG                8,2  2392233 405328
/usr/lib/libcrypto.so.1.0.0
ntpd    368  ntp  mem       REG                8,2  1022344 396456
/usr/lib/libm-2.16.so
ntpd    368  ntp  mem       REG                8,2   165344 396512
/usr/lib/ld-2.16.so
ntpd    368  ntp    0u      CHR                1,3      0t0   1028 /dev/null
ntpd    368  ntp    1u      CHR                1,3      0t0   1028 /dev/null
ntpd    368  ntp    2u      CHR                1,3      0t0   1028 /dev/null
ntpd    368  ntp    3u     unix 0xffff880214dfbb80      0t0  11432 socket
ntpd    368  ntp    5w      REG                8,2        0 664219
/var/log/ntp.log
ntpd    368  ntp   16u     IPv4              10453      0t0    UDP *:ntp
ntpd    368  ntp   17u     IPv6              10454      0t0    UDP *:ntp
ntpd    368  ntp   18u     IPv4              10460      0t0    UDP
localhost.localdomain:ntp
ntpd    368  ntp   19u     IPv4              10461      0t0    UDP
hinniepc.fritz.box:ntp
ntpd    368  ntp   20u     IPv6               8775      0t0    UDP
[fe80::922b:34ff:fe33:c292]:ntp
ntpd    368  ntp   21u  netlink                         0t0  10463 ROUTE

and here is "grep Udp6 /proc/net/snmp6" before:
Udp6InDatagrams                 	19
Udp6NoPorts                     	0
Udp6InErrors                    	0
Udp6OutDatagrams                	19
Udp6RcvbufErrors                	0
Udp6SndbufErrors                	0

and after running "ntpq -p":
Udp6InDatagrams                 	21
Udp6NoPorts                     	0
Udp6InErrors                    	0
Udp6OutDatagrams                	21
Udp6RcvbufErrors                	0
Udp6SndbufErrors                	0

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:39               ` Jan Hinnerk Stosch
@ 2012-10-14 20:45                 ` Eric Dumazet
  2012-10-14 20:47                   ` Eric Dumazet
  2012-10-14 21:07                 ` Eric Dumazet
  1 sibling, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 20:45 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 22:39 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> > On Sun, 2012-10-14 at 22:27 +0200, Eric Dumazet wrote:
> >
> >> Also post :
> >>
> >
> > # grep Udp6 /proc/net/snmp6
> >
> > Before and after your "ntpq -p" try
> >
> >
> 
> Sorry, my fault. I executed the command as user not root:
> lsof: WARNING: can't stat() fuse.gvfs-fuse-daemon file system
> /run/user/1000/gvfs
>       Output information may be incomplete.
> COMMAND PID USER   FD      TYPE             DEVICE SIZE/OFF   NODE NAME
> ntpd    368  ntp  cwd       DIR                8,2     4096      2 /
> ntpd    368  ntp  rtd       DIR                8,2     4096      2 /
> ntpd    368  ntp  txt       REG                8,2   582944 418889 /usr/bin/ntpd
> ntpd    368  ntp  mem       REG                8,2    84832 396501
> /usr/lib/libresolv-2.16.so
> ntpd    368  ntp  mem       REG                8,2    22936 396440
> /usr/lib/libnss_dns-2.16.so
> ntpd    368  ntp  mem       REG                8,2    52136 396470
> /usr/lib/libnss_files-2.16.so
> ntpd    368  ntp  mem       REG                8,2    18760 400111
> /usr/lib/libattr.so.1.1.0
> ntpd    368  ntp  mem       REG                8,2   137998 396481
> /usr/lib/libpthread-2.16.so
> ntpd    368  ntp  mem       REG                8,2    88656 399537
> /usr/lib/libz.so.1.2.7
> ntpd    368  ntp  mem       REG                8,2    14648 396487
> /usr/lib/libdl-2.16.so
> ntpd    368  ntp  mem       REG                8,2  2009849 396474
> /usr/lib/libc-2.16.so
> ntpd    368  ntp  mem       REG                8,2    16776 400227
> /usr/lib/libcap.so.2.22
> ntpd    368  ntp  mem       REG                8,2    31760 396469
> /usr/lib/librt-2.16.so
> ntpd    368  ntp  mem       REG                8,2  2392233 405328
> /usr/lib/libcrypto.so.1.0.0
> ntpd    368  ntp  mem       REG                8,2  1022344 396456
> /usr/lib/libm-2.16.so
> ntpd    368  ntp  mem       REG                8,2   165344 396512
> /usr/lib/ld-2.16.so
> ntpd    368  ntp    0u      CHR                1,3      0t0   1028 /dev/null
> ntpd    368  ntp    1u      CHR                1,3      0t0   1028 /dev/null
> ntpd    368  ntp    2u      CHR                1,3      0t0   1028 /dev/null
> ntpd    368  ntp    3u     unix 0xffff880214dfbb80      0t0  11432 socket
> ntpd    368  ntp    5w      REG                8,2        0 664219
> /var/log/ntp.log
> ntpd    368  ntp   16u     IPv4              10453      0t0    UDP *:ntp
> ntpd    368  ntp   17u     IPv6              10454      0t0    UDP *:ntp
> ntpd    368  ntp   18u     IPv4              10460      0t0    UDP
> localhost.localdomain:ntp
> ntpd    368  ntp   19u     IPv4              10461      0t0    UDP
> hinniepc.fritz.box:ntp
> ntpd    368  ntp   20u     IPv6               8775      0t0    UDP
> [fe80::922b:34ff:fe33:c292]:ntp
> ntpd    368  ntp   21u  netlink                         0t0  10463 ROUTE
> 
> and here is "grep Udp6 /proc/net/snmp6" before:
> Udp6InDatagrams                 	19
> Udp6NoPorts                     	0
> Udp6InErrors                    	0
> Udp6OutDatagrams                	19
> Udp6RcvbufErrors                	0
> Udp6SndbufErrors                	0
> 
> and after running "ntpq -p":
> Udp6InDatagrams                 	21
> Udp6NoPorts                     	0
> Udp6InErrors                    	0
> Udp6OutDatagrams                	21
> Udp6RcvbufErrors                	0
> Udp6SndbufErrors                	0

OK so IPv6 UDP frames are delivered to ntpd

could you strace it while doing one "ntpq -p"

strace -p 368

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:45                 ` Eric Dumazet
@ 2012-10-14 20:47                   ` Eric Dumazet
  2012-10-14 20:56                     ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 20:47 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 22:45 +0200, Eric Dumazet wrote:

> OK so IPv6 UDP frames are delivered to ntpd
> 
> could you strace it while doing one "ntpq -p"
> 
> strace -p 368
> 

Also please post "ip -6 addr"

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:47                   ` Eric Dumazet
@ 2012-10-14 20:56                     ` Jan Hinnerk Stosch
  2012-10-14 21:02                       ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 20:56 UTC (permalink / raw)
  To: Eric Dumazet, netdev

[-- Attachment #1: Type: text/plain, Size: 756 bytes --]

2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> On Sun, 2012-10-14 at 22:45 +0200, Eric Dumazet wrote:
>
>> OK so IPv6 UDP frames are delivered to ntpd
>>
>> could you strace it while doing one "ntpq -p"
>>
>> strace -p 368
>>
>
> Also please post "ip -6 addr"
>
>

ip -6 addr gives:
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436
    inet6 ::1/128 scope host
       valid_lft forever preferred_lft forever
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qlen 1000
    inet6 fe80::922b:34ff:fe33:c292/64 scope link
       valid_lft forever preferred_lft forever

And the output of "strace ntpq -p" is attached in file "ntpq-p".

I also did a tcpdump earlier this day as told in the bugtracker. Find
the parts containing "ntpd" attached in file "tcpdump".

[-- Attachment #2: ntpq-p --]
[-- Type: application/octet-stream, Size: 13327 bytes --]

execve("/usr/bin/ntpq", ["ntpq", "-p"], [/* 33 vars */]) = 0
brk(0)                                  = 0x15dd000
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=160446, ...}) = 0
mmap(NULL, 160446, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f2d0dfcc000
close(3)                                = 0
open("/usr/lib/libcrypto.so.1.0.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0J\6\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0555, st_size=2392233, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dfcb000
mmap(NULL, 4230344, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0d9ca000
mprotect(0x7f2d0dbaa000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0dda9000, 155648, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1df000) = 0x7f2d0dda9000
mmap(0x7f2d0ddcf000, 15560, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f2d0ddcf000
close(3)                                = 0
open("/usr/lib/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\"\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=31760, ...}) = 0
mmap(NULL, 2128880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0d7c2000
mprotect(0x7f2d0d7c9000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0d9c8000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7f2d0d9c8000
close(3)                                = 0
open("/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\30\2\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=2009849, ...}) = 0
mmap(NULL, 3828816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0d41b000
mprotect(0x7f2d0d5b9000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0d7b8000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19d000) = 0x7f2d0d7b8000
mmap(0x7f2d0d7be000, 15440, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f2d0d7be000
close(3)                                = 0
open("/usr/lib/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\16\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=14648, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dfca000
mmap(NULL, 2109720, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0d217000
mprotect(0x7f2d0d21a000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0d419000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f2d0d419000
close(3)                                = 0
open("/usr/lib/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\"\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=88656, ...}) = 0
mmap(NULL, 2183688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0d001000
mprotect(0x7f2d0d016000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0d215000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14000) = 0x7f2d0d215000
close(3)                                = 0
open("/usr/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@l\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=137998, ...}) = 0
mmap(NULL, 2208640, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0cde5000
mprotect(0x7f2d0cdfc000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0cffb000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7f2d0cffb000
mmap(0x7f2d0cffd000, 13184, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f2d0cffd000
close(3)                                = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dfc9000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dfc8000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dfc7000
arch_prctl(ARCH_SET_FS, 0x7f2d0dfc8700) = 0
mprotect(0x7f2d0d7b8000, 16384, PROT_READ) = 0
mprotect(0x7f2d0cffb000, 4096, PROT_READ) = 0
mprotect(0x7f2d0d215000, 4096, PROT_READ) = 0
mprotect(0x7f2d0d419000, 4096, PROT_READ) = 0
mprotect(0x7f2d0d9c8000, 4096, PROT_READ) = 0
mprotect(0x7f2d0dda9000, 110592, PROT_READ) = 0
mprotect(0x630000, 4096, PROT_READ)     = 0
mprotect(0x7f2d0dff4000, 4096, PROT_READ) = 0
munmap(0x7f2d0dfcc000, 160446)          = 0
set_tid_address(0x7f2d0dfc89d0)         = 12278
set_robust_list(0x7f2d0dfc89e0, 24)     = 0
rt_sigaction(SIGRTMIN, {0x7f2d0cdeb7a0, [], SA_RESTORER|SA_SIGINFO, 0x7f2d0cdf4170}, NULL, 8) = 0
rt_sigaction(SIGRT_1, {0x7f2d0cdeb820, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7f2d0cdf4170}, NULL, 8) = 0
rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 3
close(3)                                = 0
socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 3
getsockname(3, {sa_family=AF_INET6, sin6_port=htons(0), inet_pton(AF_INET6, "::", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, [28]) = 0
close(3)                                = 0
brk(0)                                  = 0x15dd000
brk(0x15fe000)                          = 0x15fe000
getcwd("/home/hinnie", 4096)            = 13
stat("/home/hinnie", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
stat("/home/hinnie/.ntprc", 0x7fff8f955dc0) = -1 ENOENT (No such file or directory)
lstat("/home", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
lstat("/home/hinnie", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
stat("/home/hinnie", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
stat("/home/hinnie/.ntprc", 0x7fff8f955dc0) = -1 ENOENT (No such file or directory)
lstat("/home", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
lstat("/home/hinnie", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
stat("/home/hinnie", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
stat("/home/hinnie/.ntprc", 0x7fff8f955dc0) = -1 ENOENT (No such file or directory)
getcwd("/home/hinnie", 4096)            = 13
stat("/home/hinnie", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
stat("/home/hinnie/.ntprc", 0x7fff8f955dc0) = -1 ENOENT (No such file or directory)
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
open("/etc/nsswitch.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=223, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "# Begin /etc/nsswitch.conf\n\npass"..., 4096) = 223
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=160446, ...}) = 0
mmap(NULL, 160446, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f2d0dfcc000
close(3)                                = 0
open("/usr/lib/libnss_files.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\"\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=52136, ...}) = 0
mmap(NULL, 2148136, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f2d0cbd8000
mprotect(0x7f2d0cbe4000, 2093056, PROT_NONE) = 0
mmap(0x7f2d0cde3000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xb000) = 0x7f2d0cde3000
close(3)                                = 0
mprotect(0x7f2d0cde3000, 4096, PROT_READ) = 0
munmap(0x7f2d0dfcc000, 160446)          = 0
open("/etc/services", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=286369, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "# See the full IANA XML file at:"..., 4096) = 4096
read(3, "     97/tcp\nswift-rvf          9"..., 4096) = 4096
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
socket(PF_NETLINK, SOCK_RAW, 0)         = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=12278, groups=00000000}, [12]) = 0
sendto(3, "\24\0\0\0\26\0\1\3e%{P\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0e%{P\366/\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 108
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0e%{P\366/\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 128
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0e%{P\366/\0\0\0\0\0\0", 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
connect(4, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(4)                                = 0
close(3)                                = 0
open("/etc/services", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=286369, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "# See the full IANA XML file at:"..., 4096) = 4096
read(3, "     97/tcp\nswift-rvf          9"..., 4096) = 4096
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
open("/etc/host.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=63, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "#\n# /etc/host.conf\n#\n\norder host"..., 4096) = 63
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
futex(0x7f2d0d7c0620, FUTEX_WAKE_PRIVATE, 2147483647) = 0
open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=25, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "nameserver 192.168.178.1\n", 4096) = 25
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
uname({sys="Linux", node="hinniepc", ...}) = 0
open("/etc/hosts", O_RDONLY|O_CLOEXEC)  = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=213, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "#\n# /etc/hosts: static lookup ta"..., 4096) = 213
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
open("/etc/gai.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=3045, ...}) = 0
fstat(3, {st_mode=S_IFREG|0644, st_size=3045, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f2d0dff3000
read(3, "# Configuration for getaddrinfo("..., 4096) = 3045
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7f2d0dff3000, 4096)            = 0
futex(0x7f2d0d7bf0b8, FUTEX_WAKE_PRIVATE, 2147483647) = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(51374), sin_addr=inet_addr("127.0.0.1")}, [16]) = 0
close(3)                                = 0
socket(PF_INET6, SOCK_DGRAM, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0
getsockname(3, {sa_family=AF_INET6, sin6_port=htons(48076), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, [28]) = 0
close(3)                                = 0
socket(PF_INET6, SOCK_DGRAM, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0
sendto(3, "\26\1\0\1\0\0\0\0\0\0\0\0", 12, 0, NULL, 0) = 12
select(4, [3], NULL, NULL, {5, 0})      = 0 (Timeout)
sendto(3, "\26\1\0\1\0\0\0\0\0\0\0\0", 12, 0, NULL, 0) = 12
select(4, [3], NULL, NULL, {5, 0})      = 0 (Timeout)
write(2, "localhost.localdomain: timed out"..., 51localhost.localdomain: timed out, nothing received
) = 51
write(2, "***Request timed out\n", 21***Request timed out
)  = 21
exit_group(0)                           = ?
+++ exited with 0 +++


[-- Attachment #3: tcpdump --]
[-- Type: application/octet-stream, Size: 428 bytes --]

17:19:11.213395 IP6 (hlim 64, next-header UDP (17) payload length: 20) localhost.localdomain.46138 > localhost.localdomain.ntp: [udp sum ok] NTPv2, length 12
	Root Delay: 0.000000, Root dispersion: 0.000000 [|ntp]
17:19:16.218453 IP6 (hlim 64, next-header UDP (17) payload length: 20) localhost.localdomain.46138 > localhost.localdomain.ntp: [udp sum ok] NTPv2, length 12
	Root Delay: 0.000000, Root dispersion: 0.000000 [|ntp]

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:56                     ` Jan Hinnerk Stosch
@ 2012-10-14 21:02                       ` Eric Dumazet
  0 siblings, 0 replies; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 21:02 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 22:56 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> > On Sun, 2012-10-14 at 22:45 +0200, Eric Dumazet wrote:
> >
> >> OK so IPv6 UDP frames are delivered to ntpd
> >>
> >> could you strace it while doing one "ntpq -p"
> >>
> >> strace -p 368
> >>
> >
> > Also please post "ip -6 addr"
> >
> >
> 
> ip -6 addr gives:
> 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436
>     inet6 ::1/128 scope host
>        valid_lft forever preferred_lft forever
> 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qlen 1000
>     inet6 fe80::922b:34ff:fe33:c292/64 scope link
>        valid_lft forever preferred_lft forever
> 
> And the output of "strace ntpq -p" is attached in file "ntpq-p".
> 
> I also did a tcpdump earlier this day as told in the bugtracker. Find
> the parts containing "ntpd" attached in file "tcpdump".

I already got these files, thank you

I asked a strace of the ntpd daemon, since we now know frames are
delivered to the application. I want to check what ntpd does of these
frames, since apparently it gives no answer.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 20:39               ` Jan Hinnerk Stosch
  2012-10-14 20:45                 ` Eric Dumazet
@ 2012-10-14 21:07                 ` Eric Dumazet
  2012-10-14 21:21                   ` Jan Hinnerk Stosch
  1 sibling, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 21:07 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 22:39 +0200, Jan Hinnerk Stosch wrote:

> ntpd    368  ntp   16u     IPv4              10453      0t0    UDP *:ntp
> ntpd    368  ntp   17u     IPv6              10454      0t0    UDP *:ntp
> ntpd    368  ntp   18u     IPv4              10460      0t0    UDP
> localhost.localdomain:ntp
> ntpd    368  ntp   19u     IPv4              10461      0t0    UDP
> hinniepc.fritz.box:ntp
> ntpd    368  ntp   20u     IPv6               8775      0t0    UDP
> [fe80::922b:34ff:fe33:c292]:ntp
> ntpd    368  ntp   21u  netlink                         0t0  10463 ROUTE

Hmm, your ntpd doesnt listen normally to ::1 UDP 123

grep ntpd /var/log/syslog :

(Here I have : )

ntpd[4912]: ntpd 4.2.6p3@1.2290-o Mon Aug 20 15:15:21 UTC 2012 (1)
ntpd[4913]: proto: precision = 0.980 usec
ntpd[4913]: ntp_io: estimated max descriptors: 1024, initial socket
boundary: 16
ntpd[4913]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
ntpd[4913]: Listen and drop on 1 v6wildcard :: UDP 123
ntpd[4913]: Listen normally on 2 lo 127.0.0.1 UDP 123
ntpd[4913]: Listen normally on 3 eth1 172.30.42.8 UDP 123
ntpd[4913]: Listen normally on 4 lo ::1 UDP 123
ntpd[4913]: Listen normally on 5 eth1 fe80::222:15ff:fe87:3ff6 UDP 123
ntpd[4913]: peers refreshed
ntpd[4913]: Listening on routing socket on fd #22 for interface updates

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 21:07                 ` Eric Dumazet
@ 2012-10-14 21:21                   ` Jan Hinnerk Stosch
  2012-10-14 21:35                     ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 21:21 UTC (permalink / raw)
  To: Eric Dumazet, netdev

[-- Attachment #1: Type: text/plain, Size: 2269 bytes --]

2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> On Sun, 2012-10-14 at 22:39 +0200, Jan Hinnerk Stosch wrote:
>
>> ntpd    368  ntp   16u     IPv4              10453      0t0    UDP *:ntp
>> ntpd    368  ntp   17u     IPv6              10454      0t0    UDP *:ntp
>> ntpd    368  ntp   18u     IPv4              10460      0t0    UDP
>> localhost.localdomain:ntp
>> ntpd    368  ntp   19u     IPv4              10461      0t0    UDP
>> hinniepc.fritz.box:ntp
>> ntpd    368  ntp   20u     IPv6               8775      0t0    UDP
>> [fe80::922b:34ff:fe33:c292]:ntp
>> ntpd    368  ntp   21u  netlink                         0t0  10463 ROUTE
>
> Hmm, your ntpd doesnt listen normally to ::1 UDP 123
>
> grep ntpd /var/log/syslog :
>
> (Here I have : )
>
> ntpd[4912]: ntpd 4.2.6p3@1.2290-o Mon Aug 20 15:15:21 UTC 2012 (1)
> ntpd[4913]: proto: precision = 0.980 usec
> ntpd[4913]: ntp_io: estimated max descriptors: 1024, initial socket
> boundary: 16
> ntpd[4913]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
> ntpd[4913]: Listen and drop on 1 v6wildcard :: UDP 123
> ntpd[4913]: Listen normally on 2 lo 127.0.0.1 UDP 123
> ntpd[4913]: Listen normally on 3 eth1 172.30.42.8 UDP 123
> ntpd[4913]: Listen normally on 4 lo ::1 UDP 123
> ntpd[4913]: Listen normally on 5 eth1 fe80::222:15ff:fe87:3ff6 UDP 123
> ntpd[4913]: peers refreshed
> ntpd[4913]: Listening on routing socket on fd #22 for interface updates
>
>

Ah, ok. Didn't read correctly..

journalctl /usr/bin/ntpd gives:
Oct 14 16:51:22 hinniepc ntpd[368]: proto: precision = 0.117 usec
Oct 14 16:51:22 hinniepc ntpd[368]: ntp_io: estimated max descriptors:
1024, initial socket boundary: 16
Oct 14 16:51:22 hinniepc ntpd[368]: Listen and drop on 0 v4wildcard
0.0.0.0 UDP 123
Oct 14 16:51:22 hinniepc ntpd[368]: Listen and drop on 1 v6wildcard :: UDP 123
Oct 14 16:51:22 hinniepc ntpd[368]: Listen normally on 2 lo 127.0.0.1 UDP 123
Oct 14 16:51:22 hinniepc ntpd[368]: Listen normally on 3 eth0
192.168.178.27 UDP 123
Oct 14 16:51:22 hinniepc ntpd[368]: Listen normally on 4 lo ::1 UDP 123
Oct 14 16:51:22 hinniepc ntpd[368]: peers refreshed
Oct 14 16:51:22 hinniepc ntpd[368]: Listening on routing socket on fd
#21 for interface updates

and the output of strace -p `pidof ntpd` is in file "stracentpd".

[-- Attachment #2: stracentpd --]
[-- Type: application/octet-stream, Size: 5184 bytes --]

Process 368 attached
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [17])
select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [17], left {0, 0})
recvfrom(17, "\26\1\0\1\0\0\0\0\0\0\0\0", 1000, 0, {sa_family=AF_INET6, sin6_port=htons(34694), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, [28]) = 12
recvfrom(17, 0x7fff3c8073e0, 1000, 0, 0x7fff3c8073c0, 0x7fff3c80736c) = -1 EAGAIN (Resource temporarily unavailable)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [17])
select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [17], left {0, 0})
recvfrom(17, "\26\1\0\1\0\0\0\0\0\0\0\0", 1000, 0, {sa_family=AF_INET6, sin6_port=htons(34694), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, [28]) = 12
recvfrom(17, 0x7fff3c8073e0, 1000, 0, 0x7fff3c8073c0, 0x7fff3c80736c) = -1 EAGAIN (Resource temporarily unavailable)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
--- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=28729632} ---
rt_sigreturn()                          = -1 EINTR (Interrupted system call)
select(22, [16 17 18 19 20 21], NULL, NULL, NULL^CProcess 368 detached
 <detached ...>

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 21:21                   ` Jan Hinnerk Stosch
@ 2012-10-14 21:35                     ` Eric Dumazet
  2012-10-14 21:54                       ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 21:35 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 23:21 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> > On Sun, 2012-10-14 at 22:39 +0200, Jan Hinnerk Stosch wrote:
> >
> >> ntpd    368  ntp   16u     IPv4              10453      0t0    UDP *:ntp
> >> ntpd    368  ntp   17u     IPv6              10454      0t0    UDP *:ntp
> >> ntpd    368  ntp   18u     IPv4              10460      0t0    UDP
> >> localhost.localdomain:ntp
> >> ntpd    368  ntp   19u     IPv4              10461      0t0    UDP
> >> hinniepc.fritz.box:ntp
> >> ntpd    368  ntp   20u     IPv6               8775      0t0    UDP
> >> [fe80::922b:34ff:fe33:c292]:ntp
> >> ntpd    368  ntp   21u  netlink                         0t0  10463 ROUTE
> >
> > Hmm, your ntpd doesnt listen normally to ::1 UDP 123
> >
> > grep ntpd /var/log/syslog :
> >
> > (Here I have : )
> >
> > ntpd[4912]: ntpd 4.2.6p3@1.2290-o Mon Aug 20 15:15:21 UTC 2012 (1)
> > ntpd[4913]: proto: precision = 0.980 usec
> > ntpd[4913]: ntp_io: estimated max descriptors: 1024, initial socket
> > boundary: 16
> > ntpd[4913]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
> > ntpd[4913]: Listen and drop on 1 v6wildcard :: UDP 123
> > ntpd[4913]: Listen normally on 2 lo 127.0.0.1 UDP 123
> > ntpd[4913]: Listen normally on 3 eth1 172.30.42.8 UDP 123
> > ntpd[4913]: Listen normally on 4 lo ::1 UDP 123
> > ntpd[4913]: Listen normally on 5 eth1 fe80::222:15ff:fe87:3ff6 UDP 123
> > ntpd[4913]: peers refreshed
> > ntpd[4913]: Listening on routing socket on fd #22 for interface updates
> >
> >
> 
> Ah, ok. Didn't read correctly..
> 
> journalctl /usr/bin/ntpd gives:
> Oct 14 16:51:22 hinniepc ntpd[368]: proto: precision = 0.117 usec
> Oct 14 16:51:22 hinniepc ntpd[368]: ntp_io: estimated max descriptors:
> 1024, initial socket boundary: 16
> Oct 14 16:51:22 hinniepc ntpd[368]: Listen and drop on 0 v4wildcard
> 0.0.0.0 UDP 123
> Oct 14 16:51:22 hinniepc ntpd[368]: Listen and drop on 1 v6wildcard :: UDP 123
> Oct 14 16:51:22 hinniepc ntpd[368]: Listen normally on 2 lo 127.0.0.1 UDP 123
> Oct 14 16:51:22 hinniepc ntpd[368]: Listen normally on 3 eth0
> 192.168.178.27 UDP 123
> Oct 14 16:51:22 hinniepc ntpd[368]: Listen normally on 4 lo ::1 UDP 123
> Oct 14 16:51:22 hinniepc ntpd[368]: peers refreshed
> Oct 14 16:51:22 hinniepc ntpd[368]: Listening on routing socket on fd
> #21 for interface updates

So ntpd claims to "Listen normally on 4 lo ::1 UDP 123" but lsof doesnt
display a socket bound to ::1 port 123

So I would say there is a ntpd bug.

You could try to strace ntpd from the very beginning of its life, so
that we check what it does exactly.

service ntp stop
strace -o /tmp/STRACE /usr/bin/ntpd -g -u ntp:ntp

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 21:35                     ` Eric Dumazet
@ 2012-10-14 21:54                       ` Jan Hinnerk Stosch
  2012-10-14 22:01                         ` Eric Dumazet
  2012-10-15  9:07                         ` Jan Ceuleers
  0 siblings, 2 replies; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 21:54 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: netdev

[-- Attachment #1: Type: text/plain, Size: 510 bytes --]

2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> So ntpd claims to "Listen normally on 4 lo ::1 UDP 123" but lsof doesnt
> display a socket bound to ::1 port 123
>
> So I would say there is a ntpd bug.
>
> You could try to strace ntpd from the very beginning of its life, so
> that we check what it does exactly.
>
> service ntp stop
> strace -o /tmp/STRACE /usr/bin/ntpd -g -u ntp:ntp
>
>
>

Ok, here is the strace output. I wonder whether this is enough as it
exits very early - ntp still running though.

[-- Attachment #2: ntpstrace --]
[-- Type: application/octet-stream, Size: 8079 bytes --]

execve("/usr/bin/ntpd", ["/usr/bin/ntpd", "-g", "-u", "ntp:ntp"], [/* 16 vars */]) = 0
brk(0)                                  = 0xbb0000
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=160446, ...}) = 0
mmap(NULL, 160446, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fcfba479000
close(3)                                = 0
open("/usr/lib/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0V\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=1022344, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba478000
mmap(NULL, 3117352, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb9f86000
mprotect(0x7fcfba07f000, 2093056, PROT_NONE) = 0
mmap(0x7fcfba27e000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xf8000) = 0x7fcfba27e000
close(3)                                = 0
open("/usr/lib/libcrypto.so.1.0.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0J\6\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0555, st_size=2392233, ...}) = 0
mmap(NULL, 4230344, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb9b7d000
mprotect(0x7fcfb9d5d000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb9f5c000, 155648, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1df000) = 0x7fcfb9f5c000
mmap(0x7fcfb9f82000, 15560, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fcfb9f82000
close(3)                                = 0
open("/usr/lib/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\"\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=31760, ...}) = 0
mmap(NULL, 2128880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb9975000
mprotect(0x7fcfb997c000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb9b7b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7fcfb9b7b000
close(3)                                = 0
open("/usr/lib/libcap.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\25\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=16776, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba477000
mmap(NULL, 2111968, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb9771000
mprotect(0x7fcfb9775000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb9974000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7fcfb9974000
close(3)                                = 0
open("/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\30\2\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=2009849, ...}) = 0
mmap(NULL, 3828816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb93ca000
mprotect(0x7fcfb9568000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb9767000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19d000) = 0x7fcfb9767000
mmap(0x7fcfb976d000, 15440, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fcfb976d000
close(3)                                = 0
open("/usr/lib/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\16\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=14648, ...}) = 0
mmap(NULL, 2109720, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb91c6000
mprotect(0x7fcfb91c9000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb93c8000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7fcfb93c8000
close(3)                                = 0
open("/usr/lib/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\"\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=88656, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba476000
mmap(NULL, 2183688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb8fb0000
mprotect(0x7fcfb8fc5000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb91c4000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14000) = 0x7fcfb91c4000
close(3)                                = 0
open("/usr/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@l\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=137998, ...}) = 0
mmap(NULL, 2208640, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb8d94000
mprotect(0x7fcfb8dab000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb8faa000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7fcfb8faa000
mmap(0x7fcfb8fac000, 13184, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fcfb8fac000
close(3)                                = 0
open("/usr/lib/libattr.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \24\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=18760, ...}) = 0
mmap(NULL, 2113880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fcfb8b8f000
mprotect(0x7fcfb8b93000, 2093056, PROT_NONE) = 0
mmap(0x7fcfb8d92000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7fcfb8d92000
close(3)                                = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba475000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba474000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba473000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba472000
arch_prctl(ARCH_SET_FS, 0x7fcfba473700) = 0
mprotect(0x7fcfb9767000, 16384, PROT_READ) = 0
mprotect(0x7fcfb8d92000, 4096, PROT_READ) = 0
mprotect(0x7fcfb8faa000, 4096, PROT_READ) = 0
mprotect(0x7fcfb91c4000, 4096, PROT_READ) = 0
mprotect(0x7fcfb93c8000, 4096, PROT_READ) = 0
mprotect(0x7fcfb9b7b000, 4096, PROT_READ) = 0
mprotect(0x7fcfb9f5c000, 110592, PROT_READ) = 0
mprotect(0x7fcfba27e000, 4096, PROT_READ) = 0
mprotect(0x686000, 4096, PROT_READ)     = 0
mprotect(0x7fcfba4a1000, 4096, PROT_READ) = 0
munmap(0x7fcfba479000, 160446)          = 0
set_tid_address(0x7fcfba4739d0)         = 12570
set_robust_list(0x7fcfba4739e0, 24)     = 0
rt_sigaction(SIGRTMIN, {0x7fcfb8d9a7a0, [], SA_RESTORER|SA_SIGINFO, 0x7fcfb8da3170}, NULL, 8) = 0
rt_sigaction(SIGRT_1, {0x7fcfb8d9a820, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7fcfb8da3170}, NULL, 8) = 0
rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
brk(0)                                  = 0xbb0000
brk(0xbd1000)                           = 0xbd1000
socket(PF_FILE, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 3
connect(3, {sa_family=AF_FILE, sun_path="/dev/log"}, 110) = 0
open("/etc/localtime", O_RDONLY|O_CLOEXEC) = 4
fstat(4, {st_mode=S_IFREG|0644, st_size=2309, ...}) = 0
fstat(4, {st_mode=S_IFREG|0644, st_size=2309, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fcfba4a0000
read(4, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\10\0\0\0\10\0\0\0\0"..., 4096) = 2309
lseek(4, -1467, SEEK_CUR)               = 842
read(4, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\t\0\0\0\t\0\0\0\0"..., 4096) = 1467
lseek(4, 2308, SEEK_SET)                = 2308
close(4)                                = 0
munmap(0x7fcfba4a0000, 4096)            = 0
sendto(3, "<29>Oct 14 23:42:42 ntpd[12570]:"..., 87, MSG_NOSIGNAL, NULL, 0) = 87
umask(0)                                = 022
umask(022)                              = 0
getuid()                                = 0
clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fcfba4739d0) = 12571
exit_group(0)                           = ?
+++ exited with 0 +++

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 21:54                       ` Jan Hinnerk Stosch
@ 2012-10-14 22:01                         ` Eric Dumazet
  2012-10-14 22:11                           ` Jan Hinnerk Stosch
  2012-10-15  9:07                         ` Jan Ceuleers
  1 sibling, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-14 22:01 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Sun, 2012-10-14 at 23:54 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/14 Eric Dumazet <eric.dumazet@gmail.com>:
> > So ntpd claims to "Listen normally on 4 lo ::1 UDP 123" but lsof doesnt
> > display a socket bound to ::1 port 123
> >
> > So I would say there is a ntpd bug.
> >
> > You could try to strace ntpd from the very beginning of its life, so
> > that we check what it does exactly.
> >
> > service ntp stop
> > strace -o /tmp/STRACE /usr/bin/ntpd -g -u ntp:ntp
> >
> >
> >
> 
> Ok, here is the strace output. I wonder whether this is enough as it
> exits very early - ntp still running though.

Please add -f to follow children

strace -o /tmp/STRACE -f /usr/bin/ntpd -g -u ntp:ntp

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 22:01                         ` Eric Dumazet
@ 2012-10-14 22:11                           ` Jan Hinnerk Stosch
  2012-10-15  5:42                             ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-14 22:11 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: netdev

[-- Attachment #1: Type: text/plain, Size: 157 bytes --]

2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
> Please add -f to follow children
>
> strace -o /tmp/STRACE -f /usr/bin/ntpd -g -u ntp:ntp
>
>

Ok, done.

[-- Attachment #2: STRACE --]
[-- Type: application/octet-stream, Size: 68573 bytes --]

12927 execve("/usr/bin/ntpd", ["/usr/bin/ntpd", "-g", "-u", "ntp:ntp"], [/* 16 vars */]) = 0
12927 brk(0)                            = 0x718000
12927 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
12927 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
12927 fstat(3, {st_mode=S_IFREG|0644, st_size=160446, ...}) = 0
12927 mmap(NULL, 160446, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fc54a6f2000
12927 close(3)                          = 0
12927 open("/usr/lib/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0V\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=1022344, ...}) = 0
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6f1000
12927 mmap(NULL, 3117352, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc54a1ff000
12927 mprotect(0x7fc54a2f8000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc54a4f7000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xf8000) = 0x7fc54a4f7000
12927 close(3)                          = 0
12927 open("/usr/lib/libcrypto.so.1.0.0", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0J\6\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0555, st_size=2392233, ...}) = 0
12927 mmap(NULL, 4230344, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc549df6000
12927 mprotect(0x7fc549fd6000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc54a1d5000, 155648, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1df000) = 0x7fc54a1d5000
12927 mmap(0x7fc54a1fb000, 15560, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fc54a1fb000
12927 close(3)                          = 0
12927 open("/usr/lib/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\"\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=31760, ...}) = 0
12927 mmap(NULL, 2128880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc549bee000
12927 mprotect(0x7fc549bf5000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc549df4000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7fc549df4000
12927 close(3)                          = 0
12927 open("/usr/lib/libcap.so.2", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\25\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=16776, ...}) = 0
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6f0000
12927 mmap(NULL, 2111968, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc5499ea000
12927 mprotect(0x7fc5499ee000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc549bed000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7fc549bed000
12927 close(3)                          = 0
12927 open("/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\30\2\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=2009849, ...}) = 0
12927 mmap(NULL, 3828816, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc549643000
12927 mprotect(0x7fc5497e1000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc5499e0000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19d000) = 0x7fc5499e0000
12927 mmap(0x7fc5499e6000, 15440, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fc5499e6000
12927 close(3)                          = 0
12927 open("/usr/lib/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\16\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=14648, ...}) = 0
12927 mmap(NULL, 2109720, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc54943f000
12927 mprotect(0x7fc549442000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc549641000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7fc549641000
12927 close(3)                          = 0
12927 open("/usr/lib/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\"\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=88656, ...}) = 0
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6ef000
12927 mmap(NULL, 2183688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc549229000
12927 mprotect(0x7fc54923e000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc54943d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14000) = 0x7fc54943d000
12927 close(3)                          = 0
12927 open("/usr/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@l\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=137998, ...}) = 0
12927 mmap(NULL, 2208640, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc54900d000
12927 mprotect(0x7fc549024000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc549223000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7fc549223000
12927 mmap(0x7fc549225000, 13184, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fc549225000
12927 close(3)                          = 0
12927 open("/usr/lib/libattr.so.1", O_RDONLY|O_CLOEXEC) = 3
12927 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \24\0\0\0\0\0\0"..., 832) = 832
12927 fstat(3, {st_mode=S_IFREG|0755, st_size=18760, ...}) = 0
12927 mmap(NULL, 2113880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc548e08000
12927 mprotect(0x7fc548e0c000, 2093056, PROT_NONE) = 0
12927 mmap(0x7fc54900b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7fc54900b000
12927 close(3)                          = 0
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6ee000
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6ed000
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6ec000
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a6eb000
12927 arch_prctl(ARCH_SET_FS, 0x7fc54a6ec700) = 0
12927 mprotect(0x7fc5499e0000, 16384, PROT_READ) = 0
12927 mprotect(0x7fc54900b000, 4096, PROT_READ) = 0
12927 mprotect(0x7fc549223000, 4096, PROT_READ) = 0
12927 mprotect(0x7fc54943d000, 4096, PROT_READ) = 0
12927 mprotect(0x7fc549641000, 4096, PROT_READ) = 0
12927 mprotect(0x7fc549df4000, 4096, PROT_READ) = 0
12927 mprotect(0x7fc54a1d5000, 110592, PROT_READ) = 0
12927 mprotect(0x7fc54a4f7000, 4096, PROT_READ) = 0
12927 mprotect(0x686000, 4096, PROT_READ) = 0
12927 mprotect(0x7fc54a71a000, 4096, PROT_READ) = 0
12927 munmap(0x7fc54a6f2000, 160446)    = 0
12927 set_tid_address(0x7fc54a6ec9d0)   = 12927
12927 set_robust_list(0x7fc54a6ec9e0, 24) = 0
12927 rt_sigaction(SIGRTMIN, {0x7fc5490137a0, [], SA_RESTORER|SA_SIGINFO, 0x7fc54901c170}, NULL, 8) = 0
12927 rt_sigaction(SIGRT_1, {0x7fc549013820, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7fc54901c170}, NULL, 8) = 0
12927 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
12927 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
12927 brk(0)                            = 0x718000
12927 brk(0x739000)                     = 0x739000
12927 socket(PF_FILE, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 3
12927 connect(3, {sa_family=AF_FILE, sun_path="/dev/log"}, 110) = 0
12927 open("/etc/localtime", O_RDONLY|O_CLOEXEC) = 4
12927 fstat(4, {st_mode=S_IFREG|0644, st_size=2309, ...}) = 0
12927 fstat(4, {st_mode=S_IFREG|0644, st_size=2309, ...}) = 0
12927 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a719000
12927 read(4, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\10\0\0\0\10\0\0\0\0"..., 4096) = 2309
12927 lseek(4, -1467, SEEK_CUR)         = 842
12927 read(4, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\t\0\0\0\t\0\0\0\0"..., 4096) = 1467
12927 lseek(4, 2308, SEEK_SET)          = 2308
12927 close(4)                          = 0
12927 munmap(0x7fc54a719000, 4096)      = 0
12927 sendto(3, "<29>Oct 15 00:02:29 ntpd[12927]:"..., 87, MSG_NOSIGNAL, NULL, 0) = 87
12927 umask(0)                          = 022
12927 umask(022)                        = 0
12927 getuid()                          = 0
12927 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc54a6ec9d0) = 12928
12928 set_robust_list(0x7fc54a6ec9e0, 24 <unfinished ...>
12927 exit_group(0)                     = ?
12928 <... set_robust_list resumed> )   = 0
12928 setsid()                          = 12928
12928 chdir("/")                        = 0
12927 +++ exited with 0 +++
12928 open("/dev/null", O_RDWR)         = 4
12928 fstat(4, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 3), ...}) = 0
12928 dup2(4, 0)                        = 0
12928 dup2(4, 1)                        = 1
12928 dup2(4, 2)                        = 2
12928 close(4)                          = 0
12928 rt_sigaction(SIGHUP, {0x4146c0, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGINT, {0x4146c0, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGQUIT, {0x4146c0, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGTERM, {0x4146c0, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGBUS, {0x4146c0, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGUSR1, {0x414490, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGUSR2, {0x414440, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigaction(SIGPIPE, {SIG_IGN, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 timer_create(CLOCK_REALTIME, {0x718120, SIGALRM, SIGEV_SIGNAL, {...}}, {0}) = 0
12928 rt_sigaction(SIGALRM, {0x42fc60, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 timer_settime(0, 0, {it_interval={1, 0}, it_value={1, 0}}, NULL) = 0
12928 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 4
12928 close(4)                          = 0
12928 socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 4
12928 getsockname(4, {sa_family=AF_INET6, sin6_port=htons(0), inet_pton(AF_INET6, "::", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, [28]) = 0
12928 close(4)                          = 0
12928 uname({sys="Linux", node="hinniepc", ...}) = 0
12928 sendto(3, "<29>Oct 15 00:02:29 ntpd[12928]:"..., 62, MSG_NOSIGNAL, NULL, 0) = 62
12928 rt_sigaction(SIGSYS, {0x420d60, [], SA_RESTORER, 0x7fc549678020}, {SIG_DFL, [], 0}, 8) = 0
12928 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
12928 adjtimex({modes=ADJ_OFFSET|ADJ_MAXERROR|ADJ_ESTERROR|ADJ_STATUS|ADJ_TIMECONST, offset=0, freq=380673, maxerror=16, esterror=16, status=STA_PLL|STA_NANO, constant=3, precision=1, tolerance=32768000, time={1350252149, 821199283}, tick=10000, ppsfreq=0, jitter=0, shift=0, stabil=0, jitcnt=0, calcnt=0, errcnt=0, stbcnt=0}) = 0 (TIME_OK)
12928 rt_sigaction(SIGSYS, {SIG_DFL, [], SA_RESTORER, 0x7fc549678020}, NULL, 8) = 0
12928 open("/etc/ntp.conf", O_RDONLY)   = 4
12928 fstat(4, {st_mode=S_IFREG|0644, st_size=716, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a719000
12928 read(4, "# With the default settings belo"..., 4096) = 716
12928 read(4, "", 4096)                 = 0
12928 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5
12928 getrlimit(RLIMIT_NOFILE, {rlim_cur=1024, rlim_max=4*1024}) = 0
12928 getrlimit(RLIMIT_NOFILE, {rlim_cur=1024, rlim_max=4*1024}) = 0
12928 getrlimit(RLIMIT_NOFILE, {rlim_cur=1024, rlim_max=4*1024}) = 0
12928 sendto(3, "<31>Oct 15 00:02:29 ntpd[12928]:"..., 101, MSG_NOSIGNAL, NULL, 0) = 101
12928 fcntl(5, F_DUPFD, 16)             = 16
12928 close(5)                          = 0
12928 setsockopt(16, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 setsockopt(16, SOL_IP, IP_TOS, [192], 4) = 0
12928 bind(16, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
12928 setsockopt(16, SOL_SOCKET, SO_TIMESTAMP, [1], 4) = 0
12928 fcntl(16, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 80, MSG_NOSIGNAL, NULL, 0) = 80
12928 socket(PF_INET6, SOCK_DGRAM, IPPROTO_IP) = 5
12928 fcntl(5, F_DUPFD, 16)             = 17
12928 close(5)                          = 0
12928 setsockopt(17, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 5
12928 setsockopt(5, SOL_IPV6, IPV6_V6ONLY, [1], 4) = 0
12928 close(5)                          = 0
12928 socket(PF_INET6, SOCK_DGRAM, IPPROTO_IP) = 5
12928 setsockopt(5, SOL_IPV6, IPV6_V6ONLY, [1], 4) = 0
12928 close(5)                          = 0
12928 close(5)                          = -1 EBADF (Bad file descriptor)
12928 setsockopt(17, SOL_IPV6, IPV6_V6ONLY, [1], 4) = 0
12928 bind(17, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "::", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0
12928 setsockopt(17, SOL_SOCKET, SO_TIMESTAMP, [1], 4) = 0
12928 fcntl(17, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
12928 brk(0x75a000)                     = 0x75a000
12928 brk(0x758000)                     = 0x758000
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 75, MSG_NOSIGNAL, NULL, 0) = 75
12928 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5
12928 ioctl(5, SIOCGIFCONF, {80, {{"lo", {AF_INET, inet_addr("127.0.0.1")}}, {"eth0", {AF_INET, inet_addr("192.168.178.27")}}}}) = 0
12928 open("/proc/net/if_inet6", O_RDONLY) = 6
12928 fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a718000
12928 lseek(6, 0, SEEK_SET)             = 0
12928 read(6, "fe80000000000000922b34fffe33c292"..., 1024) = 54
12928 ioctl(5, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
12928 ioctl(5, SIOCGIFNETMASK, {ifr_name="lo", ifr_netmask={AF_INET, inet_addr("255.0.0.0")}}) = 0
12928 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
12928 fcntl(7, F_DUPFD, 16)             = 18
12928 close(7)                          = 0
12928 setsockopt(18, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
12928 setsockopt(18, SOL_IP, IP_TOS, [192], 4) = 0
12928 setsockopt(16, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
12928 bind(18, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
12928 setsockopt(16, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 setsockopt(18, SOL_SOCKET, SO_TIMESTAMP, [1], 4) = 0
12928 fcntl(18, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 74, MSG_NOSIGNAL, NULL, 0) = 74
12928 ioctl(5, SIOCGIFFLAGS, {ifr_name="eth0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
12928 ioctl(5, SIOCGIFBRDADDR, {ifr_name="eth0", ifr_broadaddr={AF_INET, inet_addr("192.168.178.255")}}) = 0
12928 ioctl(5, SIOCGIFNETMASK, {ifr_name="eth0", ifr_netmask={AF_INET, inet_addr("255.255.255.0")}}) = 0
12928 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
12928 fcntl(7, F_DUPFD, 16)             = 19
12928 close(7)                          = 0
12928 setsockopt(19, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
12928 setsockopt(19, SOL_IP, IP_TOS, [192], 4) = 0
12928 setsockopt(16, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
12928 bind(19, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.27")}, 16) = 0
12928 setsockopt(16, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 setsockopt(19, SOL_SOCKET, SO_TIMESTAMP, [1], 4) = 0
12928 fcntl(19, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 81, MSG_NOSIGNAL, NULL, 0) = 81
12928 setsockopt(19, SOL_IP, IP_MULTICAST_IF, [464693440], 4) = 0
12928 ioctl(5, SIOCGIFFLAGS, {ifr_name="eth0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
12928 socket(PF_INET6, SOCK_DGRAM, IPPROTO_IP) = 7
12928 fcntl(7, F_DUPFD, 16)             = 20
12928 close(7)                          = 0
12928 setsockopt(20, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
12928 setsockopt(20, SOL_IPV6, IPV6_V6ONLY, [1], 4) = 0
12928 setsockopt(17, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
12928 bind(20, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "fe80::922b:34ff:fe33:c292", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=if_nametoindex("eth0")}, 28) = 0
12928 setsockopt(17, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 setsockopt(20, SOL_SOCKET, SO_TIMESTAMP, [1], 4) = 0
12928 fcntl(20, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 92, MSG_NOSIGNAL, NULL, 0) = 92
12928 setsockopt(20, SOL_IPV6, IPV6_MULTICAST_IF, [2], 4) = 0
12928 read(6, "", 1024)                 = 0
12928 close(5)                          = 0
12928 lseek(6, 54, SEEK_SET)            = 54
12928 close(6)                          = 0
12928 munmap(0x7fc54a718000, 4096)      = 0
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 48, MSG_NOSIGNAL, NULL, 0) = 48
12928 setsockopt(20, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 setsockopt(19, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 setsockopt(18, SOL_SOCKET, SO_REUSEADDR, [0], 4) = 0
12928 socket(PF_NETLINK, SOCK_RAW, 0)   = 5
12928 fcntl(5, F_DUPFD, 16)             = 21
12928 close(5)                          = 0
12928 bind(21, {sa_family=AF_NETLINK, pid=0, groups=00000771}, 12) = 0
12928 fcntl(21, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
12928 sendto(3, "<30>Oct 15 00:02:29 ntpd[12928]:"..., 92, MSG_NOSIGNAL, NULL, 0) = 92
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 5
12928 connect(5, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(5)                          = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 5
12928 connect(5, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(5)                          = 0
12928 open("/etc/nsswitch.conf", O_RDONLY|O_CLOEXEC) = 5
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=223, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a718000
12928 read(5, "# Begin /etc/nsswitch.conf\n\npass"..., 4096) = 223
12928 read(5, "", 4096)                 = 0
12928 close(5)                          = 0
12928 munmap(0x7fc54a718000, 4096)      = 0
12928 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=160446, ...}) = 0
12928 mmap(NULL, 160446, PROT_READ, MAP_PRIVATE, 5, 0) = 0x7fc54a6c3000
12928 close(5)                          = 0
12928 open("/usr/lib/libnss_files.so.2", O_RDONLY|O_CLOEXEC) = 5
12928 read(5, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\"\0\0\0\0\0\0"..., 832) = 832
12928 fstat(5, {st_mode=S_IFREG|0755, st_size=52136, ...}) = 0
12928 mmap(NULL, 2148136, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 5, 0) = 0x7fc548bfb000
12928 mprotect(0x7fc548c07000, 2093056, PROT_NONE) = 0
12928 mmap(0x7fc548e06000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 5, 0xb000) = 0x7fc548e06000
12928 close(5)                          = 0
12928 mprotect(0x7fc548e06000, 4096, PROT_READ) = 0
12928 munmap(0x7fc54a6c3000, 160446)    = 0
12928 open("/etc/services", O_RDONLY|O_CLOEXEC) = 5
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=286369, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a718000
12928 read(5, "# See the full IANA XML file at:"..., 4096) = 4096
12928 read(5, "     97/tcp\nswift-rvf          9"..., 4096) = 4096
12928 close(5)                          = 0
12928 munmap(0x7fc54a718000, 4096)      = 0
12928 open("/etc/services", O_RDONLY|O_CLOEXEC) = 5
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=286369, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a718000
12928 read(5, "# See the full IANA XML file at:"..., 4096) = 4096
12928 read(5, "     97/tcp\nswift-rvf          9"..., 4096) = 4096
12928 close(5)                          = 0
12928 munmap(0x7fc54a718000, 4096)      = 0
12928 open("/var/lib/ntp/ntp.drift", O_RDONLY) = 5
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=6, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a718000
12928 read(5, "5.928\n", 4096)          = 6
12928 close(5)                          = 0
12928 munmap(0x7fc54a718000, 4096)      = 0
12928 open("/var/log/ntp.log", O_WRONLY|O_CREAT|O_APPEND, 0666) = 5
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=163, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a718000
12928 fstat(5, {st_mode=S_IFREG|0644, st_size=163, ...}) = 0
12928 lseek(5, 163, SEEK_SET)           = 163
12928 open("/etc/services", O_RDONLY|O_CLOEXEC) = 6
12928 fstat(6, {st_mode=S_IFREG|0644, st_size=286369, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a717000
12928 read(6, "# See the full IANA XML file at:"..., 4096) = 4096
12928 read(6, "     97/tcp\nswift-rvf          9"..., 4096) = 4096
12928 close(6)                          = 0
12928 munmap(0x7fc54a717000, 4096)      = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 6
12928 connect(6, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(6)                          = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 6
12928 connect(6, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(6)                          = 0
12928 open("/etc/host.conf", O_RDONLY|O_CLOEXEC) = 6
12928 fstat(6, {st_mode=S_IFREG|0644, st_size=63, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a717000
12928 read(6, "#\n# /etc/host.conf\n#\n\norder host"..., 4096) = 63
12928 read(6, "", 4096)                 = 0
12928 close(6)                          = 0
12928 munmap(0x7fc54a717000, 4096)      = 0
12928 futex(0x7fc5499e8620, FUTEX_WAKE_PRIVATE, 2147483647) = 0
12928 open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 6
12928 fstat(6, {st_mode=S_IFREG|0644, st_size=25, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a717000
12928 read(6, "nameserver 192.168.178.1\n", 4096) = 25
12928 read(6, "", 4096)                 = 0
12928 close(6)                          = 0
12928 munmap(0x7fc54a717000, 4096)      = 0
12928 uname({sys="Linux", node="hinniepc", ...}) = 0
12928 open("/etc/hosts", O_RDONLY|O_CLOEXEC) = 6
12928 fstat(6, {st_mode=S_IFREG|0644, st_size=213, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a717000
12928 read(6, "#\n# /etc/hosts: static lookup ta"..., 4096) = 213
12928 read(6, "", 4096)                 = 0
12928 close(6)                          = 0
12928 munmap(0x7fc54a717000, 4096)      = 0
12928 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 6
12928 fstat(6, {st_mode=S_IFREG|0644, st_size=160446, ...}) = 0
12928 mmap(NULL, 160446, PROT_READ, MAP_PRIVATE, 6, 0) = 0x7fc54a6c3000
12928 close(6)                          = 0
12928 open("/usr/lib/libnss_dns.so.2", O_RDONLY|O_CLOEXEC) = 6
12928 read(6, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\21\0\0\0\0\0\0"..., 832) = 832
12928 fstat(6, {st_mode=S_IFREG|0755, st_size=22936, ...}) = 0
12928 mmap(NULL, 2117872, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 6, 0) = 0x7fc5489f5000
12928 mprotect(0x7fc5489fa000, 2093056, PROT_NONE) = 0
12928 mmap(0x7fc548bf9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 6, 0x4000) = 0x7fc548bf9000
12928 close(6)                          = 0
12928 open("/usr/lib/libresolv.so.2", O_RDONLY|O_CLOEXEC) = 6
12928 read(6, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200:\0\0\0\0\0\0"..., 832) = 832
12928 fstat(6, {st_mode=S_IFREG|0755, st_size=84832, ...}) = 0
12928 mmap(NULL, 2189928, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 6, 0) = 0x7fc5487de000
12928 mprotect(0x7fc5487f1000, 2097152, PROT_NONE) = 0
12928 mmap(0x7fc5489f1000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 6, 0x13000) = 0x7fc5489f1000
12928 mmap(0x7fc5489f3000, 6760, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fc5489f3000
12928 close(6)                          = 0
12928 mprotect(0x7fc5489f1000, 4096, PROT_READ) = 0
12928 mprotect(0x7fc548bf9000, 4096, PROT_READ) = 0
12928 munmap(0x7fc54a6c3000, 160446)    = 0
12928 socket(PF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 6
12928 connect(6, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.178.1")}, 16) = 0
12928 poll([{fd=6, events=POLLOUT}], 1, 0) = 1 ([{fd=6, revents=POLLOUT}])
12928 sendmmsg(6, {{{msg_name(0)=NULL, msg_iov(1)=[{"\322\207\1\0\0\1\0\0\0\0\0\0\5fritz\3box\0\0\1\0\1", 27}], msg_controllen=0, msg_flags=0}, 27}, {{msg_name(0)=NULL, msg_iov(1)=[{"@\37\1\0\0\1\0\0\0\0\0\0\5fritz\3box\0\0\34\0\1", 27}], msg_controllen=0, msg_flags=0}, 27}}, 2, MSG_NOSIGNAL) = 2
12928 poll([{fd=6, events=POLLIN}], 1, 5000) = 1 ([{fd=6, revents=POLLIN}])
12928 ioctl(6, FIONREAD, [73])          = 0
12928 recvfrom(6, "\322\207\204\200\0\1\0\1\0\1\0\1\5fritz\3box\0\0\1\0\1\300\f\0\1\0"..., 2048, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.178.1")}, [16]) = 73
12928 poll([{fd=6, events=POLLIN}], 1, 4998) = 1 ([{fd=6, revents=POLLIN}])
12928 ioctl(6, FIONREAD, [57])          = 0
12928 recvfrom(6, "@\37\204\200\0\1\0\0\0\1\0\1\5fritz\3box\0\0\34\0\1\300\f\0\2\0"..., 1975, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.178.1")}, [16]) = 57
12928 close(6)                          = 0
12928 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 6
12928 connect(6, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 0
12928 getsockname(6, {sa_family=AF_INET, sin_port=htons(39782), sin_addr=inet_addr("192.168.178.27")}, [16]) = 0
12928 close(6)                          = 0
12928 close(4)                          = 0
12928 munmap(0x7fc54a719000, 4096)      = 0
12928 adjtimex({modes=ADJ_FREQUENCY, offset=0, freq=388497, maxerror=16, esterror=16, status=STA_PLL|STA_NANO, constant=3, precision=1, tolerance=32768000, time={1350252149, 833115061}, tick=10000, ppsfreq=0, jitter=0, shift=0, stabil=0, jitcnt=0, calcnt=0, errcnt=0, stbcnt=0}) = 0 (TIME_OK)
12928 prctl(PR_SET_KEEPCAPS, 1)         = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
12928 connect(4, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(4)                          = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
12928 connect(4, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(4)                          = 0
12928 open("/etc/passwd", O_RDONLY|O_CLOEXEC) = 4
12928 fstat(4, {st_mode=S_IFREG|0644, st_size=697, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a719000
12928 read(4, "root:x:0:0:root:/root:/bin/bash\n"..., 4096) = 697
12928 close(4)                          = 0
12928 munmap(0x7fc54a719000, 4096)      = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
12928 connect(4, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(4)                          = 0
12928 socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
12928 connect(4, {sa_family=AF_FILE, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
12928 close(4)                          = 0
12928 open("/etc/group", O_RDONLY|O_CLOEXEC) = 4
12928 fstat(4, {st_mode=S_IFREG|0644, st_size=588, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a719000
12928 read(4, "root:x:0:root\nbin:x:1:root,bin,d"..., 4096) = 588
12928 close(4)                          = 0
12928 munmap(0x7fc54a719000, 4096)      = 0
12928 open("/proc/sys/kernel/ngroups_max", O_RDONLY) = 4
12928 read(4, "65536\n", 31)            = 6
12928 close(4)                          = 0
12928 open("/etc/group", O_RDONLY|O_CLOEXEC) = 4
12928 fstat(4, {st_mode=S_IFREG|0644, st_size=588, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a719000
12928 lseek(4, 0, SEEK_CUR)             = 0
12928 read(4, "root:x:0:root\nbin:x:1:root,bin,d"..., 4096) = 588
12928 read(4, "", 4096)                 = 0
12928 lseek(4, 588, SEEK_SET)           = 588
12928 close(4)                          = 0
12928 munmap(0x7fc54a719000, 4096)      = 0
12928 setgroups(1, [87])                = 0
12928 setgid(87)                        = 0
12928 setresgid(-1, 87, -1)             = 0
12928 setuid(87)                        = 0
12928 setresuid(-1, 87, -1)             = 0
12928 capget({_LINUX_CAPABILITY_VERSION_3, 0}, NULL) = 0
12928 capset({_LINUX_CAPABILITY_VERSION_3, 0}, {CAP_NET_BIND_SERVICE|CAP_SYS_TIME, CAP_NET_BIND_SERVICE|CAP_SYS_TIME, CAP_NET_BIND_SERVICE|CAP_SYS_TIME}) = 0
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "\343\0\6\351\0\0\0\0\0\0\0\0INIT\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 4
12928 ioctl(4, SIOCGIFCONF, {80, {{"lo", {AF_INET, inet_addr("127.0.0.1")}}, {"eth0", {AF_INET, inet_addr("192.168.178.27")}}}}) = 0
12928 open("/proc/net/if_inet6", O_RDONLY) = 6
12928 fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc54a719000
12928 lseek(6, 0, SEEK_SET)             = 0
12928 read(6, "fe80000000000000922b34fffe33c292"..., 1024) = 54
12928 ioctl(4, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
12928 ioctl(4, SIOCGIFNETMASK, {ifr_name="lo", ifr_netmask={AF_INET, inet_addr("255.0.0.0")}}) = 0
12928 ioctl(4, SIOCGIFFLAGS, {ifr_name="eth0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
12928 ioctl(4, SIOCGIFBRDADDR, {ifr_name="eth0", ifr_broadaddr={AF_INET, inet_addr("192.168.178.255")}}) = 0
12928 ioctl(4, SIOCGIFNETMASK, {ifr_name="eth0", ifr_netmask={AF_INET, inet_addr("255.255.255.0")}}) = 0
12928 ioctl(4, SIOCGIFFLAGS, {ifr_name="eth0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
12928 read(6, "", 1024)                 = 0
12928 close(4)                          = 0
12928 lseek(6, 54, SEEK_SET)            = 54
12928 close(6)                          = 0
12928 munmap(0x7fc54a719000, 4096)      = 0
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\234\203\274\3\335\324%\264[\27\307`O\324%\264\366\321a.h"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "\343\0\6\351\0\0\0\0\0\0\0\2INIT\0\0\0\0\0\0\0\0\324%\264\366\321\213\366Y"..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\236\203\274\3\335\324%\264[\27\307`O\324%\264\370\321b%L"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "\343\0\6\351\0\0\0\0\0\0\0\4INIT\0\0\0\0\0\0\0\0\324%\264\370\321\212\351\352"..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\237\203\274\3\335\324%\264[\27\307`O\324%\264\372\321a\177\213"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "\343\0\6\351\0\0\0\0\0\0\0\6INIT\0\0\0\0\0\0\0\0\324%\264\372\321\212 \226"..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\240\203\274\3\335\324%\264[\27\307`O\324%\264\374\321b^\3"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 adjtimex({modes=ADJ_OFFSET|ADJ_MAXERROR|ADJ_ESTERROR|ADJ_STATUS|ADJ_TIMECONST|0x2000, offset=-28197, freq=388484, maxerror=1000703, esterror=9, status=STA_PLL|STA_NANO, constant=6, precision=1, tolerance=32768000, time={1350252156, 819317574}, tick=10000, ppsfreq=0, jitter=0, shift=0, stabil=0, jitcnt=0, calcnt=0, errcnt=0, stbcnt=0}) = 0 (TIME_OK)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 open("/dev/urandom", O_RDONLY|O_NOCTTY|O_NONBLOCK) = 4
12928 fstat(4, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 9), ...}) = 0
12928 poll([{fd=4, events=POLLIN}], 1, 10) = 1 ([{fd=4, revents=POLLIN}])
12928 read(4, ">8\346\2017M\346ZA\245<\373\271\210\242b\272\350\212\351\341\373,x\r76\261\322\215\256\332", 32) = 32
12928 close(4)                          = 0
12928 getuid()                          = 87
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "#\3\6\351\0\0\17\212\0\0\370j\300\250\262\1\324%\264\374\321\233o:\324%\264\374\321\213\263="..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\242\203\274\3\335\324%\264[\27\307`O\324%\264\376\321dBM"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "#\3\6\351\0\0\17\212\0\0\370l\300\250\262\1\324%\264\374\321\233o:\324%\264\376\321\220(\27"..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\243\203\274\3\335\324%\264[\27\307`O\324%\265\0\321c\305%"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 sendto(19, "#\3\6\351\0\0\17\212\0\0\370\244\300\250\262\1\324%\264\374\321\233o:\324%\265\0\321\214[\3"..., 48, 0, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, 16) = 48
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = 1 (in [19])
12928 select(22, [16 17 18 19 20 21], NULL, NULL, {0, 0}) = 1 (in [19], left {0, 0})
12928 recvmsg(19, {msg_name(16)={sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.1")}, msg_iov(1)=[{"\34\2\6\355\0\0\17o\0\0\0\311\203\274\3\335\324%\264[\27\307`O\324%\2659\321c\362\353"..., 2120}], msg_controllen=32, {cmsg_len=32, cmsg_level=SOL_SOCKET, cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 48
12928 recvmsg(19, 0x7fff37c323f0, 0)    = -1 EAGAIN (Resource temporarily unavailable)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL) = ? ERESTARTNOHAND (Interrupted by signal)
12928 --- SIGALRM {si_signo=SIGALRM, si_code=SI_TIMER, si_pid=0, si_uid=0, si_value=7438624} ---
12928 rt_sigreturn()                    = -1 EINTR (Interrupted system call)
12928 select(22, [16 17 18 19 20 21], NULL, NULL, NULL

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 22:11                           ` Jan Hinnerk Stosch
@ 2012-10-15  5:42                             ` Eric Dumazet
  2012-10-15  7:03                               ` Jan Hinnerk Stosch
                                                 ` (2 more replies)
  0 siblings, 3 replies; 40+ messages in thread
From: Eric Dumazet @ 2012-10-15  5:42 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Mon, 2012-10-15 at 00:11 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
> > Please add -f to follow children
> >
> > strace -o /tmp/STRACE -f /usr/bin/ntpd -g -u ntp:ntp
> >
> >
> 
> Ok, done.

# grep bind STRACE



12928 bind(16, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
12928 bind(17, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "::", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0

(These two binds are the wildcards to drop ipv4/ipv6 packets sent on other addresses than
 the following :)

12928 bind(18, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
12928 bind(19, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.27")}, 16) = 0
12928 bind(20, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "fe80::922b:34ff:fe33:c292", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=if_nametoindex("eth0")}, 28) = 0
12928 bind(21, {sa_family=AF_NETLINK, pid=0, groups=00000771}, 12) = 0

So ntpd doesnt listen on ntp messages sent on ::1

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  5:42                             ` Eric Dumazet
@ 2012-10-15  7:03                               ` Jan Hinnerk Stosch
  2012-10-15  7:14                               ` Joe Perches
  2012-10-15  9:25                               ` regression when connecting to ipv6 localhost Eric Dumazet
  2 siblings, 0 replies; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-15  7:03 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: netdev

2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
> # grep bind STRACE
>
>
>
> 12928 bind(16, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> 12928 bind(17, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "::", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0
>
> (These two binds are the wildcards to drop ipv4/ipv6 packets sent on other addresses than
>  the following :)
>
> 12928 bind(18, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
> 12928 bind(19, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.27")}, 16) = 0
> 12928 bind(20, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "fe80::922b:34ff:fe33:c292", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=if_nametoindex("eth0")}, 28) = 0
> 12928 bind(21, {sa_family=AF_NETLINK, pid=0, groups=00000771}, 12) = 0
>
> So ntpd doesnt listen on ntp messages sent on ::1
>
>
Ok, so I will report it to the guys from ntp. Thank you for your help
and time debugging this!

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  5:42                             ` Eric Dumazet
  2012-10-15  7:03                               ` Jan Hinnerk Stosch
@ 2012-10-15  7:14                               ` Joe Perches
  2012-10-15  7:39                                 ` Eric Dumazet
  2012-10-15  9:25                               ` regression when connecting to ipv6 localhost Eric Dumazet
  2 siblings, 1 reply; 40+ messages in thread
From: Joe Perches @ 2012-10-15  7:14 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: Jan Hinnerk Stosch, netdev

On Mon, 2012-10-15 at 07:42 +0200, Eric Dumazet wrote:
> So ntpd doesnt listen on ntp messages sent on ::1

So how is it a regression?
How did it ever work before?
Did some kernel change cause it to fail?
If so, are there more apps like it?

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  7:14                               ` Joe Perches
@ 2012-10-15  7:39                                 ` Eric Dumazet
  2012-10-15  7:43                                   ` Eric Dumazet
  2012-10-15 11:25                                   ` Joe Perches
  0 siblings, 2 replies; 40+ messages in thread
From: Eric Dumazet @ 2012-10-15  7:39 UTC (permalink / raw)
  To: Joe Perches; +Cc: Jan Hinnerk Stosch, netdev

On Mon, 2012-10-15 at 00:14 -0700, Joe Perches wrote:
> On Mon, 2012-10-15 at 07:42 +0200, Eric Dumazet wrote:
> > So ntpd doesnt listen on ntp messages sent on ::1
> 
> So how is it a regression?
> How did it ever work before?
> Did some kernel change cause it to fail?
> If so, are there more apps like it?
> 

Thanks for your help Joe.

What about trying to answer yourself to these questions ?

You can have a valid patch that uncover a bug in an application, it
happens everyday.

So if you want to help, grab ntpd sources, reproduce the problem, make a
bisection so that we have a clue of the kernel change that trigger the
problem, and so on...

That would be really awesome, given Jan is not a kernel developer, we
cannot really ask him to do this himself.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  7:39                                 ` Eric Dumazet
@ 2012-10-15  7:43                                   ` Eric Dumazet
  2012-10-15 11:25                                   ` Joe Perches
  1 sibling, 0 replies; 40+ messages in thread
From: Eric Dumazet @ 2012-10-15  7:43 UTC (permalink / raw)
  To: Joe Perches; +Cc: Jan Hinnerk Stosch, netdev

On Mon, 2012-10-15 at 09:39 +0200, Eric Dumazet wrote:

> So if you want to help, grab ntpd sources, reproduce the problem, make a
> bisection so that we have a clue of the kernel change that trigger the
> problem, and so on...
> 
> That would be really awesome, given Jan is not a kernel developer, we
> cannot really ask him to do this himself.
> 

And before you ask, on my machine, ntpd does listen to ::1, so I dont
have the problem. If I cant reproduce it, I probably wont fix the
problem.

...

ntpd    2964  ntp   16u     IPv4              17966      0t0     UDP *:ntp 
ntpd    2964  ntp   17u     IPv6              17967      0t0     UDP *:ntp 
ntpd    2964  ntp   18u     IPv4              17973      0t0     UDP 127.0.0.1:ntp 
ntpd    2964  ntp   19u     IPv4              17974      0t0     UDP 172.30.42.8:ntp 
ntpd    2964  ntp   20u     IPv6              17975      0t0     UDP [::1]:ntp 
ntpd    2964  ntp   21u     IPv6              17976      0t0     UDP [fe80::222:15ff:fe87:3ff6]:ntp 
ntpd    2964  ntp   22u  netlink                         0t0   17977 ROUTE

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-14 21:54                       ` Jan Hinnerk Stosch
  2012-10-14 22:01                         ` Eric Dumazet
@ 2012-10-15  9:07                         ` Jan Ceuleers
  1 sibling, 0 replies; 40+ messages in thread
From: Jan Ceuleers @ 2012-10-15  9:07 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: Eric Dumazet, netdev

On 10/14/2012 11:54 PM, Jan Hinnerk Stosch wrote:
> Ok, here is the strace output. I wonder whether this is enough as it
> exits very early - ntp still running though.

When starting, ntpd forks a copy and daemonises it. The original process
then parses the configuration file, passes the parsed configuration
incrementally to the daemon process and then exits.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  5:42                             ` Eric Dumazet
  2012-10-15  7:03                               ` Jan Hinnerk Stosch
  2012-10-15  7:14                               ` Joe Perches
@ 2012-10-15  9:25                               ` Eric Dumazet
  2012-10-15 10:32                                 ` Jan Hinnerk Stosch
  2 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-15  9:25 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Mon, 2012-10-15 at 07:42 +0200, Eric Dumazet wrote:
> On Mon, 2012-10-15 at 00:11 +0200, Jan Hinnerk Stosch wrote:
> > 2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
> > > Please add -f to follow children
> > >
> > > strace -o /tmp/STRACE -f /usr/bin/ntpd -g -u ntp:ntp
> > >
> > >
> > 
> > Ok, done.
> 
> # grep bind STRACE
> 
> 
> 
> 12928 bind(16, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
> 12928 bind(17, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "::", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0
> 
> (These two binds are the wildcards to drop ipv4/ipv6 packets sent on other addresses than
>  the following :)
> 
> 12928 bind(18, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
> 12928 bind(19, {sa_family=AF_INET, sin_port=htons(123), sin_addr=inet_addr("192.168.178.27")}, 16) = 0
> 12928 bind(20, {sa_family=AF_INET6, sin6_port=htons(123), inet_pton(AF_INET6, "fe80::922b:34ff:fe33:c292", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=if_nametoindex("eth0")}, 28) = 0
> 12928 bind(21, {sa_family=AF_NETLINK, pid=0, groups=00000771}, 12) = 0
> 
> So ntpd doesnt listen on ntp messages sent on ::1
> 
> 

Hmm, another strange thing in your STRACE file is :


12928 open("/proc/net/if_inet6", O_RDONLY) = 6
12928 fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0x7fc54a719000
12928 lseek(6, 0, SEEK_SET)             = 0
12928 read(6, "fe80000000000000922b34fffe33c292"..., 1024) = 54


So /proc/net/if_inet6 doesnt include lo device on your machine ?

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  9:25                               ` regression when connecting to ipv6 localhost Eric Dumazet
@ 2012-10-15 10:32                                 ` Jan Hinnerk Stosch
  0 siblings, 0 replies; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-15 10:32 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: netdev

> Hmm, another strange thing in your STRACE file is :
>
>
> 12928 open("/proc/net/if_inet6", O_RDONLY) = 6
> 12928 fstat(6, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> 12928 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
> -1, 0) = 0x7fc54a719000
> 12928 lseek(6, 0, SEEK_SET)             = 0
> 12928 read(6, "fe80000000000000922b34fffe33c292"..., 1024) = 54
>
>
> So /proc/net/if_inet6 doesnt include lo device on your machine ?
>
>
>

@Jan Ceuleers: Thank you, for your explanation!

@Eric Dumazet: I will be at home in some hours and will check the
/proc/net/if_inet6 file.
I can offer you to compile some code reversions. I even looked at the
changes at kernelnewbies.org/LinuxChanges but did not know where to
start.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15  7:39                                 ` Eric Dumazet
  2012-10-15  7:43                                   ` Eric Dumazet
@ 2012-10-15 11:25                                   ` Joe Perches
  2012-10-15 12:31                                     ` Eric Dumazet
  1 sibling, 1 reply; 40+ messages in thread
From: Joe Perches @ 2012-10-15 11:25 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: Jan Hinnerk Stosch, netdev

On Mon, 2012-10-15 at 09:39 +0200, Eric Dumazet wrote:
> On Mon, 2012-10-15 at 00:14 -0700, Joe Perches wrote:
> > On Mon, 2012-10-15 at 07:42 +0200, Eric Dumazet wrote:
> > > So ntpd doesnt listen on ntp messages sent on ::1

> You can have a valid patch that uncover a bug in an application, it
> happens everyday.

<shrug> you can have a behavior that important applications
rely on and not be able to change the kernel because of it.

It looks more like a recently introduced app defect though.

> That would be really awesome, given Jan is not a kernel developer, we
> cannot really ask him to do this himself.

Jan's using a newer version of ntp than you are.
https://bugs.archlinux.org/task/31775
ntp 4.2.6.p5-11

Perhaps the defect was introduced in the ntp code with
this change:

http://bk.ntp.org/ntp-stable/lib/isc/unix/interfaceiter.c?PAGE=diffs&REV=4e12bf8dJoMBgRoYzI9851nTqVBhgQ

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15 11:25                                   ` Joe Perches
@ 2012-10-15 12:31                                     ` Eric Dumazet
  2012-10-15 13:10                                       ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-15 12:31 UTC (permalink / raw)
  To: Joe Perches; +Cc: Jan Hinnerk Stosch, netdev

On Mon, 2012-10-15 at 04:25 -0700, Joe Perches wrote:

> 
> It looks more like a recently introduced app defect though.
> 

> Jan's using a newer version of ntp than you are.
> https://bugs.archlinux.org/task/31775
> ntp 4.2.6.p5-11

> Perhaps the defect was introduced in the ntp code with
> this change:
> 
> http://bk.ntp.org/ntp-stable/lib/isc/unix/interfaceiter.c?PAGE=diffs&REV=4e12bf8dJoMBgRoYzI9851nTqVBhgQ


It seems you missed fact that /proc/net/if_inet6 doesnt include lo
device for Jan machine.

Even an old ntpd (or ifconfig -a) wont see lo device ::1 address if not
listed in /proc/net/if_inet6

"ip -6 addr" doesnt parse /proc/net/if_inet6

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15 12:31                                     ` Eric Dumazet
@ 2012-10-15 13:10                                       ` Jan Hinnerk Stosch
  2012-10-15 13:21                                         ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-15 13:10 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: Joe Perches, netdev

2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
> On Mon, 2012-10-15 at 04:25 -0700, Joe Perches wrote:
>
>>
>> It looks more like a recently introduced app defect though.
>>
>
>> Jan's using a newer version of ntp than you are.
>> https://bugs.archlinux.org/task/31775
>> ntp 4.2.6.p5-11
>
>> Perhaps the defect was introduced in the ntp code with
>> this change:
>>
>> http://bk.ntp.org/ntp-stable/lib/isc/unix/interfaceiter.c?PAGE=diffs&REV=4e12bf8dJoMBgRoYzI9851nTqVBhgQ
>
>
> It seems you missed fact that /proc/net/if_inet6 doesnt include lo
> device for Jan machine.
>
> Even an old ntpd (or ifconfig -a) wont see lo device ::1 address if not
> listed in /proc/net/if_inet6
>
> "ip -6 addr" doesnt parse /proc/net/if_inet6
>

Now I can confirm, that the file contains no loopback interface.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15 13:10                                       ` Jan Hinnerk Stosch
@ 2012-10-15 13:21                                         ` Eric Dumazet
  2012-10-15 13:28                                           ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-15 13:21 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: Joe Perches, netdev

On Mon, 2012-10-15 at 15:10 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
> > On Mon, 2012-10-15 at 04:25 -0700, Joe Perches wrote:
> >
> >>
> >> It looks more like a recently introduced app defect though.
> >>
> >
> >> Jan's using a newer version of ntp than you are.
> >> https://bugs.archlinux.org/task/31775
> >> ntp 4.2.6.p5-11
> >
> >> Perhaps the defect was introduced in the ntp code with
> >> this change:
> >>
> >> http://bk.ntp.org/ntp-stable/lib/isc/unix/interfaceiter.c?PAGE=diffs&REV=4e12bf8dJoMBgRoYzI9851nTqVBhgQ
> >
> >
> > It seems you missed fact that /proc/net/if_inet6 doesnt include lo
> > device for Jan machine.
> >
> > Even an old ntpd (or ifconfig -a) wont see lo device ::1 address if not
> > listed in /proc/net/if_inet6
> >
> > "ip -6 addr" doesnt parse /proc/net/if_inet6
> >
> 
> Now I can confirm, that the file contains no loopback interface.

OK thanks

Now we have to find why ::1 is missing.

Can you send : 

lsmod

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15 13:21                                         ` Eric Dumazet
@ 2012-10-15 13:28                                           ` Jan Hinnerk Stosch
  2012-10-16 16:07                                             ` Jan Hinnerk Stosch
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-15 13:28 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: netdev

[-- Attachment #1: Type: text/plain, Size: 158 bytes --]

2012/10/15 Eric Dumazet <eric.dumazet@gmail.com>:
>
> OK thanks
>
> Now we have to find why ::1 is missing.
>
> Can you send :
>
> lsmod
>
>

Yes. Attached..

[-- Attachment #2: lsmod --]
[-- Type: application/octet-stream, Size: 3835 bytes --]

Module                  Size  Used by
fuse                   69173  3 
saa7134_alsa           10878  0 
tda1004x               15485  1 
saa7134_dvb            25964  0 
videobuf_dvb            4890  1 saa7134_dvb
dvb_core               96168  1 videobuf_dvb
lm75                    4312  0 
it87                   29819  0 
hwmon_vid               3196  1 it87
iTCO_wdt                5255  0 
iTCO_vendor_support     1929  1 iTCO_wdt
snd_hda_codec_hdmi     24487  1 
snd_hda_codec_realtek    61033  1 
hid_logitech_dj        10189  0 
joydev                  9991  0 
tda827x                 9558  2 
hid_cherry              1577  0 
usbhid                 36996  1 hid_logitech_dj
hid                    85929  3 hid_cherry,usbhid,hid_logitech_dj
tda8290                12682  1 
tuner                  16843  1 
usb_storage            47320  1 
uas                    11119  0 
microcode              12377  0 
aesni_intel            42081  0 
aes_x86_64              7508  1 aesni_intel
aes_generic            26138  2 aesni_intel,aes_x86_64
ablk_helper             1972  1 aesni_intel
ghash_clmulni_intel     4237  0 
cryptd                  8741  3 ghash_clmulni_intel,aesni_intel,ablk_helper
kvm_intel             124749  0 
kvm                   373949  1 kvm_intel
coretemp                5974  0 
crc32c_intel            1987  0 
saa7134               168976  2 saa7134_dvb,saa7134_alsa
tveeprom               13777  1 saa7134
videobuf_dma_sg         7872  3 saa7134_dvb,saa7134,saa7134_alsa
psmouse                71909  0 
videobuf_core          16007  3 videobuf_dma_sg,saa7134,videobuf_dvb
v4l2_common             5370  2 tuner,saa7134
videodev              100859  3 tuner,saa7134,v4l2_common
serio_raw               4689  0 
media                  10405  1 videodev
rc_core                13088  1 saa7134
i2c_i801                9571  0 
pcspkr                  1899  0 
lpc_ich                10609  0 
snd_hda_intel          26180  1 
snd_hda_codec          98033  3 snd_hda_codec_realtek,snd_hda_codec_hdmi,snd_hda_intel
atl1c                  33646  0 
snd_hwdep               6300  1 snd_hda_codec
snd_pcm                75167  4 snd_hda_codec_hdmi,snd_hda_codec,snd_hda_intel,saa7134_alsa
snd_page_alloc          7217  2 snd_pcm,snd_hda_intel
snd_timer              18934  1 snd_pcm
snd                    59989  10 snd_hda_codec_realtek,snd_hwdep,snd_timer,snd_hda_codec_hdmi,snd_pcm,snd_hda_codec,snd_hda_intel,saa7134_alsa
soundcore               5442  1 snd
mei                    32665  0 
acpi_cpufreq            5933  0 
mperf                   1267  1 acpi_cpufreq
evdev                  10266  9 
fan                     2618  0 
processor              26855  1 acpi_cpufreq
thermal                 8119  0 
ext4                  440093  3 
crc16                   1359  1 ext4
jbd2                   78751  1 ext4
mbcache                 5977  1 ext4
sr_mod                 14823  0 
sd_mod                 29559  6 
cdrom                  35520  1 sr_mod
ahci                   21360  3 
libahci                20023  1 ahci
xhci_hcd               86922  0 
libata                167683  2 ahci,libahci
ehci_hcd               41816  0 
scsi_mod              133386  5 uas,usb_storage,libata,sd_mod,sr_mod
usbcore               150334  5 uas,usb_storage,ehci_hcd,usbhid,xhci_hcd
usb_common               954  1 usbcore
i915                  495105  2 
video                  11276  1 i915
button                  4662  1 i915
i2c_algo_bit            5391  1 i915
intel_agp              10744  1 i915
intel_gtt              15659  2 i915,intel_agp
drm_kms_helper         32877  1 i915
drm                   218724  3 i915,drm_kms_helper
i2c_core               20707  15 drm,i915,lm75,i2c_i801,tuner,saa7134_dvb,saa7134,drm_kms_helper,tda8290,tda827x,i2c_algo_bit,v4l2_common,tveeprom,tda1004x,videodev

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-15 13:28                                           ` Jan Hinnerk Stosch
@ 2012-10-16 16:07                                             ` Jan Hinnerk Stosch
  2012-10-16 16:43                                               ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-16 16:07 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: netdev

2012/10/15 Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>:
>
> Yes. Attached..

Today I read about the existence of the "git bisect" function and if I
have not done anything wrong the "bad commit" which lead to this
regression is ddbe503203855939946430e39bae58de11b70b69.
Perhaps this can help you.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-16 16:07                                             ` Jan Hinnerk Stosch
@ 2012-10-16 16:43                                               ` Eric Dumazet
  2012-10-16 16:55                                                 ` Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-16 16:43 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Tue, 2012-10-16 at 18:07 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/15 Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>:
> >
> > Yes. Attached..
> 
> Today I read about the existence of the "git bisect" function and if I
> have not done anything wrong the "bad commit" which lead to this
> regression is ddbe503203855939946430e39bae58de11b70b69.
> Perhaps this can help you.

Interesting, I was about to ask you to do a bisection ;)

So the hash value of ::1 was changed, and it has bad effect on your
machine...

Could it be hash collision and an old bug being uncovered ?

Could you trace inet6_addr_hash() a bit ?

diff --git a/net/ipv6/addrconf.c b/net/ipv6/addrconf.c
index d7c56f8..096c04c 100644
--- a/net/ipv6/addrconf.c
+++ b/net/ipv6/addrconf.c
@@ -580,7 +580,10 @@ ipv6_link_dev_addr(struct inet6_dev *idev, struct inet6_ifaddr *ifp)
 
 static u32 inet6_addr_hash(const struct in6_addr *addr)
 {
-	return hash_32(ipv6_addr_hash(addr), IN6_ADDR_HSIZE_SHIFT);
+	u32 res = hash_32(ipv6_addr_hash(addr), IN6_ADDR_HSIZE_SHIFT);
+
+	pr_err("inet6_addr_hash(%pi6) %u\n", addr, res);
+	return res;
 }
 
 /* On success it returns ifp with increased reference count */

^ permalink raw reply related	[flat|nested] 40+ messages in thread

* Re: regression when connecting to ipv6 localhost
  2012-10-16 16:43                                               ` Eric Dumazet
@ 2012-10-16 16:55                                                 ` Eric Dumazet
  2012-10-16 17:37                                                   ` [PATCH] ipv6: addrconf: fix /proc/net/if_inet6 Eric Dumazet
  0 siblings, 1 reply; 40+ messages in thread
From: Eric Dumazet @ 2012-10-16 16:55 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: netdev

On Tue, 2012-10-16 at 18:43 +0200, Eric Dumazet wrote:
> On Tue, 2012-10-16 at 18:07 +0200, Jan Hinnerk Stosch wrote:
> > 2012/10/15 Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>:
> > >
> > > Yes. Attached..
> > 
> > Today I read about the existence of the "git bisect" function and if I
> > have not done anything wrong the "bad commit" which lead to this
> > regression is ddbe503203855939946430e39bae58de11b70b69.
> > Perhaps this can help you.
> 
> Interesting, I was about to ask you to do a bisection ;)
> 
> So the hash value of ::1 was changed, and it has bad effect on your
> machine...
> 
> Could it be hash collision and an old bug being uncovered ?
> 
> Could you trace inet6_addr_hash() a bit ?

Dont bother, I found the bug. Its related to network namespaces

I'll send a fix asap.

Thanks !

^ permalink raw reply	[flat|nested] 40+ messages in thread

* [PATCH] ipv6: addrconf: fix /proc/net/if_inet6
  2012-10-16 16:55                                                 ` Eric Dumazet
@ 2012-10-16 17:37                                                   ` Eric Dumazet
  2012-10-16 18:30                                                     ` Jan Hinnerk Stosch
  2012-10-16 18:55                                                     ` David Miller
  0 siblings, 2 replies; 40+ messages in thread
From: Eric Dumazet @ 2012-10-16 17:37 UTC (permalink / raw)
  To: Jan Hinnerk Stosch, David Miller; +Cc: netdev, Mihai Maruseac

From: Eric Dumazet <edumazet@google.com>

Commit 1d5783030a1 (ipv6/addrconf: speedup /proc/net/if_inet6 filling)
added bugs hiding some devices from if_inet6 and breaking applications.

"ip -6 addr" could still display all IPv6 addresses, while "ifconfig -a"
couldnt.

One way to reproduce the bug is by starting in a shell :

unshare -n /bin/bash
ifconfig lo up

And in original net namespace, lo device disappeared from if_inet6

Reported-by: Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>
Signed-off-by: Eric Dumazet <edumazet@google.com>
Cc: Mihai Maruseac <mihai.maruseac@gmail.com>
---
Please Jan test following fix, thanks !

 net/ipv6/addrconf.c |   15 +++++++++------
 1 file changed, 9 insertions(+), 6 deletions(-)

diff --git a/net/ipv6/addrconf.c b/net/ipv6/addrconf.c
index d7c56f8..0424e4e 100644
--- a/net/ipv6/addrconf.c
+++ b/net/ipv6/addrconf.c
@@ -3064,14 +3064,15 @@ static struct inet6_ifaddr *if6_get_first(struct seq_file *seq, loff_t pos)
 		struct hlist_node *n;
 		hlist_for_each_entry_rcu_bh(ifa, n, &inet6_addr_lst[state->bucket],
 					 addr_lst) {
+			if (!net_eq(dev_net(ifa->idev->dev), net))
+				continue;
 			/* sync with offset */
 			if (p < state->offset) {
 				p++;
 				continue;
 			}
 			state->offset++;
-			if (net_eq(dev_net(ifa->idev->dev), net))
-				return ifa;
+			return ifa;
 		}
 
 		/* prepare for next bucket */
@@ -3089,18 +3090,20 @@ static struct inet6_ifaddr *if6_get_next(struct seq_file *seq,
 	struct hlist_node *n = &ifa->addr_lst;
 
 	hlist_for_each_entry_continue_rcu_bh(ifa, n, addr_lst) {
+		if (!net_eq(dev_net(ifa->idev->dev), net))
+			continue;
 		state->offset++;
-		if (net_eq(dev_net(ifa->idev->dev), net))
-			return ifa;
+		return ifa;
 	}
 
 	while (++state->bucket < IN6_ADDR_HSIZE) {
 		state->offset = 0;
 		hlist_for_each_entry_rcu_bh(ifa, n,
 				     &inet6_addr_lst[state->bucket], addr_lst) {
+			if (!net_eq(dev_net(ifa->idev->dev), net))
+				continue;
 			state->offset++;
-			if (net_eq(dev_net(ifa->idev->dev), net))
-				return ifa;
+			return ifa;
 		}
 	}
 

^ permalink raw reply related	[flat|nested] 40+ messages in thread

* Re: [PATCH] ipv6: addrconf: fix /proc/net/if_inet6
  2012-10-16 17:37                                                   ` [PATCH] ipv6: addrconf: fix /proc/net/if_inet6 Eric Dumazet
@ 2012-10-16 18:30                                                     ` Jan Hinnerk Stosch
  2012-10-16 18:39                                                       ` David Miller
  2012-10-16 22:01                                                       ` Joe Perches
  2012-10-16 18:55                                                     ` David Miller
  1 sibling, 2 replies; 40+ messages in thread
From: Jan Hinnerk Stosch @ 2012-10-16 18:30 UTC (permalink / raw)
  To: Eric Dumazet; +Cc: David Miller, netdev, Mihai Maruseac

2012/10/16 Eric Dumazet <eric.dumazet@gmail.com>:
> From: Eric Dumazet <edumazet@google.com>
>
> Commit 1d5783030a1 (ipv6/addrconf: speedup /proc/net/if_inet6 filling)
> added bugs hiding some devices from if_inet6 and breaking applications.
>
> "ip -6 addr" could still display all IPv6 addresses, while "ifconfig -a"
> couldnt.
>
> One way to reproduce the bug is by starting in a shell :
>
> unshare -n /bin/bash
> ifconfig lo up
>
> And in original net namespace, lo device disappeared from if_inet6
>
> Reported-by: Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>
> Signed-off-by: Eric Dumazet <edumazet@google.com>
> Cc: Mihai Maruseac <mihai.maruseac@gmail.com>
> ---
> Please Jan test following fix, thanks !
>
>  net/ipv6/addrconf.c |   15 +++++++++------
>  1 file changed, 9 insertions(+), 6 deletions(-)
>
> diff --git a/net/ipv6/addrconf.c b/net/ipv6/addrconf.c
> index d7c56f8..0424e4e 100644
> --- a/net/ipv6/addrconf.c
> +++ b/net/ipv6/addrconf.c
> @@ -3064,14 +3064,15 @@ static struct inet6_ifaddr *if6_get_first(struct seq_file *seq, loff_t pos)
>                 struct hlist_node *n;
>                 hlist_for_each_entry_rcu_bh(ifa, n, &inet6_addr_lst[state->bucket],
>                                          addr_lst) {
> +                       if (!net_eq(dev_net(ifa->idev->dev), net))
> +                               continue;
>                         /* sync with offset */
>                         if (p < state->offset) {
>                                 p++;
>                                 continue;
>                         }
>                         state->offset++;
> -                       if (net_eq(dev_net(ifa->idev->dev), net))
> -                               return ifa;
> +                       return ifa;
>                 }
>
>                 /* prepare for next bucket */
> @@ -3089,18 +3090,20 @@ static struct inet6_ifaddr *if6_get_next(struct seq_file *seq,
>         struct hlist_node *n = &ifa->addr_lst;
>
>         hlist_for_each_entry_continue_rcu_bh(ifa, n, addr_lst) {
> +               if (!net_eq(dev_net(ifa->idev->dev), net))
> +                       continue;
>                 state->offset++;
> -               if (net_eq(dev_net(ifa->idev->dev), net))
> -                       return ifa;
> +               return ifa;
>         }
>
>         while (++state->bucket < IN6_ADDR_HSIZE) {
>                 state->offset = 0;
>                 hlist_for_each_entry_rcu_bh(ifa, n,
>                                      &inet6_addr_lst[state->bucket], addr_lst) {
> +                       if (!net_eq(dev_net(ifa->idev->dev), net))
> +                               continue;
>                         state->offset++;
> -                       if (net_eq(dev_net(ifa->idev->dev), net))
> -                               return ifa;
> +                       return ifa;
>                 }
>         }
>
>
>

Yes, everything works fine again. Thank you very much!

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: [PATCH] ipv6: addrconf: fix /proc/net/if_inet6
  2012-10-16 18:30                                                     ` Jan Hinnerk Stosch
@ 2012-10-16 18:39                                                       ` David Miller
  2012-10-16 22:01                                                       ` Joe Perches
  1 sibling, 0 replies; 40+ messages in thread
From: David Miller @ 2012-10-16 18:39 UTC (permalink / raw)
  To: janhinnerk.stosch; +Cc: eric.dumazet, netdev, mihai.maruseac

From: Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>
Date: Tue, 16 Oct 2012 20:30:19 +0200

> 2012/10/16 Eric Dumazet <eric.dumazet@gmail.com>:
>> From: Eric Dumazet <edumazet@google.com>
>>
>> Commit 1d5783030a1 (ipv6/addrconf: speedup /proc/net/if_inet6 filling)
>> added bugs hiding some devices from if_inet6 and breaking applications.
>>
>> "ip -6 addr" could still display all IPv6 addresses, while "ifconfig -a"
>> couldnt.
>>
>> One way to reproduce the bug is by starting in a shell :
>>
>> unshare -n /bin/bash
>> ifconfig lo up
>>
>> And in original net namespace, lo device disappeared from if_inet6
>>
>> Reported-by: Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>
>> Signed-off-by: Eric Dumazet <edumazet@google.com>
>> Cc: Mihai Maruseac <mihai.maruseac@gmail.com>
 ...
> Yes, everything works fine again. Thank you very much!

Thanks for testing.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: [PATCH] ipv6: addrconf: fix /proc/net/if_inet6
  2012-10-16 17:37                                                   ` [PATCH] ipv6: addrconf: fix /proc/net/if_inet6 Eric Dumazet
  2012-10-16 18:30                                                     ` Jan Hinnerk Stosch
@ 2012-10-16 18:55                                                     ` David Miller
  1 sibling, 0 replies; 40+ messages in thread
From: David Miller @ 2012-10-16 18:55 UTC (permalink / raw)
  To: eric.dumazet; +Cc: janhinnerk.stosch, netdev, mihai.maruseac

From: Eric Dumazet <eric.dumazet@gmail.com>
Date: Tue, 16 Oct 2012 19:37:27 +0200

> From: Eric Dumazet <edumazet@google.com>
> 
> Commit 1d5783030a1 (ipv6/addrconf: speedup /proc/net/if_inet6 filling)
> added bugs hiding some devices from if_inet6 and breaking applications.
> 
> "ip -6 addr" could still display all IPv6 addresses, while "ifconfig -a"
> couldnt.
> 
> One way to reproduce the bug is by starting in a shell :
> 
> unshare -n /bin/bash
> ifconfig lo up
> 
> And in original net namespace, lo device disappeared from if_inet6
> 
> Reported-by: Jan Hinnerk Stosch <janhinnerk.stosch@gmail.com>
> Signed-off-by: Eric Dumazet <edumazet@google.com>
> Cc: Mihai Maruseac <mihai.maruseac@gmail.com>

Applied and queued up for -stable, thanks.

^ permalink raw reply	[flat|nested] 40+ messages in thread

* Re: [PATCH] ipv6: addrconf: fix /proc/net/if_inet6
  2012-10-16 18:30                                                     ` Jan Hinnerk Stosch
  2012-10-16 18:39                                                       ` David Miller
@ 2012-10-16 22:01                                                       ` Joe Perches
  1 sibling, 0 replies; 40+ messages in thread
From: Joe Perches @ 2012-10-16 22:01 UTC (permalink / raw)
  To: Jan Hinnerk Stosch; +Cc: Eric Dumazet, David Miller, netdev, Mihai Maruseac

On Tue, 2012-10-16 at 20:30 +0200, Jan Hinnerk Stosch wrote:
> 2012/10/16 Eric Dumazet <eric.dumazet@gmail.com>:
> > From: Eric Dumazet <edumazet@google.com>
> >
> > Commit 1d5783030a1 (ipv6/addrconf: speedup /proc/net/if_inet6 filling)
> > added bugs hiding some devices from if_inet6 and breaking applications.
> >
> > "ip -6 addr" could still display all IPv6 addresses, while "ifconfig -a"
> > couldnt.

Thanks Eric and Jan.  Well done.

^ permalink raw reply	[flat|nested] 40+ messages in thread

end of thread, other threads:[~2012-10-16 22:01 UTC | newest]

Thread overview: 40+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2012-10-14 15:38 regression when connecting to ipv6 localhost Jan Hinnerk Stosch
2012-10-14 19:29 ` Eric Dumazet
2012-10-14 19:48   ` Jan Hinnerk Stosch
2012-10-14 19:54     ` Eric Dumazet
2012-10-14 19:57       ` Jan Hinnerk Stosch
2012-10-14 20:25         ` Eric Dumazet
2012-10-14 20:26           ` Eric Dumazet
2012-10-14 20:28             ` Eric Dumazet
2012-10-14 20:39               ` Jan Hinnerk Stosch
2012-10-14 20:45                 ` Eric Dumazet
2012-10-14 20:47                   ` Eric Dumazet
2012-10-14 20:56                     ` Jan Hinnerk Stosch
2012-10-14 21:02                       ` Eric Dumazet
2012-10-14 21:07                 ` Eric Dumazet
2012-10-14 21:21                   ` Jan Hinnerk Stosch
2012-10-14 21:35                     ` Eric Dumazet
2012-10-14 21:54                       ` Jan Hinnerk Stosch
2012-10-14 22:01                         ` Eric Dumazet
2012-10-14 22:11                           ` Jan Hinnerk Stosch
2012-10-15  5:42                             ` Eric Dumazet
2012-10-15  7:03                               ` Jan Hinnerk Stosch
2012-10-15  7:14                               ` Joe Perches
2012-10-15  7:39                                 ` Eric Dumazet
2012-10-15  7:43                                   ` Eric Dumazet
2012-10-15 11:25                                   ` Joe Perches
2012-10-15 12:31                                     ` Eric Dumazet
2012-10-15 13:10                                       ` Jan Hinnerk Stosch
2012-10-15 13:21                                         ` Eric Dumazet
2012-10-15 13:28                                           ` Jan Hinnerk Stosch
2012-10-16 16:07                                             ` Jan Hinnerk Stosch
2012-10-16 16:43                                               ` Eric Dumazet
2012-10-16 16:55                                                 ` Eric Dumazet
2012-10-16 17:37                                                   ` [PATCH] ipv6: addrconf: fix /proc/net/if_inet6 Eric Dumazet
2012-10-16 18:30                                                     ` Jan Hinnerk Stosch
2012-10-16 18:39                                                       ` David Miller
2012-10-16 22:01                                                       ` Joe Perches
2012-10-16 18:55                                                     ` David Miller
2012-10-15  9:25                               ` regression when connecting to ipv6 localhost Eric Dumazet
2012-10-15 10:32                                 ` Jan Hinnerk Stosch
2012-10-15  9:07                         ` Jan Ceuleers

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).