Broken dev docker image (VPP 20.09)
rewenset opened this issue · 1 comments
rewenset commented
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 commented
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```