Broken dev docker image (VPP 20.09)

[rewenset]

Note that I didn't check any other VPP version except the default one 20.09.

Hi! I have a problem with running dev agent. I've tried first to

$ docker pull ligato/dev-vpp-agent

And run it as

$ docker run -it --rm --name dagent --privileged ligato/dev-vpp-agent

But it failed with:

/usr/bin/vpp[16]: clib_elf_parse_file: open `linux-vdso.so.1': No such file or directory
/usr/bin/vpp[16]: clib_elf_parse_file: open `linux-vdso.so.1': No such file or directory
vpp[16]: snat_config: unknown input 'translation hash buckets 10485...'
vpp[16]: snat_config: unknown input 'translation hash buckets 10485...'
INFO[0005] Signal terminated received, stopping.         loc="agent/agent.go(196)" logger=agent
INFO[0005] Stopping agent                                loc="agent/agent.go(269)" logger=agent
INFO[0005] stopping programs                             loc="supervisor/plugin.go(107)" logger=supervisor
time="2021-04-16 06:42:20.99570" level=info msg="Signal terminated received during agent start, stopping" loc="agent/agent.go(153)" logger=agent
INFO[0005] Agent stopped                                 loc="agent/agent.go(291)" logger=agent

I thought that it happens because of the image in dockerhub is old, so I built it myself:

$ make dev-image
# building dev image                                                                                                                                                                           
IMAGE_TAG= \                                                                                                                                                                                   
        VPP_IMG=ligato/vpp-base:20.09 VPP_VERSION=2009 VPP_BINAPI=plugins/vpp/binapi/vpp2009 \                                                                                                 
        VERSION=v3.3.0-alpha-26-g843b3ec02 COMMIT=843b3ec02f631c1c861578df887839a11ef0acc2 BRANCH=master \                                                                                    
        BUILD_DATE=1618555002 \                                                                                                                                                                
  ./docker/dev/build.sh  
...

and after I tried to run dev-agent, but it failed and the error was still the same.

I've found that it because of VPP configuration:

vpp[16]: snat_config: unknown input 'translation hash buckets 10485...'

[cymetz09]

Same error with debug turned on.

vpp-agent git:(master) ✗ sudo docker run -it --rm --name dagent --privileged -e RUN_VPP_DEBUG=y ligato/dev-vpp-agent
INFO[0000] Starting agent version: v0.0.0-dev            BuildDate= CommitHash= loc="agent/agent.go(134)" logger=agent
INFO[0000] Agent started with 2 plugins (took 2ms)       loc="agent/agent.go(179)" logger=agent
                                       __
  _  _____  ___ _______ ____ ____ ___ / /_
 | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/  vpp-agent v3.3.0-alpha-24-g3b8422268
 |___/ .__/ .__/   \_'_/\_' /\__/_//_\__/   Wed Mar 17 17:18:37 UTC 2021 (30 days ago)
    /_/  /_/           /___/                root@buildkitsandbox (go1.15.2 linux/amd64)

