[2017-02-28 21:49:55.030 W] discovery: device has no IP address -> retry later

[thomas r. hollensteiner]

hello,

i have to move to a new server and i was able to compile the vdcd + vdsm - all loks ok but the vdcd is bringing this error:

[2017-02-28 21:49:55.030 W] discovery: device has no IP address -> retry later

so i turned on logging and i get:

[2017-02-28 21:51:48.694 N] *** starting initialisation of vcd host 'plan44.ch plan44.ch vdcd #E00F1442CAA25CA3C0757C5C9FCA59C200'
*** dSUID (MAC-derived) = E00F1442CAA25CA3C0757C5C9FCA59C200, MAC: 14:DD:A9:D3:F5:DC, IP = 0.0.0.0

the choosen mac is correct but why does it not get the ip addr?

br0       Link encap:Ethernet  HWaddr 14:dd:a9:d3:f5:dc
          inet addr:192.168.249.1  Bcast:192.168.251.255  Mask:255.255.252.0

any idea?

thanks

holli

[Lukas Zeller]

Hello holli,

Not really :-(

The code that is responsible for getting MAC and IP is p44::getIfInfo() in p44utils/macaddress.cpp.

It just enumerates all network interface, and takes MAC and IP from the first non-loopback interface.
What are the other interfaces besides "br0" in your system?

Best Regards,

Lukas

[thomas r. hollensteiner]

hello,

this is my new obelix server and has 2x10g in a bond and some vm's but i want to run the vdsm + vdcd native on this box.

so ifconfig looks like - would be nice if i could specify the network device as command line parameter.

bond0     Link encap:Ethernet  HWaddr 14:dd:a9:d3:f5:de
          UP BROADCAST RUNNING MASTER MULTICAST  MTU:9216  Metric:1
          RX packets:214369 errors:0 dropped:3 overruns:0 frame:0
          TX packets:6932549 errors:0 dropped:3 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:102170751 (102.1 MB)  TX bytes:9227086528 (9.2 GB)


br0       Link encap:Ethernet  HWaddr 14:dd:a9:d3:f5:de
          inet addr:192.168.249.1  Bcast:192.168.251.255  Mask:255.255.252.0
          UP BROADCAST RUNNING MULTICAST  MTU:9216  Metric:1
          RX packets:132830 errors:0 dropped:0 overruns:0 frame:0
          TX packets:6897173 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:50995796 (50.9 MB)  TX bytes:9190875098 (9.1 GB)


enp2s0f0  Link encap:Ethernet  HWaddr 14:dd:a9:d3:f5:de
          UP BROADCAST RUNNING SLAVE MULTICAST  MTU:9216  Metric:1
          RX packets:160401 errors:0 dropped:2 overruns:0 frame:0
          TX packets:2013601 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:93098437 (93.0 MB)  TX bytes:2662823394 (2.6 GB)
          Interrupt:17 Memory:88800000-88ffffff


enp2s0f1  Link encap:Ethernet  HWaddr 14:dd:a9:d3:f5:de
          UP BROADCAST RUNNING SLAVE MULTICAST  MTU:9216  Metric:1
          RX packets:53968 errors:0 dropped:1 overruns:0 frame:0
          TX packets:4918948 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:9072314 (9.0 MB)  TX bytes:6564263134 (6.5 GB)
          Interrupt:18 Memory:89800000-89ffffff


lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:12670578 errors:0 dropped:0 overruns:0 frame:0
          TX packets:12670578 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1
          RX bytes:9961618734 (9.9 GB)  TX bytes:9961618734 (9.9 GB)


virbr0    Link encap:Ethernet  HWaddr 52:54:00:e4:7b:81
          inet addr:192.168.122.1  Bcast:192.168.122.255  Mask:255.255.255.0
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)


vnet0     Link encap:Ethernet  HWaddr fe:54:00:7a:5f:36
          UP BROADCAST RUNNING MULTICAST  MTU:9216  Metric:1
          RX packets:91796 errors:0 dropped:0 overruns:0 frame:0
          TX packets:140576 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:50796294 (50.7 MB)  TX bytes:73289371 (73.2 MB)


vnet1     Link encap:Ethernet  HWaddr fe:54:00:45:c7:c8
          UP BROADCAST RUNNING MULTICAST  MTU:9216  Metric:1
          RX packets:14408 errors:0 dropped:0 overruns:0 frame:0
          TX packets:66256 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:2084303 (2.0 MB)  TX bytes:15695440 (15.6 MB)


vnet2     Link encap:Ethernet  HWaddr fe:54:00:17:2b:39
          UP BROADCAST RUNNING MULTICAST  MTU:9216  Metric:1
          RX packets:14960 errors:0 dropped:0 overruns:0 frame:0
          TX packets:47481 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:2513503 (2.5 MB)  TX bytes:7922891 (7.9 MB)


vnet3     Link encap:Ethernet  HWaddr fe:54:00:a9:ed:fe
          UP BROADCAST RUNNING MULTICAST  MTU:9216  Metric:1
          RX packets:58529 errors:0 dropped:0 overruns:0 frame:0
          TX packets:101764 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:21359640 (21.3 MB)  TX bytes:33095073 (33.0 MB)


vnet4     Link encap:Ethernet  HWaddr fe:54:00:5e:0f:3b
          UP BROADCAST RUNNING MULTICAST  MTU:9216  Metric:1
          RX packets:2969 errors:0 dropped:0 overruns:0 frame:0
          TX packets:35432 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:2358525 (2.3 MB)  TX bytes:6064537 (6.0 MB)

thanks

holli

[Lukas Zeller]

Hi,

> On 1 Mar 2017, at 18:39, thomas r. hollensteiner <ho...@holli.at> wrote:
>
> hello,
>
> this is my new obelix server and has 2x10g in a bond and some vm's but i want to run the vdsm + vdcd native on this box.
>
> so ifconfig looks like - would be nice if i could specify the network device as command line parameter.
>
>
> bond0 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:de
> UP BROADCAST RUNNING MASTER MULTICAST MTU:9216 Metric:1
> RX packets:214369 errors:0 dropped:3 overruns:0 frame:0
> TX packets:6932549 errors:0 dropped:3 overruns:0 carrier:0
> collisions:0 txqueuelen:1000
> RX bytes:102170751 (102.1 MB) TX bytes:9227086528 (9.2 GB)
>
>
> br0 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:de
> inet addr:192.168.249.1 Bcast:192.168.251.255 Mask:255.255.252.0
> UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
> RX packets:132830 errors:0 dropped:0 overruns:0 frame:0
> TX packets:6897173 errors:0 dropped:0 overruns:0 carrier:0
> collisions:0 txqueuelen:1000
> RX bytes:50995796 (50.9 MB) TX bytes:9190875098 (9.1 GB)

> [...]

ok, so the MAC is taken from bond0 (which is ok). However, in the current implementation of getIfInfo(), the IP address is also taken from the same interface as the MAC address, and in your case that interface (bond0) does not have an IP.

Specifying the network device to use for MAC and IP on the command line is a nice idea, and would totally make sense. However, it would need a bit of refactoring I can't do right now. I have it on the todo list now ;-)

But I have made a small change to getIfInfo(), such that it will search differently for the IP address, and in setups like yours find out that there *is* an interface with IP assigned.
This change is in p44utils/luz on github, so you can try it (manually fetch origin, checkout 160f6b3b43 in the p44utils submodule, then make)

Best Regards,

Lukas

[thomas r. hollensteiner]

hello Luz,

this did do the trick - i now get an ip and vdcd is running.

thanks

holli

[thomas r. hollensteiner]

hello,

luz - i did upgrade to the latest dss - so it looks like the vdsm is running now there - so i tried to move to the new server as well - so i did make a rm -rf on the vdcd and did clone all new - from git all looks ok - but i do not get an ip address now?

holli@obelix:~/BUILD/ds/vdcd$ $PWD/vdcd -s /home/holli/BUILD/ds/data --externaldevices 8989 --cfgapinonlocal --externalnonlocal --vdsmnonlocal --ifnameformac br0 --ifnameforconn br0 -l 7
[2017-04-21 15:00:18.462 W] Running a migrated configuration: dSUID collisions with original unit possible
[2017-04-21 15:00:18.462 W] - native vDC host dSUID of this instance would be A89904D65EEC53ABC0B50D4845FF6B0400
[2017-04-21 15:00:18.462 W] - if this is not a replacement unit -> factory reset recommended!
[2017-04-21 15:00:18.462 D] Mainloop specified cycle time: 0.033333 S
[2017-04-21 15:00:18.496 D] - measurement 1: 0.033413 S
[2017-04-21 15:00:18.529 D] - measurement 2: 0.033450 S, average: 0.033431 S
[2017-04-21 15:00:18.563 D] - measurement 3: 0.033439 S, average: 0.033434 S






[2017-04-21 15:00:18.563 N] *** starting initialisation of vcd host 'plan44.ch plan44.ch vdcd #E00F1442CAA25CA3C0757C5C9FCA59C200'
                            *** dSUID (MAC-derived) = E00F1442CAA25CA3C0757C5C9FCA59C200, MAC: 00:00:00:00:00:00, IP = 0.0.0.0