/usr/bin/vpp[17]: clib_elf_parse_file: open `linux-vdso.so.1': No such file or directory
/usr/bin/vpp[17]: clib_elf_parse_file: open `linux-vdso.so.1': No such file or directory
time="2021-04-16 19:02:49.50935" level=info msg="Starting agent version: v3.3.0-alpha-24-g3b8422268" BuildDate="Wed Mar 17 17:18:37 UTC 2021" CommitHash=3b84222 loc="agent/agent.go(134)" logger=agent
time="2021-04-16 19:02:49.51637" level=debug msg="-> Init(): kvscheduler" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.51690" level=debug msg="kvscheduler config not found" loc="kvscheduler/plugin_scheduler.go(213)" logger=kvscheduler
time="2021-04-16 19:02:49.51713" level=debug msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" loc="kvscheduler/plugin_scheduler.go(167)" logger=kvscheduler
time="2021-04-16 19:02:49.51852" level=debug msg="Registering handler: /scheduler/txn-history" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.51940" level=debug msg="Registering handler: /scheduler/key-timeline" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.52011" level=debug msg="Registering handler: /scheduler/graph-snapshot" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.52079" level=debug msg="Registering handler: /scheduler/flag-stats" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.52347" level=debug msg="Registering handler: /scheduler/downstream-resync" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.52422" level=debug msg="Registering handler: /scheduler/dump" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.52739" level=debug msg="Registering handler: /scheduler/status" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.52901" level=debug msg="Registering handler: /scheduler/graph" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.53037" level=debug msg="Registering handler: /scheduler/stats" loc="rest/plugin_impl_rest.go(125)" logger=http
time="2021-04-16 19:02:49.53170" level=debug msg="-> Init(): resync" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.53296" level=debug msg="-> Init(): etcd" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.53425" level=debug msg="Connecting to Etcd.." endpoints="[172.17.0.1:2379]" loc="etcd/bytes_broker_impl.go(60)" logger=etcd
time="2021-04-16 19:02:49.54330" level=info msg="Connected to Etcd (took 9.150513ms)" endpoints="[172.17.0.1:2379]" loc="etcd/bytes_broker_impl.go(60)" logger=etcd
time="2021-04-16 19:02:49.54964" level=debug msg="-> Init(): kvdb-etcd-datasync" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.54981" level=debug msg="-> Init(): consul" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.55046" level=info msg="Consul config not found, skip loading this plugin" loc="consul/plugin.go(138)" logger=consul
time="2021-04-16 19:02:49.55061" level=debug msg="-> Init(): kvdb-consul-datasync" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.55131" level=debug msg="-> Init(): redis" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.55159" level=info msg="Redis config not found, skip loading this plugin" loc="redis/plugin_impl_redis.go(123)" logger=redis
time="2021-04-16 19:02:49.55296" level=debug msg="-> Init(): kvdb-redis-datasync" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.55319" level=debug msg="-> Init(): status-check" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.55339" level=debug msg="-> Init(): govpp" loc="agent/agent.go(220)" logger=agent
time="2021-04-16 19:02:49.55427" level=debug msg="config file \"\" not found, using default config" loc="govppmux/config.go(85)" logger=govpp
time="2021-04-16 19:02:49.55446" level=debug msg="config: &{ReconnectResync:true ReplyTimeout:1s ConnectViaShm:false ShmPrefix: BinAPISocketPath: StatsSocketPath: RetryRequestCount:0 RetryRequestTimeout:500ms RetryConnectCount:0 RetryConnectTimeout:1s ProxyEnabled:true HealthCheckProbeInterval:1s HealthCheckReplyTimeout:250ms HealthCheckThreshold:1 TraceEnabled:false}" loc="govppmux/plugin_impl_govppmux.go(101)" logger=govpp
time="2021-04-16 19:02:49.55456" level=debug msg="found 15 registered VPP handlers" loc="govppmux/plugin_impl_govppmux.go(117)" logger=govpp
time="2021-04-16 19:02:49.55465" level=debug msg="- handler: ipsec      has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.55473" level=debug msg="- handler: stn        has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.55481" level=debug msg="- handler: nat        has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.55517" level=debug msg="- handler: wireguard  has 2 versions: [20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.55717" level=debug msg="- handler: core       has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.55871" level=debug msg="- handler: dns        has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56005" level=debug msg="- handler: abf        has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56048" level=debug msg="- handler: interface  has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56230" level=debug msg="- handler: l2         has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56348" level=debug msg="- handler: srv6       has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56548" level=debug msg="- handler: acl        has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56588" level=debug msg="- handler: telemetry  has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.56805" level=debug msg="- handler: ipfix      has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.57338" level=debug msg="- handler: l3         has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
time="2021-04-16 19:02:49.57511" level=debug msg="- handler: punt       has 4 versions: [20.01 20.05.1 20.09-rc0 21.01]" loc="govppmux/plugin_impl_govppmux.go(120)" logger=govpp
vpp[17]: snat_config: unknown input 'translation hash buckets 10485...'
vpp[17]: snat_config: unknown input 'translation hash buckets 10485...'
INFO[0002] Signal terminated received, stopping.         loc="agent/agent.go(196)" logger=agent
INFO[0002] Stopping agent                                loc="agent/agent.go(269)" logger=agent
INFO[0002] stopping programs                             loc="supervisor/plugin.go(107)" logger=supervisor
time="2021-04-16 19:02:51.41152" level=info msg="Signal terminated received during agent start, stopping" loc="agent/agent.go(153)" logger=agent
INFO[0002] Agent stopped                                 loc="agent/agent.go(291)" logger=agent```