[2017-04-21 15:00:18.564 N] *** Network connection lost
[2017-04-21 15:00:18.564 N] === collecting devices from vdc 68737D8021C35459C0E05FB0BC8D018D00 (External_Device_Container #1)
[2017-04-21 15:00:18.565 N] === done collecting from 68737D8021C35459C0E05FB0BC8D018D00


[2017-04-21 15:00:18.565 N] === collected devices from all vdcs -> initializing devices now


[2017-04-21 15:00:18.565 N] === initialized all collected devices


^C[2017-04-21 15:00:21.524 E] Terminating because pid 0 sent signal 2
[2017-04-21 15:00:21.524 E] Terminating because pid 0 sent signal 2

so if i do the checkout in the p44util as mentioned above i can not compile because of missing regex - so i have no idea why no ip is now found?

any help would be great - because of the new dss - my old vdsm is not working anymore and my house has no lighting...

thanks

holli

[Lukas Zeller]

Hi Holli,

sorry for the late reply, my server-side mail sieve config was wrong and moved this message from my inbox into a subfolder I only look at from time to time :-(

> On 21 Apr 2017, at 18:28, holli <ho...@holli.at> wrote:
>
> Hello Lukas,
>
> It is still broken – I did following:
>
> [...]
> holli@obelix:~/BUILD/ds/vdcd$ $PWD/vdcd -s /home/holli/BUILD/ds/data/data-obelix --externaldevices 8989 --externalnonlocal --ifnameforconn br0 --ifnameformac br0 -l 6
> [...]
> [2017-04-21 18:23:54.057 N] *** starting initialisation of vcd host 'plan44.ch plan44.ch vdcd #F74D4B9E9B7457FC80A260E1ED5FA6F800'
> *** dSUID (MAC-derived) = F74D4B9E9B7457FC80A260E1ED5FA6F800, MAC: 00:00:00:00:00:00, IP = 0.0.0.0

I really have no idea what the problem with that "br0" interface could be. I just built the vdcd from github exactly as you did, and for me the --ifnameforconn and --ifnameformac options work fine.

I don't have the same long list of interfaces, but just two of them, see below.
But I can take either ens33's or ens38's mac or ip with no limitation. Without the --ifnameforxxx options, the vdcd just takes ens33 because it is the first interface.

The vdcd macaddress.cpp code uses the same method as described in http://stackoverflow.com/a/14266718 for enumerating the interfaces. That stackoverflow answer has a small sample program that can be copied 1:1 and built. On my system it outputs:

lo: Interface 1, up
ens33: Interface 2, up, full duplex, 1000 Mbps
ens38: Interface 3, up, full duplex, 1000 Mbps

Please compile and run that small program on your machine and let me know what the output is. It might help to figure out why apparently the vdcd cannot find that "br0" interface in your system.

Best Regards,

Lukas


PS: my ifconfig:

> ens33 Link encap:Ethernet HWaddr 00:0c:29:a6:eb:23
> inet addr:192.168.88.128 Bcast:192.168.88.255 Mask:255.255.255.0
> inet6 addr: fe80::45e4:9b30:99e4:2375/64 Scope:Link
> UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
> RX packets:157 errors:0 dropped:0 overruns:0 frame:0
> TX packets:134 errors:0 dropped:0 overruns:0 carrier:0
> collisions:0 txqueuelen:1000
> RX bytes:30799 (30.7 KB) TX bytes:21615 (21.6 KB)
>
> ens38 Link encap:Ethernet HWaddr 00:0c:29:a6:eb:2d
> inet addr:192.168.11.90 Bcast:192.168.11.255 Mask:255.255.255.0
> inet6 addr: fe80::269e:346a:e09d:2f6b/64 Scope:Link
> UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
> RX packets:942 errors:0 dropped:0 overruns:0 frame:0
> TX packets:112 errors:0 dropped:0 overruns:0 carrier:0
> collisions:0 txqueuelen:1000
> RX bytes:187130 (187.1 KB) TX bytes:19251 (19.2 KB)

>
> lo Link encap:Local Loopback
> inet addr:127.0.0.1 Mask:255.0.0.0

> inet6 addr: ::1/128 Scope:Host

> UP LOOPBACK RUNNING MTU:65536 Metric:1

> RX packets:243 errors:0 dropped:0 overruns:0 frame:0
> TX packets:243 errors:0 dropped:0 overruns:0 carrier:0
> collisions:0 txqueuelen:1
> RX bytes:18407 (18.4 KB) TX bytes:18407 (18.4 KB)

[holli]

Hello Lukas,

This is my output:

holli@obelix:~/BUILD/ds/vdcd-br-test-tool$ gcc -W -Wall -O3 iflist.c -o iflist
holli@obelix:~/BUILD/ds/vdcd-br-test-tool$ ./iflist
lo: Interface 1, up
enp2s0f0: Interface 2, up, full duplex, 10000 Mbps
enp2s0f1: Interface 3, up, full duplex, 10000 Mbps
bond0: Interface 4, up, full duplex, 20000 Mbps
br0: Interface 5, up
virbr0: Interface 6, up
virbr0-nic: Interface 7, full duplex, 10 Mbps
vnet0: Interface 8, up, full duplex, 10 Mbps
vnet1: Interface 9, up, full duplex, 10 Mbps
vnet2: Interface 10, up, full duplex, 10 Mbps
vnet3: Interface 11, up, full duplex, 10 Mbps
vnet4: Interface 12, up, full duplex, 10 Mbps
holli@obelix:~/BUILD/ds/vdcd-br-test-tool$ ./iflist br0
br0: Interface 5, up

hope this helps

thanks
holli

[thomas r. hollensteiner]

hello Lukas,

i did try to change some code in within the macadress.cpp and it looks like i have a bit of success.

you are using there some for schleifen - some with less than 4 and some with less than 6 - so in my case the br is on the top (5) if your code gets the ip/interfaces in the same order.

so i just tried something and changed all to 9 and the output looks different now:

[2017-05-01 10:35:46.445 N] *** starting initialisation of vcd host 'plan44.ch plan44.ch vdcd #F74D4B9E9B7457FC80A260E1ED5FA6F800'

                            *** dSUID (MAC-derived) = F74D4B9E9B7457FC80A260E1ED5FA6F800, MAC: D3:F5:DE:00:00:00, IP = 0.0.0.0

so it looks like a mac is there but not complete?

so if i look on my two 10g devices and the br0 device i have:

br0       Link encap:Ethernet  HWaddr 14:dd:a9:d3:f5:de

so somehow the address 'D3:F5:DE' is there but on the wrong position?

before my change the mac returned in the output was always '00' - so there must be something wrong with the structure?

thanks

holli

[Lukas Zeller]

Hello Holli,

I'm also trying to track it down right now,. I have a suspect now ;-)

Could you please do a

strace ifconfig

and also

strace ./iflist

and send me the outputs?

What I found is that my code does not try to read MAC or IP when the SIOCGIFFLAGS fails. The code in iflist however then just assumes the flags to be zero.
So I suspect now that your br0 interface does not return ok for SIOCGIFFLAGS for some reason.

Best Regards,

Lukas

> --
> Sie erhalten diese Nachricht, weil Sie in Google Groups E-Mails von der Gruppe "plan44_vdcd" abonniert haben.
> Wenn Sie sich von dieser Gruppe abmelden und keine E-Mails mehr von dieser Gruppe erhalten möchten, senden Sie eine E-Mail an plan44_vdcd...@googlegroups.com.
> Wenn Sie in dieser Gruppe einen Beitrag posten möchten, senden Sie eine E-Mail an plan4...@googlegroups.com.
> Gruppe besuchen: https://groups.google.com/group/plan44_vdcd
> Wenn Sie diese Diskussion im Web verfolgen möchten, rufen Sie https://groups.google.com/d/msgid/plan44_vdcd/500d0bc5-52eb-41d2-8ab6-aef25418af74%40googlegroups.com auf.
> Weitere Optionen finden Sie unter https://groups.google.com/d/optout.

[Lukas Zeller]

Hi Holli,

> On 1 May 2017, at 10:49, thomas r. hollensteiner <ho...@holli.at> wrote:
>
> i did try to change some code in within the macadress.cpp and it looks like i have a bit of success.
>
> you are using there some for schleifen - some with less than 4 and some with less than 6 - so in my case the br is on the top (5) if your code gets the ip/interfaces in the same order.
>
> so i just tried something and changed all to 9 and the output looks different now:

I bet it looks different ;-)

However, these inner for loops are for collecting the bytes for the MAC address (<6, meaning 0..5) and for the IPv4 address (<4, meaning 0..4). If you expand these to 9, what happens is that the code will read data from bytes that are neither IP nor MAC, so you get some non-zero garbage back. In case of the MAC, you get part of the correct MAC, but as you read 9 instead of 6, the position is 3 bytes shifted.

But that's not the problem. The problem is that the outermost loop (the do {...} while(true)) apparently skips over your br0 interface. Most probably this is because of SIOCGIFFLAGS returning false, see last email. Let me know the outputs of these strace tests, and we'll see what actually happens.

Best Regards,

Lukas

>
>
> [2017-05-01 10:35:46.445 N] *** starting initialisation of vcd host 'plan44.ch plan44.ch vdcd #F74D4B9E9B7457FC80A260E1ED5FA6F800'
> *** dSUID (MAC-derived) = F74D4B9E9B7457FC80A260E1ED5FA6F800, MAC: D3:F5:DE:00:00:00, IP = 0.0.0.0
>
>
> so it looks like a mac is there but not complete?
>
> so if i look on my two 10g devices and the br0 device i have:
>
> br0 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:de
>
> so somehow the address 'D3:F5:DE' is there but on the wrong position?
>
> before my change the mac returned in the output was always '00' - so there must be something wrong with the structure?
>
> thanks
> holli
>
>

[holli]

Hallo Lukas,

Attached are the 2 requested files


Thanks
Holli

[holli]

Hello Lukas,

I just tried something if I could find some parameters from my env.
I provded the strace files – so it looks like on all interfaces ‘0’ is returned..

holli@obelix:~$ cat iflist.txt |grep SIOCGIFFLAGS
11:05:42.640168 ioctl(3, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
11:05:42.640326 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:05:42.640406 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:05:42.640484 ioctl(3, SIOCGIFFLAGS, {ifr_name="bond0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MASTER|IFF_MULTICAST}) = 0
11:05:42.640568 ioctl(3, SIOCGIFFLAGS, {ifr_name="br0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:05:42.640646 ioctl(3, SIOCGIFFLAGS, {ifr_name="virbr0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_MULTICAST}) = 0
11:05:42.640724 ioctl(3, SIOCGIFFLAGS, {ifr_name="virbr0-nic", ifr_flags=IFF_BROADCAST|IFF_MULTICAST}) = 0
11:05:42.640802 ioctl(3, SIOCGIFFLAGS, {ifr_name="vnet0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:05:42.640879 ioctl(3, SIOCGIFFLAGS, {ifr_name="vnet1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:05:42.640956 ioctl(3, SIOCGIFFLAGS, {ifr_name="vnet2", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:05:42.641032 ioctl(3, SIOCGIFFLAGS, {ifr_name="vnet3", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:05:42.641108 ioctl(3, SIOCGIFFLAGS, {ifr_name="vnet4", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
holli@obelix:~$ cat ifconfig.txt |grep SIOCGIFFLAGS
11:04:04.819005 ioctl(5, SIOCGIFFLAGS, {ifr_name="bond0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MASTER|IFF_MULTICAST}) = 0
11:04:04.820526 ioctl(5, SIOCGIFFLAGS, {ifr_name="br0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:04:04.822131 ioctl(5, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:04:04.823394 ioctl(5, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:04:04.824650 ioctl(5, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
11:04:04.826349 ioctl(5, SIOCGIFFLAGS, {ifr_name="virbr0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_MULTICAST}) = 0
11:04:04.827822 ioctl(5, SIOCGIFFLAGS, {ifr_name="virbr0-nic", ifr_flags=IFF_BROADCAST|IFF_MULTICAST}) = 0
11:04:04.828295 ioctl(5, SIOCGIFFLAGS, {ifr_name="vnet0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:04:04.829474 ioctl(5, SIOCGIFFLAGS, {ifr_name="vnet1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:04:04.830622 ioctl(5, SIOCGIFFLAGS, {ifr_name="vnet2", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:04:04.831759 ioctl(5, SIOCGIFFLAGS, {ifr_name="vnet3", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:04:04.833044 ioctl(5, SIOCGIFFLAGS, {ifr_name="vnet4", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0

thanks
holli

[Lukas Zeller]

Hi holli,

thanks for the logs and your effort to search for the cause of the problem!

Yes, the ictls seem fine, so SIOCGIFFLAGS cannot be the problem as I thought it could.

Next idea: can you please run the vdcd with strace? Something like:

strace ./vdcd -l 0 2>&1 --ifnameformac br0 --ifnameforconn br0 | grep -v "POLLIN"

and, for reference

strace ./vdcd -l 0 2>&1 | grep -v "POLLIN"

These will continue forever, just abort them with ctrl-C after 10 secs or so. I set logging to 0 to reduce the output as much as possible.

As the results will still be quite big, you might not want to put them on the list, just send them directly to me.

With this, I can hopefully see exactly what my macaddress.cpp is getting from the ioctl calls, and why it then refuses to use the br0 interface.

Best Regards,

Lukas

[holli]

[Lukas Zeller]

Hi Holli,

> On 1 May 2017, at 11:47, holli <ho...@holli.at> wrote:
>
> [strace logs]

thanks for collecting the vdcd strace logs.

However, looking at these, it remains an absolute mystery to me to understand why you still do not get MAC and IP.
Because the strace shows the macaddress.cpp code is working exactly as expected, see below.

I'm really at loss of more ideas what could be wrong :-(

Is anything special in your build environment? In particular, did something CHANGE in your build environment (or the system itself) since vdcd has worked ok a few weeks ago? I can only imagine some strange things like non-matching headers (32 vs 64bit) that could possibly cause collecting mac/ip to fail after a correct SIOCGIFHWADDR or SIOCGIFADDR.

The straces show that IP and MAC ar returned correctly in both cases, with or without --ifnameforxxx:

When you start it with no --ifnameforxxxx, it picks up MAC from "enp2s0f0" as it is the first non-loopback interface

11:42:49.957726 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
11:42:49.957821 ioctl(3, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
11:42:49.957881 ioctl(3, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
11:42:49.957937 ioctl(3, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
11:42:49.957990 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:42:49.958045 ioctl(3, SIOCGIFHWADDR, {ifr_name="enp2s0f0", ifr_hwaddr=14:dd:a9:d3:f5:de}) = 0
11:42:49.958103 close(3) = 0

...and the IP address from "br0" as this is the first interface that is not loopback and has a IP address:

11:42:50.068726 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
11:42:50.068782 ioctl(7, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
11:42:50.068839 ioctl(7, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
11:42:50.068896 ioctl(7, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
11:42:50.068949 ioctl(7, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:42:50.069004 ioctl(7, SIOCGIFADDR, {ifr_name="enp2s0f0"}) = -1 EADDRNOTAVAIL (Cannot assign requested address)
11:42:50.069058 ioctl(7, SIOCGIFNAME, {ifr_index=3, ifr_name="enp2s0f1"}) = 0
11:42:50.069110 ioctl(7, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:42:50.069164 ioctl(7, SIOCGIFADDR, {ifr_name="enp2s0f1"}) = -1 EADDRNOTAVAIL (Cannot assign requested address)
11:42:50.069224 ioctl(7, SIOCGIFNAME, {ifr_index=4, ifr_name="bond0"}) = 0
11:42:50.069279 ioctl(7, SIOCGIFFLAGS, {ifr_name="bond0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MASTER|IFF_MULTICAST}) = 0
11:42:50.069334 ioctl(7, SIOCGIFADDR, {ifr_name="bond0"}) = -1 EADDRNOTAVAIL (Cannot assign requested address)
11:42:50.069385 ioctl(7, SIOCGIFNAME, {ifr_index=5, ifr_name="br0"}) = 0
11:42:50.069438 ioctl(7, SIOCGIFFLAGS, {ifr_name="br0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:42:50.069491 ioctl(7, SIOCGIFADDR, {ifr_name="br0", ifr_addr={AF_INET, inet_addr("192.168.249.1")}}) = 0
11:42:50.069548 close(7) = 0


When you start it with --ifnameforxxxx, both MAC and IP are read from "br0":

MAC:

11:44:17.161054 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
11:44:17.161070 ioctl(3, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
11:44:17.161084 ioctl(3, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
11:44:17.161096 ioctl(3, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
11:44:17.161109 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:44:17.161122 ioctl(3, SIOCGIFNAME, {ifr_index=3, ifr_name="enp2s0f1"}) = 0
11:44:17.161135 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:44:17.161148 ioctl(3, SIOCGIFNAME, {ifr_index=4, ifr_name="bond0"}) = 0
11:44:17.161160 ioctl(3, SIOCGIFFLAGS, {ifr_name="bond0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MASTER|IFF_MULTICAST}) = 0
11:44:17.161173 ioctl(3, SIOCGIFNAME, {ifr_index=5, ifr_name="br0"}) = 0
11:44:17.161186 ioctl(3, SIOCGIFFLAGS, {ifr_name="br0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:44:17.161199 ioctl(3, SIOCGIFHWADDR, {ifr_name="br0", ifr_hwaddr=14:dd:a9:d3:f5:de}) = 0
11:44:17.161212 close(3) = 0


IP:

11:44:17.264013 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 7
11:44:17.264027 ioctl(7, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
11:44:17.264041 ioctl(7, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
11:44:17.264055 ioctl(7, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
11:44:17.264067 ioctl(7, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:44:17.264080 ioctl(7, SIOCGIFNAME, {ifr_index=3, ifr_name="enp2s0f1"}) = 0
11:44:17.264092 ioctl(7, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
11:44:17.264105 ioctl(7, SIOCGIFNAME, {ifr_index=4, ifr_name="bond0"}) = 0
11:44:17.264117 ioctl(7, SIOCGIFFLAGS, {ifr_name="bond0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MASTER|IFF_MULTICAST}) = 0
11:44:17.264130 ioctl(7, SIOCGIFNAME, {ifr_index=5, ifr_name="br0"}) = 0
11:44:17.264142 ioctl(7, SIOCGIFFLAGS, {ifr_name="br0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_MULTICAST}) = 0
11:44:17.264154 ioctl(7, SIOCGIFADDR, {ifr_name="br0", ifr_addr={AF_INET, inet_addr("192.168.249.1")}}) = 0
11:44:17.264169 close(7) = 0

Best Regards,

Lukas

[holli]

Hello Lukas,

I did see this in the trace files as well – yes the system did change in the meantime

(Ubuntu 16.04 updates and kernel updates almost every 2 weeks…)

Welcome to Ubuntu 16.04.2 LTS (GNU/Linux 4.4.0-75-generic x86_64)

holli@obelix:~$ uname -a
Linux obelix 4.4.0-75-generic #96-Ubuntu SMP Thu Apr 20 09:56:33 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux

So I was checking the history to find the time when it did work but I could not see a difference – so I decided to reboot the box (because a new kernel was provided from Ubuntu again…)

After the reboot I did:

holli@obelix:~/BUILD/ds/vdcd$ ./vdcd -s /home/holli/BUILD/ds/data/data-obelix --externaldevices 8989 --externalnonlocal --ifnameforconn br0 --ifnameformac br0 -l 6

[2017-05-01 15:45:52.824 N] *** starting initialisation of vcd host 'plan44.ch plan44.ch vdcd #F74D4B9E9B7457FC80A260E1ED5FA6F800'
*** dSUID (MAC-derived) = F74D4B9E9B7457FC80A260E1ED5FA6F800, MAC: 14:DD:A9:D3:F5:DE, IP = 192.168.249.1

[2017-05-01 15:45:52.825 N] === collecting devices from vdc 0C5E62AB398D5A1FC01C72380E0855EC00 (External_Device_Container #1)
[2017-05-01 15:45:52.825 N] === done collecting from 0C5E62AB398D5A1FC01C72380E0855EC00

[2017-05-01 15:45:52.825 N] === collected devices from all vdcs -> initializing devices now

[2017-05-01 15:45:52.825 N] === initialized all collected devices

[2017-05-01 15:46:00.825 N] avahi: starting client
[2017-05-01 15:46:01.860 N] discovery: successfully published vDC service 'plan44.ch plan44.ch vdcd #F74D4B9E9B7457FC80A260E1ED5FA6F800'.
^C[2017-05-01 15:46:03.982 E] Terminating because pid 0 sent signal 2
[2017-0

so it looks like it is working now – I’m sorry for wasting your time – but I would not have believed that this reboot will help but was my last hope (like on windows did reboot before you start to analyse…)

thanks a lot for your help – I did start with a test lamp and the dss build in vdsm did show up – and from this log I could see it was able to talk to vdcd on obelix – so I can now start to move my interface scripts to obelix
thanks as lot for your help

best regards
holli

[Lukas Zeller]

Hi Holli,

Thanks for the feedback. I lack the imagination to understand how this can be possible, but most important: it works now and your vdcd is up and running!

While looking at the macaddress code again and again I found a unrelated minor problem I could fix now, so no time wasted ;-)

I pushed this and a bunch of other changes to github a few minutes ago.

Best Regards,

Lukas

[holli]

Hello Lukas,

Today I had to reboot my obelix box because of kernel update from Ubuntu…

Guess after reboot now the vdcd is not working anymore…

holli@obelix:~/BUILD/ds/vdcd$ ./vdcd -s /home/holli/BUILD/ds/data/data-obelix --hostname obelix --vdsmnonlocal --externaldevices 8989 --externalnonlocal -l 7 --cfgapinonlocal --externalnonlocal --ifnameformac br0 --ifnameforconn br0 --productname vdcd-obelix --productversion v1 --evaluators
[2017-06-09 00:17:58.565 W] Running a migrated configuration: dSUID collisions with original unit possible
[2017-06-09 00:17:58.565 W] - native vDC host dSUID of this instance would be A89904D65EEC53ABC0B50D4845FF6B0400
[2017-06-09 00:17:58.565 W] - if this is not a replacement unit -> factory reset recommended!
[2017-06-09 00:17:58.565 D] Mainloop specified cycle time: 0.033333 S
[2017-06-09 00:17:58.598 D] - measurement 1: 0.033387 S
[2017-06-09 00:17:58.631 D] - measurement 2: 0.033387 S, average: 0.033387 S
[2017-06-09 00:17:58.665 D] - measurement 3: 0.033389 S, average: 0.033387 S



[2017-06-09 00:17:58.665 N] *** starting initialisation of vcd host 'plan44.ch vdcd-obelix #F74D4B9E9B7457FC80A260E1ED5FA6F800'
*** dSUID (MAC-derived) = F74D4B9E9B7457FC80A260E1ED5FA6F800, MAC: 00:00:00:00:00:00, IP = 0.0.0.0

[2017-06-09 00:17:58.665 N] *** Network connection lost
[2017-06-09 00:17:58.665 N] === collecting devices from vdc 0C5E62AB398D5A1FC01C72380E0855EC00 (External_Device_Container #1)
[2017-06-09 00:17:58.665 N] === done collecting from 0C5E62AB398D5A1FC01C72380E0855EC00 (vdsm-dss-obelix-vdc)

[2017-06-09 00:17:58.666 N] === collecting devices from vdc FCC00A03CB97579A8027E6B81EC29BF600 (Evaluator_Device_Container #1)
[2017-06-09 00:17:58.666 N] === done collecting from FCC00A03CB97579A8027E6B81EC29BF600

[2017-06-09 00:17:58.666 N] === collected devices from all vdcs -> initializing devices now

[2017-06-09 00:17:58.666 N] === initialized all collected devices

[2017-06-09 00:18:06.665 W] discovery: device has no IP address -> retry later
^C[2017-06-09 00:18:15.401 E] Terminating because pid 0 sent signal 2

so I do not get an ip/mac now for br0…

holli@obelix:~/BUILD/ds/vdcd$ ifconfig -a
bond0 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:dc

UP BROADCAST RUNNING MASTER MULTICAST MTU:9216 Metric:1

RX packets:52777 errors:0 dropped:1 overruns:0 frame:0
TX packets:1097364 errors:0 dropped:3 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:11717133 (11.7 MB) TX bytes:1456459128 (1.4 GB)

br0 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:dc

inet addr:192.168.249.1 Bcast:192.168.251.255 Mask:255.255.252.0

UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
RX packets:36460 errors:0 dropped:0 overruns:0 frame:0
TX packets:1081169 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:8738602 (8.7 MB) TX bytes:1450689274 (1.4 GB)

enp2s0f0 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:dc
UP BROADCAST RUNNING SLAVE MULTICAST MTU:9216 Metric:1
RX packets:35506 errors:0 dropped:0 overruns:0 frame:0
TX packets:1068990 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:6536823 (6.5 MB) TX bytes:1443162216 (1.4 GB)
Interrupt:17 Memory:88800000-88ffffff

enp2s0f1 Link encap:Ethernet HWaddr 14:dd:a9:d3:f5:dc
UP BROADCAST RUNNING SLAVE MULTICAST MTU:9216 Metric:1
RX packets:17271 errors:0 dropped:1 overruns:0 frame:0
TX packets:28374 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:5180310 (5.1 MB) TX bytes:13296912 (13.2 MB)
Interrupt:18 Memory:89800000-89ffffff

lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0

UP LOOPBACK RUNNING MTU:65536 Metric:1

RX packets:2984482 errors:0 dropped:0 overruns:0 frame:0
TX packets:2984482 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1
RX bytes:4118717231 (4.1 GB) TX bytes:4118717231 (4.1 GB)

virbr0 Link encap:Ethernet HWaddr 52:54:00:e4:7b:81
inet addr:192.168.122.1 Bcast:192.168.122.255 Mask:255.255.255.0

UP BROADCAST MULTICAST MTU:1500 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000

RX bytes:0 (0.0 B) TX bytes:0 (0.0 B)

virbr0-nic Link encap:Ethernet HWaddr 52:54:00:e4:7b:81
BROADCAST MULTICAST MTU:1500 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000

RX bytes:0 (0.0 B) TX bytes:0 (0.0 B)

vnet0 Link encap:Ethernet HWaddr fe:54:00:17:2b:39
UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
RX packets:3288 errors:0 dropped:0 overruns:0 frame:0
TX packets:19548 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:569519 (569.5 KB) TX bytes:6053247 (6.0 MB)

vnet1 Link encap:Ethernet HWaddr fe:54:00:45:c7:c8

UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
RX packets:9106 errors:0 dropped:0 overruns:0 frame:0
TX packets:24742 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:1394498 (1.3 MB) TX bytes:8847510 (8.8 MB)

vnet2 Link encap:Ethernet HWaddr fe:54:00:5e:0f:3b
UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
RX packets:1119 errors:0 dropped:0 overruns:0 frame:0
TX packets:17131 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:426504 (426.5 KB) TX bytes:5693970 (5.6 MB)

vnet3 Link encap:Ethernet HWaddr fe:54:00:7a:5f:36
UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
RX packets:12459 errors:0 dropped:0 overruns:0 frame:0
TX packets:29292 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:5254681 (5.2 MB) TX bytes:7421534 (7.4 MB)

vnet4 Link encap:Ethernet HWaddr fe:54:00:a9:ed:fe
UP BROADCAST RUNNING MULTICAST MTU:9216 Metric:1
RX packets:4611 errors:0 dropped:0 overruns:0 frame:0
TX packets:21758 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:963102 (963.1 KB) TX bytes:6374559 (6.3 MB)

So I did not change anything on the vdcd side
I don’t know what – but somehow something is not working as expected – I can reboot again and see if this makes a difference but I don’t know why…
Any ideas?
All other software on this box is fine – all vm’s are fine only the vdcd has an issue.

Thanks
Holli

[holli]

Hi Lukas,

Sorry for spaming but I did start with strace – it looks like the br0 is not taken
Here is an extract

00:28:52.912297 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
00:28:52.912319 ioctl(3, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
00:28:52.912336 ioctl(3, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
00:28:52.912352 ioctl(3, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
00:28:52.912366 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:52.912381 ioctl(3, SIOCGIFHWADDR, {ifr_name="enp2s0f0", ifr_hwaddr=14:dd:a9:d3:f5:dc}) = 0
00:28:52.912397 close(3)

why is it not looking for br0?

Thanks
Holli


holli@obelix:~/BUILD/ds/vdcd$ strace -tt ./vdcd -s /home/holli/BUILD/ds/data/data-obelix --hostname obelix --vdsmnonlocal --externaldevices 8989 --externalnonlocal -l7 --cfgapinonlocal --externalnonlocal --ifnameformac br0 --ifnameforconn br0 --productname vdcd-obelix --productversion v1 --evaluators
00:28:52.906565 execve("./vdcd", ["./vdcd", "-s", "/home/holli/BUILD/ds/data/data-o"..., "--hostname", "obelix", "--vdsmnonlocal", "--externaldevices", "8989", "--externalnonlocal", "-l7", "--cfgapinonlocal", "--externalnonlocal", "--ifnameformac", "br0", "--ifnameforconn", "br0", ...], [/* 22 vars */]) = 0
00:28:52.906733 brk(NULL) = 0x10d3000
00:28:52.906769 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.906786 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0ff1c000
00:28:52.906807 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
00:28:52.906823 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
00:28:52.906841 fstat(3, {st_mode=S_IFREG|0644, st_size=94791, ...}) = 0
00:28:52.906856 mmap(NULL, 94791, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7feb0ff04000
00:28:52.906869 close(3) = 0
00:28:52.906883 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.906898 open("/lib/x86_64-linux-gnu/libjson-c.so.2", O_RDONLY|O_CLOEXEC) = 3
00:28:52.906913 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0(\0\0\0\0\0\0"..., 832) = 832
00:28:52.906927 fstat(3, {st_mode=S_IFREG|0644, st_size=43496, ...}) = 0
00:28:52.906940 mmap(NULL, 2138640, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0faee000
00:28:52.906954 mprotect(0x7feb0faf8000, 2093056, PROT_NONE) = 0
00:28:52.906968 mmap(0x7feb0fcf7000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x9000) = 0x7feb0fcf7000
00:28:52.906987 close(3) = 0
00:28:52.907001 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907016 open("/usr/lib/x86_64-linux-gnu/libsqlite3.so.0", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907030 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@\260\0\0\0\0\0\0"..., 832) = 832
00:28:52.907044 fstat(3, {st_mode=S_IFREG|0644, st_size=870240, ...}) = 0
00:28:52.907057 mmap(NULL, 2967256, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0f819000
00:28:52.907070 mprotect(0x7feb0f8e8000, 2097152, PROT_NONE) = 0
00:28:52.907085 mmap(0x7feb0fae8000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xcf000) = 0x7feb0fae8000
00:28:52.907101 mmap(0x7feb0faed000, 1752, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0faed000
00:28:52.907121 close(3) = 0
00:28:52.907147 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907175 open("/usr/lib/x86_64-linux-gnu/libprotobuf-c.so.1", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907197 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\f\0\0\0\0\0\0"..., 832) = 832
00:28:52.907216 fstat(3, {st_mode=S_IFREG|0644, st_size=35000, ...}) = 0
00:28:52.907232 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0ff03000
00:28:52.907250 mmap(NULL, 2130064, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0f610000
00:28:52.907265 mprotect(0x7feb0f618000, 2093056, PROT_NONE) = 0
00:28:52.907280 mmap(0x7feb0f817000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7feb0f817000
00:28:52.907299 close(3) = 0
00:28:52.907315 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907333 open("/usr/lib/x86_64-linux-gnu/libavahi-common.so.3", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907350 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@/\0\0\0\0\0\0"..., 832) = 832
00:28:52.907365 fstat(3, {st_mode=S_IFREG|0644, st_size=47952, ...}) = 0
00:28:52.907380 mmap(NULL, 2143216, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0f404000
00:28:52.907395 mprotect(0x7feb0f40f000, 2093056, PROT_NONE) = 0
00:28:52.907409 mmap(0x7feb0f60e000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xa000) = 0x7feb0f60e000
00:28:52.907428 close(3) = 0
00:28:52.907443 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907464 open("/usr/lib/x86_64-linux-gnu/libavahi-client.so.3", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907483 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\2206\0\0\0\0\0\0"..., 832) = 832
00:28:52.907498 fstat(3, {st_mode=S_IFREG|0644, st_size=68512, ...}) = 0
00:28:52.907514 mmap(NULL, 2163576, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0f1f3000
00:28:52.907540 mprotect(0x7feb0f203000, 2093056, PROT_NONE) = 0
00:28:52.907561 mmap(0x7feb0f402000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xf000) = 0x7feb0f402000
00:28:52.907594 close(3) = 0
00:28:52.907618 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907648 open("/lib/x86_64-linux-gnu/libcrypto.so.1.0.0", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907668 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\36\6\0\0\0\0\0"..., 832) = 832
00:28:52.907683 fstat(3, {st_mode=S_IFREG|0644, st_size=2361856, ...}) = 0
00:28:52.907698 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0ff02000
00:28:52.907716 mmap(NULL, 4471680, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0edaf000
00:28:52.907731 mprotect(0x7feb0efc9000, 2093056, PROT_NONE) = 0
00:28:52.907747 mmap(0x7feb0f1c8000, 163840, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x219000) = 0x7feb0f1c8000
00:28:52.907766 mmap(0x7feb0f1f0000, 11136, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0f1f0000
00:28:52.907785 close(3) = 0
00:28:52.907803 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907818 open("/lib/x86_64-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907833 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\r\0\0\0\0\0\0"..., 832) = 832
00:28:52.907847 fstat(3, {st_mode=S_IFREG|0644, st_size=14608, ...}) = 0
00:28:52.907860 mmap(NULL, 2109680, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0ebab000
00:28:52.907874 mprotect(0x7feb0ebae000, 2093056, PROT_NONE) = 0
00:28:52.907889 mmap(0x7feb0edad000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7feb0edad000
00:28:52.907910 close(3) = 0
00:28:52.907931 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.907962 open("/lib/x86_64-linux-gnu/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
00:28:52.907978 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0!\0\0\0\0\0\0"..., 832) = 832
00:28:52.907992 fstat(3, {st_mode=S_IFREG|0644, st_size=31712, ...}) = 0
00:28:52.908005 mmap(NULL, 2128832, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0e9a3000
00:28:52.908020 mprotect(0x7feb0e9aa000, 2093056, PROT_NONE) = 0
00:28:52.908035 mmap(0x7feb0eba9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7feb0eba9000
00:28:52.908053 close(3) = 0
00:28:52.908068 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908082 open("/usr/lib/x86_64-linux-gnu/libstdc++.so.6", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908096 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \235\10\0\0\0\0\0"..., 832) = 832
00:28:52.908109 fstat(3, {st_mode=S_IFREG|0644, st_size=1566440, ...}) = 0
00:28:52.908122 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0ff01000
00:28:52.908137 mmap(NULL, 3675136, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0e621000
00:28:52.908152 mprotect(0x7feb0e793000, 2097152, PROT_NONE) = 0
00:28:52.908167 mmap(0x7feb0e993000, 49152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x172000) = 0x7feb0e993000
00:28:52.908183 mmap(0x7feb0e99f000, 13312, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0e99f000
00:28:52.908201 close(3) = 0
00:28:52.908217 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908231 open("/lib/x86_64-linux-gnu/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908245 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0V\0\0\0\0\0\0"..., 832) = 832
00:28:52.908258 fstat(3, {st_mode=S_IFREG|0644, st_size=1088952, ...}) = 0
00:28:52.908271 mmap(NULL, 3178744, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0e318000
00:28:52.908284 mprotect(0x7feb0e420000, 2093056, PROT_NONE) = 0
00:28:52.908298 mmap(0x7feb0e61f000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x107000) = 0x7feb0e61f000
00:28:52.908316 close(3) = 0
00:28:52.908331 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908345 open("/lib/x86_64-linux-gnu/libgcc_s.so.1", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908358 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p*\0\0\0\0\0\0"..., 832) = 832
00:28:52.908371 fstat(3, {st_mode=S_IFREG|0644, st_size=89696, ...}) = 0
00:28:52.908383 mmap(NULL, 2185488, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0e102000
00:28:52.908396 mprotect(0x7feb0e118000, 2093056, PROT_NONE) = 0
00:28:52.908410 mmap(0x7feb0e317000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0x7feb0e317000
00:28:52.908426 close(3) = 0
00:28:52.908440 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908454 open("/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908468 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260`\0\0\0\0\0\0"..., 832) = 832
00:28:52.908480 fstat(3, {st_mode=S_IFREG|0755, st_size=138696, ...}) = 0
00:28:52.908493 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0ff00000
00:28:52.908507 mmap(NULL, 2212904, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0dee5000
00:28:52.908522 mprotect(0x7feb0defd000, 2093056, PROT_NONE) = 0
00:28:52.908535 mmap(0x7feb0e0fc000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17000) = 0x7feb0e0fc000
00:28:52.908552 mmap(0x7feb0e0fe000, 13352, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0e0fe000
00:28:52.908568 close(3) = 0
00:28:52.908583 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908597 open("/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908611 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\t\2\0\0\0\0\0"..., 832) = 832
00:28:52.908624 fstat(3, {st_mode=S_IFREG|0755, st_size=1864888, ...}) = 0
00:28:52.908643 mmap(NULL, 3967392, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0db1c000
00:28:52.908659 mprotect(0x7feb0dcdb000, 2097152, PROT_NONE) = 0
00:28:52.908675 mmap(0x7feb0dedb000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1bf000) = 0x7feb0dedb000
00:28:52.908693 mmap(0x7feb0dee1000, 14752, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0dee1000
00:28:52.908711 close(3) = 0
00:28:52.908733 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908749 open("/lib/x86_64-linux-gnu/libdbus-1.so.3", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908765 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\244\0\0\0\0\0\0"..., 832) = 832
00:28:52.908780 fstat(3, {st_mode=S_IFREG|0644, st_size=309392, ...}) = 0
00:28:52.908794 mmap(NULL, 2405040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0d8d0000
00:28:52.908809 mprotect(0x7feb0d91a000, 2097152, PROT_NONE) = 0
00:28:52.908825 mmap(0x7feb0db1a000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4a000) = 0x7feb0db1a000
00:28:52.908844 close(3) = 0
00:28:52.908861 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0feff000
00:28:52.908882 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.908897 open("/lib/x86_64-linux-gnu/libsystemd.so.0", O_RDONLY|O_CLOEXEC) = 3
00:28:52.908912 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\0\0\0\0\0\0\0"..., 832) = 832
00:28:52.908927 fstat(3, {st_mode=S_IFREG|0644, st_size=536520, ...}) = 0
00:28:52.908941 mmap(NULL, 540800, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0fe7a000
00:28:52.908957 mmap(0x7feb0fefa000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7f000) = 0x7feb0fefa000
00:28:52.909057 mmap(0x7feb0fefe000, 128, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0fefe000
00:28:52.909079 close(3) = 0
00:28:52.909104 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.909327 open("/lib/x86_64-linux-gnu/libselinux.so.1", O_RDONLY|O_CLOEXEC) = 3
00:28:52.909348 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260Z\0\0\0\0\0\0"..., 832) = 832
00:28:52.909368 fstat(3, {st_mode=S_IFREG|0644, st_size=130224, ...}) = 0
00:28:52.909461 mmap(NULL, 2234080, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0d6ae000
00:28:52.909483 mprotect(0x7feb0d6cd000, 2093056, PROT_NONE) = 0
00:28:52.909502 mmap(0x7feb0d8cc000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1e000) = 0x7feb0d8cc000
00:28:52.909525 mmap(0x7feb0d8ce000, 5856, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0d8ce000
00:28:52.909548 close(3) = 0
00:28:52.909571 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.909592 open("/lib/x86_64-linux-gnu/liblzma.so.5", O_RDONLY|O_CLOEXEC) = 3
00:28:52.909612 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
00:28:52.909631 fstat(3, {st_mode=S_IFREG|0644, st_size=137400, ...}) = 0
00:28:52.909660 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0fe79000
00:28:52.909682 mmap(NULL, 2232456, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0d48c000
00:28:52.909701 mprotect(0x7feb0d4ad000, 2093056, PROT_NONE) = 0
00:28:52.909719 mmap(0x7feb0d6ac000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x20000) = 0x7feb0d6ac000
00:28:52.909743 close(3) = 0
00:28:52.909762 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.909782 open("/lib/x86_64-linux-gnu/libgcrypt.so.20", O_RDONLY|O_CLOEXEC) = 3
00:28:52.909802 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\215\0\0\0\0\0\0"..., 832) = 832
00:28:52.909820 fstat(3, {st_mode=S_IFREG|0644, st_size=919168, ...}) = 0
00:28:52.909838 mmap(NULL, 3015360, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0d1ab000
00:28:52.909857 mprotect(0x7feb0d283000, 2093056, PROT_NONE) = 0
00:28:52.909876 mmap(0x7feb0d482000, 36864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xd7000) = 0x7feb0d482000
00:28:52.909898 mmap(0x7feb0d48b000, 704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0d48b000
00:28:52.909919 close(3) = 0
00:28:52.909944 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.909966 open("/lib/x86_64-linux-gnu/libpcre.so.3", O_RDONLY|O_CLOEXEC) = 3
00:28:52.909986 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\25\0\0\0\0\0\0"..., 832) = 832
00:28:52.910007 fstat(3, {st_mode=S_IFREG|0644, st_size=456632, ...}) = 0
00:28:52.910027 mmap(NULL, 2552072, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0cf3b000
00:28:52.910046 mprotect(0x7feb0cfa9000, 2097152, PROT_NONE) = 0
00:28:52.910064 mmap(0x7feb0d1a9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6e000) = 0x7feb0d1a9000
00:28:52.910087 close(3) = 0
00:28:52.910110 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:52.910130 open("/lib/x86_64-linux-gnu/libgpg-error.so.0", O_RDONLY|O_CLOEXEC) = 3
00:28:52.910149 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
00:28:52.910168 fstat(3, {st_mode=S_IFREG|0644, st_size=80496, ...}) = 0
00:28:52.910205 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0fe78000
00:28:52.910227 mmap(NULL, 2175544, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7feb0cd27000
00:28:52.910246 mprotect(0x7feb0cd39000, 2097152, PROT_NONE) = 0
00:28:52.910266 mmap(0x7feb0cf39000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x12000) = 0x7feb0cf39000
00:28:52.910289 close(3) = 0
00:28:52.910326 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0fe77000
00:28:52.910356 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0fe76000
00:28:52.910380 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0fe74000
00:28:52.910399 arch_prctl(ARCH_SET_FS, 0x7feb0fe748c0) = 0
00:28:52.910458 mprotect(0x7feb0dedb000, 16384, PROT_READ) = 0
00:28:52.910482 mprotect(0x7feb0cf39000, 4096, PROT_READ) = 0
00:28:52.910506 mprotect(0x7feb0e0fc000, 4096, PROT_READ) = 0
00:28:52.910542 mprotect(0x7feb0d1a9000, 4096, PROT_READ) = 0
00:28:52.910570 mprotect(0x7feb0d482000, 4096, PROT_READ) = 0
00:28:52.910592 mprotect(0x7feb0edad000, 4096, PROT_READ) = 0
00:28:52.910612 mprotect(0x7feb0d6ac000, 4096, PROT_READ) = 0
00:28:52.910635 mprotect(0x7feb0d8cc000, 4096, PROT_READ) = 0
00:28:52.910660 mprotect(0x7feb0eba9000, 4096, PROT_READ) = 0
00:28:52.910766 mprotect(0x7feb0fefa000, 12288, PROT_READ) = 0
00:28:52.910830 mprotect(0x7feb0db1a000, 4096, PROT_READ) = 0
00:28:52.910864 mprotect(0x7feb0e61f000, 4096, PROT_READ) = 0
00:28:52.911266 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7feb0fe73000
00:28:52.911329 mprotect(0x7feb0e993000, 40960, PROT_READ) = 0
00:28:52.911457 mprotect(0x7feb0f1c8000, 114688, PROT_READ) = 0
00:28:52.911482 mprotect(0x7feb0f60e000, 4096, PROT_READ) = 0
00:28:52.911504 mprotect(0x7feb0f402000, 4096, PROT_READ) = 0
00:28:52.911524 mprotect(0x7feb0f817000, 4096, PROT_READ) = 0
00:28:52.911574 mprotect(0x7feb0fae8000, 12288, PROT_READ) = 0
00:28:52.911600 mprotect(0x7feb0fcf7000, 4096, PROT_READ) = 0
00:28:52.911625 mprotect(0x7ba000, 4096, PROT_READ) = 0
00:28:52.911646 mprotect(0x7feb0ff1e000, 4096, PROT_READ) = 0
00:28:52.911679 munmap(0x7feb0ff04000, 94791) = 0
00:28:52.911703 set_tid_address(0x7feb0fe74b90) = 12449
00:28:52.911720 set_robust_list(0x7feb0fe74ba0, 24) = 0
00:28:52.911740 rt_sigaction(SIGRTMIN, {0x7feb0deeab50, [], SA_RESTORER|SA_SIGINFO, 0x7feb0def6390}, NULL, 8) = 0
00:28:52.911765 rt_sigaction(SIGRT_1, {0x7feb0deeabe0, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7feb0def6390}, NULL, 8) = 0
00:28:52.911786 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
00:28:52.911809 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
00:28:52.911890 brk(NULL) = 0x10d3000
00:28:52.911910 brk(0x10f4000) = 0x10f4000
00:28:52.911959 statfs("/sys/fs/selinux", 0x7ffc267195e0) = -1 ENOENT (No such file or directory)
00:28:52.911995 statfs("/selinux", 0x7ffc267195e0) = -1 ENOENT (No such file or directory)
00:28:52.912019 open("/proc/filesystems", O_RDONLY) = 3
00:28:52.912047 fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
00:28:52.912068 read(3, "nodev\tsysfs\nnodev\trootfs\nnodev\tr"..., 1024) = 397
00:28:52.912097 read(3, "", 1024) = 0
00:28:52.912114 close(3) = 0
00:28:52.912172 rt_sigaction(SIGHUP, {0x414420, [], SA_RESTORER|SA_SIGINFO, 0x7feb0def6390}, NULL, 8) = 0
00:28:52.912187 rt_sigaction(SIGINT, {0x414420, [], SA_RESTORER|SA_SIGINFO, 0x7feb0def6390}, NULL, 8) = 0
00:28:52.912201 rt_sigaction(SIGTERM, {0x414420, [], SA_RESTORER|SA_SIGINFO, 0x7feb0def6390}, NULL, 8) = 0
00:28:52.912217 rt_sigaction(SIGCHLD, {SIG_DFL, [CHLD], SA_RESTORER|SA_RESTART, 0x7feb0db514b0}, {SIG_DFL, [], 0}, 8) = 0
00:28:52.912297 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
00:28:52.912319 ioctl(3, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
00:28:52.912336 ioctl(3, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
00:28:52.912352 ioctl(3, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
00:28:52.912366 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:52.912381 ioctl(3, SIOCGIFHWADDR, {ifr_name="enp2s0f0", ifr_hwaddr=14:dd:a9:d3:f5:dc}) = 0
00:28:52.912397 close(3) = 0
00:28:52.912434 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
00:28:52.912448 ioctl(3, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
00:28:52.912463 ioctl(3, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
00:28:52.912477 ioctl(3, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
00:28:52.912491 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:52.912506 ioctl(3, SIOCGIFNAME, {ifr_index=3, ifr_name="enp2s0f1"}) = 0
00:28:52.912521 ioctl(3, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:52.912535 ioctl(3, SIOCGIFNAME, {ifr_index=4}) = -1 ENODEV (No such device)
00:28:52.912549 close(3) = 0
00:28:52.912598 lstat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3", {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912619 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3", {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912636 open("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3", O_RDWR|O_CREAT|O_CLOEXEC, 0644) = 3
00:28:52.912653 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912669 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912685 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3", {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912706 lseek(3, 0, SEEK_SET) = 0
00:28:52.912719 read(3, "SQLite format 3\0\4\0\1\1\0@ \0\0\0W\0\0\0\25"..., 100) = 100
00:28:52.912775 fcntl(3, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:52.912792 fcntl(3, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
00:28:52.912806 fcntl(3, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:52.912820 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3-journal", 0x7ffc26718280) = -1 ENOENT (No such file or directory)
00:28:52.912836 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912850 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3-wal", 0x7ffc26718280) = -1 ENOENT (No such file or directory)
00:28:52.912865 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.912882 brk(0x112b000) = 0x112b000
00:28:52.912919 lseek(3, 0, SEEK_SET) = 0
00:28:52.912932 read(3, "SQLite format 3\0\4\0\1\1\0@ \0\0\0W\0\0\0\25"..., 1024) = 1024
00:28:52.912976 lseek(3, 9216, SEEK_SET) = 9216
00:28:52.912995 read(3, "\r\0\0\0\10\0H\0\3\223\3A\2\303\2D\1\277\1P\0\303\0H\0@\0\0\0\0\0\0"..., 1024) = 1024
00:28:52.913054 lseek(3, 11264, SEEK_SET) = 11264
00:28:52.913068 read(3, "\r\0\0\0\6\0L\0\2\357\2\200\1r\0\342\0L\3\205\0\0\0\0\0\0\0\0\0\0\0\0"..., 1024) = 1024
00:28:52.913106 fcntl(3, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
00:28:52.913136 fcntl(3, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:52.913151 fcntl(3, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
00:28:52.913166 fcntl(3, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:52.913180 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3-journal", 0x7ffc26718640) = -1 ENOENT (No such file or directory)
00:28:52.913195 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.913210 lseek(3, 24, SEEK_SET) = 24
00:28:52.913222 read(3, "\0\0\0W\0\0\0\25\0\0\0\0\0\0\0\0", 16) = 16
00:28:52.913236 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.913250 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3-wal", 0x7ffc26718640) = -1 ENOENT (No such file or directory)
00:28:52.913264 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.913279 lseek(3, 1024, SEEK_SET) = 1024
00:28:52.913292 read(3, "\r\0\0\0\1\3\372\0\3\372\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 1024) = 1024
00:28:52.913308 fcntl(3, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
00:28:52.913354 fcntl(3, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:52.913370 fcntl(3, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
00:28:52.913383 fcntl(3, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:52.913397 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3-journal", 0x7ffc267185e0) = -1 ENOENT (No such file or directory)
00:28:52.913412 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.913426 lseek(3, 24, SEEK_SET) = 24
00:28:52.913439 read(3, "\0\0\0W\0\0\0\25\0\0\0\0\0\0\0\0", 16) = 16
00:28:52.913453 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.913466 stat("/home/holli/BUILD/ds/data/data-obelix/DsParams.sqlite3-wal", 0x7ffc267185e0) = -1 ENOENT (No such file or directory)
00:28:52.913481 fstat(3, {st_mode=S_IFREG|0644, st_size=21504, ...}) = 0
00:28:52.913497 lseek(3, 4096, SEEK_SET) = 4096
00:28:52.913509 read(3, "\n\0\0\0\1\3\365\0\3\365\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 1024) = 1024
00:28:52.913528 lseek(3, 3072, SEEK_SET) = 3072
00:28:52.913543 read(3, "\r\0\0\0\1\3\321\0\3\321\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 1024) = 1024
00:28:52.913587 fcntl(3, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
00:28:52.913606 open("/home/holli/BUILD/ds/data/data-obelix/vdchostsettings.csv", O_RDONLY) = -1 ENOENT (No such file or directory)
00:28:52.913629 open("/etc/localtime", O_RDONLY|O_CLOEXEC) = 4
00:28:52.913645 fstat(4, {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.913660 fstat(4, {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.913675 read(4, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\7\0\0\0\7\0\0\0\0"..., 4096) = 2237
00:28:52.913692 lseek(4, -1419, SEEK_CUR) = 818
00:28:52.913705 read(4, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\7\0\0\0\7\0\0\0\0"..., 4096) = 1419
00:28:52.913720 close(4) = 0
00:28:52.913741 fstat(1, {st_mode=S_IFCHR|0620, st_rdev=makedev(136, 0), ...}) = 0
00:28:52.913756 write(1, "[2017-06-09 00:28:52.913 W] Runn"..., 107[2017-06-09 00:28:52.913 W] Running a migrated configuration: dSUID collisions with original unit possible
) = 107
00:28:52.913777 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.913795 write(1, "[2017-06-09 00:28:52.913 W] - na"..., 113[2017-06-09 00:28:52.913 W] - native vDC host dSUID of this instance would be A89904D65EEC53ABC0B50D4845FF6B0400
) = 113
00:28:52.913814 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.913848 write(1, "[2017-06-09 00:28:52.913 W] - if"..., 94[2017-06-09 00:28:52.913 W] - if this is not a replacement unit -> factory reset recommended!
) = 94
00:28:52.913882 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.913900 write(1, "[2017-06-09 00:28:52.913 D] Main"..., 70[2017-06-09 00:28:52.913 D] Mainloop specified cycle time: 0.033333 S
) = 70
00:28:52.913918 nanosleep({0, 33333000}, NULL) = 0
00:28:52.947361 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.947423 write(1, "[2017-06-09 00:28:52.947 D] - me"..., 56[2017-06-09 00:28:52.947 D] - measurement 1: 0.033435 S
) = 56
00:28:52.947453 nanosleep({0, 33333000}, NULL) = 0
00:28:52.980900 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:52.980950 write(1, "[2017-06-09 00:28:52.980 D] - me"..., 77[2017-06-09 00:28:52.980 D] - measurement 2: 0.033436 S, average: 0.033435 S
) = 77
00:28:52.980975 nanosleep({0, 33333000}, NULL) = 0
00:28:53.014404 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:53.014450 write(1, "[2017-06-09 00:28:53.014 D] - me"..., 77[2017-06-09 00:28:53.014 D] - measurement 3: 0.033419 S, average: 0.033430 S
) = 77
00:28:53.014485 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 4
00:28:53.014522 ioctl(4, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
00:28:53.014555 ioctl(4, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
00:28:53.014588 ioctl(4, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
00:28:53.014612 ioctl(4, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:53.014631 ioctl(4, SIOCGIFNAME, {ifr_index=3, ifr_name="enp2s0f1"}) = 0
00:28:53.014655 ioctl(4, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:53.014674 ioctl(4, SIOCGIFNAME, {ifr_index=4}) = -1 ENODEV (No such device)
00:28:53.014697 close(4) = 0
00:28:53.014740 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:53.014762 write(1, "\n", 1
) = 1
00:28:53.014792 write(1, "\n", 1
) = 1
00:28:53.014810 write(1, "\n", 1
) = 1
00:28:53.014833 write(1, "[2017-06-09 00:28:53.014 N] *** "..., 128[2017-06-09 00:28:53.014 N] *** starting initialisation of vcd host 'plan44.ch vdcd-obelix #F74D4B9E9B7457FC80A260E1ED5FA6F800'
) = 128
00:28:53.014852 write(1, " *** "..., 127 *** dSUID (MAC-derived) = F74D4B9E9B7457FC80A260E1ED5FA6F800, MAC: 00:00:00:00:00:00, IP = 0.0.0.0
) = 127
00:28:53.014871 write(1, " \n", 29
) = 29
00:28:53.014905 socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
00:28:53.014932 connect(4, {sa_family=AF_LOCAL, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
00:28:53.014969 close(4) = 0
00:28:53.014993 socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 4
00:28:53.015012 connect(4, {sa_family=AF_LOCAL, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
00:28:53.015035 close(4) = 0
00:28:53.015056 open("/etc/nsswitch.conf", O_RDONLY|O_CLOEXEC) = 4
00:28:53.015081 fstat(4, {st_mode=S_IFREG|0644, st_size=529, ...}) = 0
00:28:53.015101 read(4, "# /etc/nsswitch.conf\n#\n# Example"..., 4096) = 529
00:28:53.015126 read(4, "", 4096) = 0
00:28:53.015144 close(4) = 0
00:28:53.015185 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 4
00:28:53.015206 fstat(4, {st_mode=S_IFREG|0644, st_size=94791, ...}) = 0
00:28:53.015230 mmap(NULL, 94791, PROT_READ, MAP_PRIVATE, 4, 0) = 0x7feb0ff04000
00:28:53.015253 close(4) = 0
00:28:53.015277 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:53.015298 open("/lib/x86_64-linux-gnu/tls/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015322 stat("/lib/x86_64-linux-gnu/tls/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015343 open("/lib/x86_64-linux-gnu/tls/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015367 stat("/lib/x86_64-linux-gnu/tls", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015387 open("/lib/x86_64-linux-gnu/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015411 stat("/lib/x86_64-linux-gnu/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015430 open("/lib/x86_64-linux-gnu/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015449 stat("/lib/x86_64-linux-gnu", {st_mode=S_IFDIR|0755, st_size=20480, ...}) = 0
00:28:53.015474 open("/usr/lib/x86_64-linux-gnu/tls/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015494 stat("/usr/lib/x86_64-linux-gnu/tls/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015518 open("/usr/lib/x86_64-linux-gnu/tls/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015536 stat("/usr/lib/x86_64-linux-gnu/tls", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015559 open("/usr/lib/x86_64-linux-gnu/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015579 stat("/usr/lib/x86_64-linux-gnu/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015602 open("/usr/lib/x86_64-linux-gnu/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015622 stat("/usr/lib/x86_64-linux-gnu", {st_mode=S_IFDIR|0755, st_size=73728, ...}) = 0
00:28:53.015644 open("/lib/tls/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015667 stat("/lib/tls/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015686 open("/lib/tls/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015707 stat("/lib/tls", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015726 open("/lib/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015749 stat("/lib/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015768 open("/lib/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015787 stat("/lib", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
00:28:53.015807 open("/usr/lib/tls/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015826 stat("/usr/lib/tls/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015845 open("/usr/lib/tls/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015865 stat("/usr/lib/tls", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015883 open("/usr/lib/x86_64/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015902 stat("/usr/lib/x86_64", 0x7ffc26717d10) = -1 ENOENT (No such file or directory)
00:28:53.015927 open("/usr/lib/libnss_db.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
00:28:53.015965 stat("/usr/lib", {st_mode=S_IFDIR|0755, st_size=12288, ...}) = 0
00:28:53.015991 munmap(0x7feb0ff04000, 94791) = 0
00:28:53.016026 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 4
00:28:53.016054 fstat(4, {st_mode=S_IFREG|0644, st_size=94791, ...}) = 0
00:28:53.016075 mmap(NULL, 94791, PROT_READ, MAP_PRIVATE, 4, 0) = 0x7feb0ff04000
00:28:53.016093 close(4) = 0
00:28:53.016111 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
00:28:53.016132 open("/lib/x86_64-linux-gnu/libnss_files.so.2", O_RDONLY|O_CLOEXEC) = 4
00:28:53.016151 read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260!\0\0\0\0\0\0"..., 832) = 832
00:28:53.016170 fstat(4, {st_mode=S_IFREG|0644, st_size=47600, ...}) = 0
00:28:53.016190 mmap(NULL, 2168600, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 4, 0) = 0x7feb0cb15000
00:28:53.016209 mprotect(0x7feb0cb20000, 2093056, PROT_NONE) = 0
00:28:53.016239 mmap(0x7feb0cd1f000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 4, 0xa000) = 0x7feb0cd1f000
00:28:53.016265 mmap(0x7feb0cd21000, 22296, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7feb0cd21000
00:28:53.016288 close(4) = 0
00:28:53.016323 mprotect(0x7feb0cd1f000, 4096, PROT_READ) = 0
00:28:53.016341 munmap(0x7feb0ff04000, 94791) = 0
00:28:53.016364 open("/etc/services", O_RDONLY|O_CLOEXEC) = 4
00:28:53.016385 fstat(4, {st_mode=S_IFREG|0644, st_size=19605, ...}) = 0
00:28:53.016403 read(4, "# Network services, Internet sty"..., 4096) = 4096
00:28:53.016451 read(4, "\t\t# IPX\nipx\t\t213/udp\nimap3\t\t220/"..., 4096) = 4096
00:28:53.016491 read(4, "nessus\t\t1241/tcp\t\t\t# Nessus vuln"..., 4096) = 4096
00:28:53.016536 read(4, "347/tcp\t\t\t# gnutella\ngnutella-rt"..., 4096) = 4096
00:28:53.016576 read(4, "ureg\t779/udp\t\tmoira_ureg\t# Moira"..., 4096) = 3221
00:28:53.016610 read(4, "", 4096) = 0
00:28:53.016629 close(4) = 0
00:28:53.016651 socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 4
00:28:53.016682 setsockopt(4, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
00:28:53.016703 bind(4, {sa_family=AF_INET, sin_port=htons(8340), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
00:28:53.016728 listen(4, 3) = 0
00:28:53.016746 fcntl(4, F_GETFL) = 0x2 (flags O_RDWR)
00:28:53.016762 fcntl(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0
00:28:53.016782 open("/etc/services", O_RDONLY|O_CLOEXEC) = 5
00:28:53.016801 fstat(5, {st_mode=S_IFREG|0644, st_size=19605, ...}) = 0
00:28:53.016820 read(5, "# Network services, Internet sty"..., 4096) = 4096
00:28:53.016855 read(5, "\t\t# IPX\nipx\t\t213/udp\nimap3\t\t220/"..., 4096) = 4096
00:28:53.016887 read(5, "nessus\t\t1241/tcp\t\t\t# Nessus vuln"..., 4096) = 4096
00:28:53.016921 read(5, "347/tcp\t\t\t# gnutella\ngnutella-rt"..., 4096) = 4096
00:28:53.016952 read(5, "ureg\t779/udp\t\tmoira_ureg\t# Moira"..., 4096) = 3221
00:28:53.016987 read(5, "", 4096) = 0
00:28:53.017006 close(5) = 0
00:28:53.017023 socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 5
00:28:53.017041 setsockopt(5, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
00:28:53.017059 bind(5, {sa_family=AF_INET, sin_port=htons(8989), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
00:28:53.017078 listen(5, 10) = 0
00:28:53.017094 fcntl(5, F_GETFL) = 0x2 (flags O_RDWR)
00:28:53.017111 fcntl(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0
00:28:53.017139 lstat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017163 stat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017183 open("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3", O_RDWR|O_CREAT|O_CLOEXEC, 0644) = 6
00:28:53.017204 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017223 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017242 stat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017264 lseek(6, 0, SEEK_SET) = 0
00:28:53.017280 read(6, "SQLite format 3\0\4\0\1\1\0@ \0\0\0\4\0\0\0\4"..., 100) = 100
00:28:53.017333 fcntl(6, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:53.017351 fcntl(6, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
00:28:53.017368 fcntl(6, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:53.017387 stat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3-journal", 0x7ffc267177f0) = -1 ENOENT (No such file or directory)
00:28:53.017407 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017425 stat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3-wal", 0x7ffc267177f0) = -1 ENOENT (No such file or directory)
00:28:53.017445 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017464 brk(0x1152000) = 0x1152000
00:28:53.017504 lseek(6, 0, SEEK_SET) = 0
00:28:53.017519 read(6, "SQLite format 3\0\4\0\1\1\0@ \0\0\0\4\0\0\0\4"..., 1024) = 1024
00:28:53.017581 fcntl(6, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
00:28:53.017610 fcntl(6, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:53.017627 fcntl(6, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
00:28:53.017645 fcntl(6, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
00:28:53.017665 stat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3-journal", 0x7ffc26717bb0) = -1 ENOENT (No such file or directory)
00:28:53.017687 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017708 lseek(6, 24, SEEK_SET) = 24
00:28:53.017733 read(6, "\0\0\0\4\0\0\0\4\0\0\0\0\0\0\0\0", 16) = 16
00:28:53.017751 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017772 stat("/home/holli/BUILD/ds/data/data-obelix/Evaluator_Device_Container_1.sqlite3-wal", 0x7ffc26717bb0) = -1 ENOENT (No such file or directory)
00:28:53.017794 fstat(6, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
00:28:53.017813 lseek(6, 1024, SEEK_SET) = 1024
00:28:53.017829 read(6, "\r\0\0\0\1\3\373\0\3\373\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 1024) = 1024
00:28:53.017850 fcntl(6, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
00:28:53.017877 pipe([7, 8]) = 0
00:28:53.017897 fcntl(7, F_GETFL) = 0 (flags O_RDONLY)
00:28:53.017919 fcntl(7, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
00:28:53.017939 fcntl(8, F_GETFL) = 0x1 (flags O_WRONLY)
00:28:53.017956 fcntl(8, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
00:28:53.017977 write(8, "W", 1) = 1
00:28:53.017997 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 9
00:28:53.018016 ioctl(9, SIOCGIFNAME, {ifr_index=1, ifr_name="lo"}) = 0
00:28:53.018036 ioctl(9, SIOCGIFFLAGS, {ifr_name="lo", ifr_flags=IFF_UP|IFF_LOOPBACK|IFF_RUNNING}) = 0
00:28:53.018056 ioctl(9, SIOCGIFNAME, {ifr_index=2, ifr_name="enp2s0f0"}) = 0
00:28:53.018073 ioctl(9, SIOCGIFFLAGS, {ifr_name="enp2s0f0", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:53.018095 ioctl(9, SIOCGIFNAME, {ifr_index=3, ifr_name="enp2s0f1"}) = 0
00:28:53.018113 ioctl(9, SIOCGIFFLAGS, {ifr_name="enp2s0f1", ifr_flags=IFF_UP|IFF_BROADCAST|IFF_RUNNING|IFF_SLAVE|IFF_MULTICAST}) = 0
00:28:53.018139 ioctl(9, SIOCGIFNAME, {ifr_index=4}) = -1 ENODEV (No such device)
00:28:53.018158 close(9) = 0
00:28:53.018179 stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2237, ...}) = 0
00:28:53.018203 write(1, "[2017-06-09 00:28:53.018 N] *** "..., 56[2017-06-09 00:28:53.018 N] *** Network connection lost
) = 56

[holli]

Hello Lukas,

I got it running – with a change to your code

I used

holli@obelix:~/BUILD/ds/vdcd-br-test-tool$ ./iflist
lo: Interface 1, up
enp2s0f0: Interface 2, up, full duplex, 10000 Mbps
enp2s0f1: Interface 3, up, full duplex, 10000 Mbps

holli@obelix:~/BUILD/ds/vdcd-br-test-tool$ ./iflist br0
br0: Interface 6, up

so my main device is interface 6 – for me it looks like the loop is not going through all

I changed in file

holli@obelix:~/BUILD/ds/vdcd/src/p44utils$ ls -al macaddress.cp

line 215:

// enumerate interfaces
ifIndex = 6; // start with 1
do {

I changed this to 6 and now it is working – so maybe for a reason I don’t know yet the interface id might change on kernel updates/reboots?
Or the loop is not going through all devices – but why would vdcd need a loop if the interface is specified on the command line…

Anyhow when everyone gets up in the morning we have light – otherwise I would have been in big trouble tomorrow/today…

Thanks
Holli

[Lukas Zeller]

Hi Holli

> On 9 Jun 2017, at 03:07, holli <ho...@holli.at> wrote:
>
> [...]

> holli@obelix:~/BUILD/ds/vdcd-br-test-tool$ ./iflist
> lo: Interface 1, up
> enp2s0f0: Interface 2, up, full duplex, 10000 Mbps
> enp2s0f1: Interface 3, up, full duplex, 10000 Mbps

apparently, for some reason, enumerating the interfaces does not work any more since your system update.

Looking at the strace from your other email, it seems to be that querying the name for interface index 4 returns ENODEV, which aborts the enumeration.

00:28:52.912535 ioctl(3, SIOCGIFNAME, {ifr_index=4}) = -1 ENODEV (No such device)

> [...] maybe for a reason I don’t know yet the interface id might change on kernel updates/reboots?

The enumeration (indices) of the interfaces change when there is a a change in the network configuration. Until now, however the indices were always consecutive. Now apparently there are holes in the series.

> Or the loop is not going through all devices

Yes, that's what's happening. Because since your update, there is no device with index==4 any more, but there ARE more interfaces with higher indices.

The question is now, what's the correct way to enumerate all interfaces? Apparently the method used in all the sources I know, including the accepted Stack Overflow Answer (the iflist example), no longer work.
They are all based on the assumption that network interface indexes are contiguous (1,2,3...last)
But it seems that especially in virtualized environments like docker containers this is no longer the case. Is your Obelix a VM?

Here's a fix: in macaddress.cpp, around line 222, replace

res = ioctl(sock, SIOCGIFNAME, &ifr);
if (res<0) {
break; // no more names, end
}

by

res = ioctl(sock, SIOCGIFNAME, &ifr);
if (res<0) {
if (ifIndex>20 || res!=ENODEV) break; // error or no more names -> end
ifIndex++; continue; // otherwise, just skip (indices aren't necessarily contiguous)
}

I'd be glad if you could verify that works in your case.

> – but why would vdcd need a loop if the interface is specified on the command line…

That's an implementation choice - the macaddress.cpp code *always* iterates through all interfaces. If you don't specify something on the command line, it just automatically picks the first non-loopback interface. If you specify the name, the loop stops when that name is encountered. iflist on the other hand uses SIOCGIFINDEX to directly access the named interface if one is specified, that's why it works then, even if the enumeration is broken as well.

Best Regards,

Lukas

[holli]

Hello,

src/p44utils/macaddress.cpp: In function ‘bool p44::getIfInfo(uint64_t*, uint32_t*, int*, const char*)’:
src/p44utils/macaddress.cpp:224:32: error: ‘ENODEV’ was not declared in this scope

if (ifIndex>20 || res!=ENODEV) break; // error or no more names -> end

^
Makefile:4332: recipe for target 'src/p44utils/vdcd-macaddress.o' failed

To your questions – OBELIX is physical box (blade)

Best regards
Holli

[Lukas Zeller]

> On 9 Jun 2017, at 11:02, holli <ho...@holli.at> wrote:
>
> src/p44utils/macaddress.cpp: In function ‘bool p44::getIfInfo(uint64_t*, uint32_t*, int*, const char*)’:
> src/p44utils/macaddress.cpp:224:32: error: ‘ENODEV’ was not declared in this scope
> if (ifIndex>20 || res!=ENODEV) break; // error or no more names -> end
> ^
> Makefile:4332: recipe for target 'src/p44utils/vdcd-macaddress.o' failed

Sorry for that, I couldn't build 1:1 on a Linux box on the fly (and it builds in my cross platform build)
I guess it'll build when you add

#include <errno.h>

right after #include <linux/sockios.h> around line 198

> To your questions – OBELIX is physical box (blade)

Ok - apparently there are other reasons for interface indices not being in sequence (any more). Obelix seems to have a non-trivial network setup, for sure ;-)

Best Regards,

Lukas

[holli]

Hello,

I changed my 6 to 1 back and added the include – this did do the trick – I could compile now and restarted vdcd -> did connect to vdsm on dss -> cool

Honestly I don’t know why there would be a gap – so the last time after a 2nd reboot it looks like I was just lucky that br0 was on 4 maybe -> it was on 6 on the initial reboot with a gap as well…

But anyway thanks a lot for your help – will this hack make it into the github?

From a network perspective I don’t think I have rocket sience here – just a /22 network with 2x 10gb bonding and some vm’s nothing special – on the old server I had 4x 1gb bonding and had never such an issue…
But this was Ubuntu 12.04 and now I’m on 16.04 – maybe this is even a bug within Ubuntu…

Best regards
Holli

[Lukas Zeller]

> On 9 Jun 2017, at 11:26, holli <ho...@holli.at> wrote:
>
> [...]

> Honestly I don’t know why there would be a gap – so the last time after a 2nd reboot it looks like I was just lucky that br0 was on 4 maybe -> it was on 6 on the initial reboot with a gap as well…

Apparently network devices can now "go away" during the operation of the system, creating gaps. Maybe there is a intermediate network interface needed during boot, which gets obsolete once the network stack is fully up.

> But anyway thanks a lot for your help – will this hack make it into the github?

Yes. But it's not exactly a hack ;-)
I'd say it's a compromise - not really elegant, but safe to add. The only restriction is that it can't detect gaps for interface indices > 20.

Best Regards,

Lukas

[holli]

Hi Lukas,

I had time today and did another reboot of the box

lo: Interface 1, up
enp2s0f0: Interface 2, up, full duplex, 10000 Mbps
enp2s0f1: Interface 3, up, full duplex, 10000 Mbps

bond0: Interface 4, up, full duplex, 20000 Mbps
br0: Interface 5, up
virbr0: Interface 6, up
virbr0-nic: Interface 7, full duplex, 10 Mbps
vnet0: Interface 8, up, full duplex, 10 Mbps
vnet1: Interface 9, up, full duplex, 10 Mbps
vnet2: Interface 10, up, full duplex, 10 Mbps
vnet3: Interface 11, up, full duplex, 10 Mbps
vnet4: Interface 12, up, full duplex, 10 Mbps
holli@obelix:~/BUILD/ds/vdcd-br-test-tool$

now this is working – but vdcd did not get an ip anymore – I ended up with changing the index to 5 to get vdcd working again
so even the loop with 20 did not work as expected.

any idea

thanks
holli

[Lukas Zeller]

Hi holli,

> On 9 Jun 2017, at 18:44, holli <ho...@holli.at> wrote:
>
> Hi Lukas,
>
> I had time today and did another reboot of the box
>
> lo: Interface 1, up
> enp2s0f0: Interface 2, up, full duplex, 10000 Mbps
> enp2s0f1: Interface 3, up, full duplex, 10000 Mbps
> bond0: Interface 4, up, full duplex, 20000 Mbps
> br0: Interface 5, up
> virbr0: Interface 6, up
> virbr0-nic: Interface 7, full duplex, 10 Mbps
> vnet0: Interface 8, up, full duplex, 10 Mbps
> vnet1: Interface 9, up, full duplex, 10 Mbps
> vnet2: Interface 10, up, full duplex, 10 Mbps
> vnet3: Interface 11, up, full duplex, 10 Mbps
> vnet4: Interface 12, up, full duplex, 10 Mbps
> holli@obelix:~/BUILD/ds/vdcd-br-test-tool$
>
> now this is working – but vdcd did not get an ip anymore – I ended up with changing the index to 5 to get vdcd working again
> so even the loop with 20 did not work as expected.

It can't be the loop, because the test program clearly shows all interface indices are below 20.

What I am beginnig to suspect is that your system's network stack is not yet fully ready when vdcd is started, and so sees an intermediate state of interfaces, depending on the timing.
How do you start vdcd? Is it managed by systemd or some other way? Anyway, starting it should depend one way or another on network stack being fully up.

Best Regards,

Lukas