Skip to content
Docker

Update GoVPP to 0.8.0 (#1938) #955

Sign in to view logs

Update GoVPP to 0.8.0 (#1938)

Update GoVPP to 0.8.0 (#1938) #955

GitHub Actions / JUnit Test Report failed Aug 14, 2023 in 0s

101 tests run, 90 passed, 8 skipped, 3 failed.

Annotations

Check failure on line 322 in tests/e2e/e2etest/e2e.go

See this annotation in the file changed.

@github-actions github-actions / JUnit Test Report

e2e.TestNATPools/CRUD_for_named_pool 

Failed
Raw output 
=== RUN   TestNATPools/CRUD_for_named_pool
level=debug logger=global msg="Environ:\nGOTESTSUM_FORMAT=testname\nHOSTNAME=d4bd68bd8dfb\nRUNNER_DEBUG=1\nPWD=/\nHOME=/root\nVPP_AGENT=prod_vpp_agent\nSHLVL=0\nTESTDATA_DIR=/home/runner/work/vpp-agent/vpp-agent/tests/e2e/e2etest/resources\nGOTESTSUM_JUNITFILE=/testreport/e2e.xml\nPATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\nGITHUB_WORKFLOW=Test\nTEST_SUITE=e2e" time="2023-08-14 11:31:19.73464"
    e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc0004cf580 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:31:19.74374"
    containerruntime.go:213: started Agent agent0 container d6496fd661c04e5c161aed74f756980415f9f8c6c200ba5a0f72dda13d79a31b
cid=d6496fd661c0 container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:31:19.97618"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:19.97628"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:19.97655"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:31:19.97556" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:31:19.97738" loc="agent/agent.go:179"
[container::/e2e-test-vppagent-agent0/d6496fd661c0]                                        __
[container::/e2e-test-vppagent-agent0/d6496fd661c0]   _  _____  ___ _______ ____ ____ ___ / /_  
[container::/e2e-test-vppagent-agent0/d6496fd661c0]  | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/  vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/d6496fd661c0]  |___/ .__/ .__/   \_'_/\_' /\__/_//_\__/   Mon Aug 14 11:12:22 UTC 2023 (19 minutes ago)
[container::/e2e-test-vppagent-agent0/d6496fd661c0]     /_/  /_/           /___/                root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/d6496fd661c0] 
[container::/e2e-test-vppagent-agent0/d6496fd661c0] BuildDate="Mon Aug 14 11:12:22 UTC 2023" CommitHash=f4fe5e7@master level=info logger=agent msg="Starting agent version: f4fe5e7" time="2023-08-14 11:31:20.00727" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:31:20.00770" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:31:20.00801" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:31:20.00823" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:31:20.00848" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:31:20.00883" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:31:20.00904" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:31:20.00926" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:31:20.00948" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:31:20.00968" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:31:20.00989" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:31:20.01009" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:31:20.01030" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:31:20.01050" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:31:20.01071" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:31:20.01091" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:31:20.01113" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:31:20.01137" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:31:20.01157" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:31:20.01177" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:31:20.01198" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:31:20.01219" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:31:20.01240" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:31:20.01262" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:31:20.01282" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:31:20.01302" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:31:20.01323" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp 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}" time="2023-08-14 11:31:20.01346" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:31:20.01369" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: l2         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01389" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: ipfix      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01410" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: srv6       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01429" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: abf        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01449" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: ipsec      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01470" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: dns        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01479" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: punt       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01488" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: core       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01505" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: acl        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01516" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: wireguard  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01541" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: nat        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01557" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: telemetry  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01576" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: stn        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01598" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: interface  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01617" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="- handler: l3         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:20.01638" loc="govppmux/plugin_impl_govppmux.go:120"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:20.07668"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:20.07691"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] vpp[36964]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/d6496fd661c0] vpp[36964]: vat-plug/load: vat_plugin_register: nsh plugin not loaded...
[container::/e2e-test-vppagent-agent0/d6496fd661c0] vpp[36964]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="first connection to VPP established (took 152ms)" time="2023-08-14 11:31:20.16889" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:31:20.16946" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="binapi version 22.10 partly incompatible: (19/355 messages)" time="2023-08-14 11:31:20.17021" loc="binapi/binapi.go:58"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="binapi version 23.06 fully COMPATIBLE (867 messages)" time="2023-08-14 11:31:20.17104" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:31:20.17175" loc="govppmux/plugin_impl_govppmux.go:135"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="connection to VPP established (took 3ms)" time="2023-08-14 11:31:20.17523" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="binapi version 22.10 partly incompatible: (19/355 messages)" time="2023-08-14 11:31:20.17645" loc="binapi/binapi.go:58"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="binapi version 23.06 fully COMPATIBLE (867 messages)" time="2023-08-14 11:31:20.17724" loc="binapi/binapi.go:66"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:20.17748"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:20.17776"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler core using preferred version: 23.06" time="2023-08-14 11:31:20.17727" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="vpp# show version verbose\nVersion:                  v23.06-rc2~2-g493b8990d~b17\nCompiled by:              root\nCompile host:             26d5adb73f80\nCompile date:             2023-06-28T10:43:47\nCompile location:         /w/workspace/vpp-merge-2306-ubuntu2004-x86_64\nCompiler:                 Clang/LLVM 10.0.0 \nCurrent PID:              36964\n" time="2023-08-14 11:31:20.17800" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="vpp# show version cmdline:\nCommand line arguments:    /usr/bin/vpp  unix    {    nodaemon    cli-listen    /run/vpp/cli.sock    cli-no-pager    full-coredump    }  plugins    {    plugin    dpdk_plugin.so      {      disable      }    }  socksvr    {    socket-name    /run/vpp/api.sock    }  statseg    {    socket-name    /run/vpp/stats.sock    per-node-counters    on    }" time="2023-08-14 11:31:20.17831" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] ClientID=0 PID=36964 level=info logger=govpp msg="VPP version: 23.06-rc2~2-g493b8990d~b17" time="2023-08-14 11:31:20.17860" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:31:20.17870" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="VPP loaded 73 plugins" time="2023-08-14 11:31:20.17907" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:31:20.17911" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:31:20.17914" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:31:20.17916" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: af_packet - af-packet" time="2023-08-14 11:31:20.17918" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:31:20.17919" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:31:20.17924" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:31:20.17925" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:31:20.17927" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:31:20.17928" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:31:20.17934" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:31:20.17935" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:31:20.17936" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:31:20.17940" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:31:20.17942" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:31:20.17944" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:31:20.17948" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:31:20.17949" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:31:20.17951" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:31:20.17952" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: dma_intel - Intel DSA Backend" time="2023-08-14 11:31:20.17954" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:31:20.17956" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:31:20.17960" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:31:20.17961" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:31:20.17963" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: gre - Generic Routing Encapsulation (GRE) plugin" time="2023-08-14 11:31:20.17965" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:31:20.17968" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:31:20.17970" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:31:20.17972" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: http - Hypertext Transfer Protocol (HTTP)" time="2023-08-14 11:31:20.17973" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:31:20.17975" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:31:20.17978" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:31:20.17980" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:31:20.17981" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:31:20.17982" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: ip_session_redirect - IP session redirect" time="2023-08-14 11:31:20.17986" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:31:20.17987" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:31:20.17988" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:31:20.17991" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:31:20.17994" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:31:20.17995" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:31:20.17996" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:31:20.17999" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:31:20.18001" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:31:20.18003" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:31:20.18005" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:31:20.18006" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:31:20.18008" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:31:20.18009" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:31:20.18012" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:31:20.18013" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:31:20.18014" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:31:20.18016" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:31:20.18017" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:31:20.18021" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:31:20.18022" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: prom - Prometheus Stats Exporter" time="2023-08-14 11:31:20.18024" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:31:20.18027" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:20.18029" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:20.18030" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:20.18033" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:20.18035" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:31:20.18036" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:31:20.18038" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:31:20.18039" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:31:20.18042" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:31:20.18043" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:31:20.18045" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:31:20.18047" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: vhost - Vhost-User" time="2023-08-14 11:31:20.18050" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:31:20.18051" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:31:20.18053" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: vxlan - VxLan Tunnels" time="2023-08-14 11:31:20.18055" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:31:20.18057" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:31:20.18315" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:31:20.18369" loc="govppmux/plugin_impl_govppmux.go:175"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:31:20.18396" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:31:20.18427" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:31:20.18451" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:31:20.18480" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:31:20.18510" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:31:20.18534" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:31:20.18564" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:31:20.18588" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:31:20.18611" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:31:20.18759" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:20.18789" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:31:20.18825" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:31:20.18852" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:31:20.18880" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:31:20.18904" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:31:20.18929" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:31:20.18954" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=kafka msg="kafka config not found  - skip loading this plugin" time="2023-08-14 11:31:20.18979" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:31:20.19010" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:31:20.19036" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin msg="config  not found" time="2023-08-14 11:31:20.19061" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:31:20.19069" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:31:20.19092" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:20.19100" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:20.19123" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:20.19162" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:20.19185" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-nsplugin.ms-descriptor msg="detected container as microservice: Name=/e2e-test-vppagent-agent0 ID=d6496fd661c04e5c161aed74f756980415f9f8c6c200ba5a0f72dda13d79a31b Created=2023-08-14 11:31:19.736237544 +0000 UTC State.StartedAt=2023-08-14 11:31:19.97266654 +0000 UTC" time="2023-08-14 11:31:20.19208" loc="descriptor/microservice.go:176"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] id=d6496fd661c04e5c161aed74f756980415f9f8c6c200ba5a0f72dda13d79a31b label=agent0 level=debug logger=linux-nsplugin.ms-descriptor msg="Discovered new microservice" pid=36925 time="2023-08-14 11:31:20.19217" loc="descriptor/microservice.go:202"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:31:20.19210" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:31:20.19293" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:31:20.19312" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:31:20.19316" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:20.19353" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:31:20.19374" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler acl using preferred version: 23.06" time="2023-08-14 11:31:20.19377" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler acl: 23.06" time="2023-08-14 11:31:20.19413" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:31:20.19439" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler abf using preferred version: 23.06" time="2023-08-14 11:31:20.19442" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler abf: 23.06" time="2023-08-14 11:31:20.19464" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:31:20.19507" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler dns using preferred version: 23.06" time="2023-08-14 11:31:20.19509" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler dns: 23.06" time="2023-08-14 11:31:20.19528" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:31:20.19570" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler ipfix using preferred version: 23.06" time="2023-08-14 11:31:20.19574" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler ipfix: 23.06" time="2023-08-14 11:31:20.19592" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:31:20.19625" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler ipsec using preferred version: 23.06" time="2023-08-14 11:31:20.19629" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler ipsec: 23.06" time="2023-08-14 11:31:20.19636" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:31:20.19664" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:20.19666" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:20.19672" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:20.19697" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:20.19702" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:31:20.19730" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:20.19733" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:20.19739" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:20.19765" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:20.19768" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:31:20.19800" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler nat using preferred version: 23.06" time="2023-08-14 11:31:20.19804" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler nat: 23.06" time="2023-08-14 11:31:20.19806" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:31:20.19838" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler punt using preferred version: 23.06" time="2023-08-14 11:31:20.19841" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler punt: 23.06" time="2023-08-14 11:31:20.19846" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:31:20.19871" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler stn using preferred version: 23.06" time="2023-08-14 11:31:20.19873" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler stn: 23.06" time="2023-08-14 11:31:20.19879" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:31:20.19903" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler srv6 using preferred version: 23.06" time="2023-08-14 11:31:20.19905" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler srv6: 23.06" time="2023-08-14 11:31:20.19911" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:31:20.19939" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler wireguard using preferred version: 23.06" time="2023-08-14 11:31:20.19942" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler wireguard: 23.06" time="2023-08-14 11:31:20.19947" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:31:20.19974" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:31:20.19984" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:20.20005" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:31:20.20014" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:31:20.20043" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:20.20048" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:31:20.20090" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:31:20.20120" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:31:20.20146" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:31:20.20149" loc="orchestrator/orchestrator.go:92"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin.dhcp-descriptor msg="Started watcher on DHCP notifications" time="2023-08-14 11:31:20.20219" loc="descriptor/dhcp.go:219"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin msg="start watching DHCP leases" time="2023-08-14 11:31:20.20243" loc="vpp2306/watch_vppcalls.go:128"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin msg="Start watching interface state events" time="2023-08-14 11:31:20.20249" loc="ifplugin/publish_state.go:18"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:31:20.20186" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:31:20.20295" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:31:20.20299" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:31:20.20301" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:20.20303" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:31:20.20305" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:20.20308" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:20.20310" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:20.20312" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:20.20314" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:20.20315" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:20.20317" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler ipsec using preferred version: 23.06" time="2023-08-14 11:31:20.20319" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler ipsec: 23.06" time="2023-08-14 11:31:20.20320" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler abf using preferred version: 23.06" time="2023-08-14 11:31:20.20322" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler abf: 23.06" time="2023-08-14 11:31:20.20323" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler acl using preferred version: 23.06" time="2023-08-14 11:31:20.20325" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler acl: 23.06" time="2023-08-14 11:31:20.20326" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler nat using preferred version: 23.06" time="2023-08-14 11:31:20.20328" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler nat: 23.06" time="2023-08-14 11:31:20.20329" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler punt using preferred version: 23.06" time="2023-08-14 11:31:20.20332" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler punt: 23.06" time="2023-08-14 11:31:20.20334" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler wireguard using preferred version: 23.06" time="2023-08-14 11:31:20.20335" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler wireguard: 23.06" time="2023-08-14 11:31:20.20337" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:31:20.20339" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler core using preferred version: 23.06" time="2023-08-14 11:31:20.20341" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler telemetry using preferred version: 23.06" time="2023-08-14 11:31:20.20343" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler telemetry: 23.06" time="2023-08-14 11:31:20.20344" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:20.20345" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:20.20347" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:20.20349" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:20.20350" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:20.20351" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:20.20353" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler ipsec using preferred version: 23.06" time="2023-08-14 11:31:20.20354" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler ipsec: 23.06" time="2023-08-14 11:31:20.20356" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler abf using preferred version: 23.06" time="2023-08-14 11:31:20.20357" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler abf: 23.06" time="2023-08-14 11:31:20.20359" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler acl using preferred version: 23.06" time="2023-08-14 11:31:20.20360" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler acl: 23.06" time="2023-08-14 11:31:20.20361" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler nat using preferred version: 23.06" time="2023-08-14 11:31:20.20363" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler nat: 23.06" time="2023-08-14 11:31:20.20364" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler punt using preferred version: 23.06" time="2023-08-14 11:31:20.20366" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler punt: 23.06" time="2023-08-14 11:31:20.20367" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler wireguard using preferred version: 23.06" time="2023-08-14 11:31:20.20473" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler wireguard: 23.06" time="2023-08-14 11:31:20.20476" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:31:20.20481" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:31:20.20483" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:31:20.20485" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:31:20.20513" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:31:20.20517" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:31:20.20536" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="VPP handler telemetry using preferred version: 23.06" time="2023-08-14 11:31:20.20581" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=global msg="found compatible version for handler telemetry: 23.06" time="2023-08-14 11:31:20.20584" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:31:20.20588" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:31:20.20593" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:31:20.20617" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:31:20.20622" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:31:20.20624" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:31:20.20659" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:31:20.20663" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:31:20.20666" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:31:20.20696" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:31:20.20700" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:31:20.20705" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:31:20.20706" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:31:20.20740" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:20.20745" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:31:20.20748" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:31:20.20750" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:20.20751" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:31:20.20785" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:31:20.20787" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:20.20788" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:31:20.20793" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:31:20.20795" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:31:20.20807" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:31:20.20821" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:31:20.20826" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:31:20.20828" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:31:20.20829" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:31:20.20853" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:31:20.20856" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:31:20.20859" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:31:20.20861" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:31:20.20863" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:31:20.20864" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:31:20.20902" loc="vpp2306/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:31:20.20935" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:31:20.20938" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:31:20.20939" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" time="2023-08-14 11:31:20.20943" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/d6496fd661c0] level=debug logger=status-check msg="Plugin vpp-abfplugin: status check probe registered" tim

Check failure on line 322 in tests/e2e/e2etest/e2e.go

See this annotation in the file changed.

@github-actions github-actions / JUnit Test Report

e2e.TestNATPools/CRUD_for_unnamed_pool 

Failed
Raw output 
=== RUN   TestNATPools/CRUD_for_unnamed_pool
level=debug logger=global msg="Environ:\nGOTESTSUM_FORMAT=testname\nHOSTNAME=d4bd68bd8dfb\nRUNNER_DEBUG=1\nPWD=/\nHOME=/root\nVPP_AGENT=prod_vpp_agent\nSHLVL=0\nTESTDATA_DIR=/home/runner/work/vpp-agent/vpp-agent/tests/e2e/e2etest/resources\nGOTESTSUM_JUNITFILE=/testreport/e2e.xml\nPATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\nGITHUB_WORKFLOW=Test\nTEST_SUITE=e2e" time="2023-08-14 11:31:26.48938"
    e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc00016aac0 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:31:26.51635"
    containerruntime.go:213: started Agent agent0 container 4d84d73f33a89a63ca2bae18e77f0d9048dd854031b1dc0a441b5fb94e84a11b
cid=4d84d73f33a8 container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:31:26.76492"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:31:26.75393" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:31:26.75446" loc="agent/agent.go:179"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:26.76510"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:26.76582"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8]                                        __
[container::/e2e-test-vppagent-agent0/4d84d73f33a8]   _  _____  ___ _______ ____ ____ ___ / /_  
[container::/e2e-test-vppagent-agent0/4d84d73f33a8]  | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/  vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/4d84d73f33a8]  |___/ .__/ .__/   \_'_/\_' /\__/_//_\__/   Mon Aug 14 11:12:22 UTC 2023 (19 minutes ago)
[container::/e2e-test-vppagent-agent0/4d84d73f33a8]     /_/  /_/           /___/                root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] 
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] BuildDate="Mon Aug 14 11:12:22 UTC 2023" CommitHash=f4fe5e7@master level=info logger=agent msg="Starting agent version: f4fe5e7" time="2023-08-14 11:31:26.79496" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:31:26.79536" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:31:26.79567" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:31:26.79587" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:31:26.79608" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:31:26.79641" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:31:26.79663" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:31:26.79682" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:31:26.79701" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:31:26.79723" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:31:26.79745" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:31:26.79767" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:31:26.79788" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:31:26.79810" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:31:26.79833" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:31:26.79853" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:31:26.79877" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:31:26.79898" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:31:26.79919" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:31:26.79940" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:31:26.79964" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:31:26.79983" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:31:26.80006" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:31:26.80025" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:31:26.80047" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:31:26.80067" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:31:26.80089" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp 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}" time="2023-08-14 11:31:26.80111" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:31:26.80134" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: acl        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80153" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: interface  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80174" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: nat        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80194" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: punt       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80215" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: dns        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80234" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: wireguard  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80255" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: core       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80274" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: abf        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80295" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: ipsec      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80316" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: l3         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80337" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: ipfix      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80356" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: stn        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80377" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: l2         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80396" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: telemetry  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80415" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="- handler: srv6       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:26.80434" loc="govppmux/plugin_impl_govppmux.go:120"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:26.86669"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:26.86694"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] vpp[37130]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] vpp[37130]: vat-plug/load: vat_plugin_register: nsh plugin not loaded...
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] vpp[37130]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="first connection to VPP established (took 140ms)" time="2023-08-14 11:31:26.94499" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:31:26.94546" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="binapi version 22.10 partly incompatible: (19/355 messages)" time="2023-08-14 11:31:26.94614" loc="binapi/binapi.go:58"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="binapi version 23.06 fully COMPATIBLE (867 messages)" time="2023-08-14 11:31:26.94690" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:31:26.94750" loc="govppmux/plugin_impl_govppmux.go:135"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="connection to VPP established (took 4ms)" time="2023-08-14 11:31:26.95102" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:31:26.95165" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="binapi version 22.10 partly incompatible: (19/355 messages)" time="2023-08-14 11:31:26.95282" loc="binapi/binapi.go:58"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="binapi version 23.06 fully COMPATIBLE (867 messages)" time="2023-08-14 11:31:26.95359" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler core using preferred version: 23.06" time="2023-08-14 11:31:26.95362" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="vpp# show version verbose\nVersion:                  v23.06-rc2~2-g493b8990d~b17\nCompiled by:              root\nCompile host:             26d5adb73f80\nCompile date:             2023-06-28T10:43:47\nCompile location:         /w/workspace/vpp-merge-2306-ubuntu2004-x86_64\nCompiler:                 Clang/LLVM 10.0.0 \nCurrent PID:              37130\n" time="2023-08-14 11:31:26.95403" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="vpp# show version cmdline:\nCommand line arguments:    /usr/bin/vpp  unix    {    nodaemon    cli-listen    /run/vpp/cli.sock    cli-no-pager    full-coredump    }  plugins    {    plugin    dpdk_plugin.so      {      disable      }    }  socksvr    {    socket-name    /run/vpp/api.sock    }  statseg    {    socket-name    /run/vpp/stats.sock    per-node-counters    on    }" time="2023-08-14 11:31:26.95415" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] ClientID=0 PID=37130 level=info logger=govpp msg="VPP version: 23.06-rc2~2-g493b8990d~b17" time="2023-08-14 11:31:26.95436" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:31:26.95444" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="VPP loaded 73 plugins" time="2023-08-14 11:31:26.95485" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:31:26.95489" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:31:26.95493" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:31:26.95494" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: af_packet - af-packet" time="2023-08-14 11:31:26.95496" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:31:26.95497" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:31:26.95500" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:31:26.95504" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:31:26.95507" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:31:26.95509" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:31:26.95510" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:31:26.95512" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:31:26.95513" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:31:26.95516" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:31:26.95517" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:31:26.95519" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:31:26.95520" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:31:26.95522" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:31:26.95523" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:31:26.95532" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: dma_intel - Intel DSA Backend" time="2023-08-14 11:31:26.95534" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:31:26.95536" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:31:26.95538" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:31:26.95541" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:31:26.95542" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: gre - Generic Routing Encapsulation (GRE) plugin" time="2023-08-14 11:31:26.95544" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:31:26.95545" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:31:26.95546" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:31:26.95548" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: http - Hypertext Transfer Protocol (HTTP)" time="2023-08-14 11:31:26.95549" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:31:26.95556" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:31:26.95557" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:31:26.95559" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:31:26.95560" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:31:26.95562" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: ip_session_redirect - IP session redirect" time="2023-08-14 11:31:26.95571" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:31:26.95573" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:31:26.95574" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:31:26.95576" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:31:26.95578" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:31:26.95580" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:31:26.95581" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:31:26.95582" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:31:26.95584" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:31:26.95585" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:31:26.95587" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:31:26.95589" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:31:26.95590" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:31:26.95601" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:31:26.95606" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:31:26.95612" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:31:26.95614" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:31:26.95615" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:31:26.95616" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:31:26.95619" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:31:26.95620" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: prom - Prometheus Stats Exporter" time="2023-08-14 11:31:26.95622" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:31:26.95623" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:26.95624" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:26.95626" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:26.95627" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:26.95628" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:31:26.95630" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:31:26.95643" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:31:26.95645" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:31:26.95647" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:31:26.95649" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:31:26.95650" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:31:26.95652" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: vhost - Vhost-User" time="2023-08-14 11:31:26.95654" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:31:26.95656" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:31:26.95657" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: vxlan - VxLan Tunnels" time="2023-08-14 11:31:26.95658" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:31:26.95660" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:31:26.95868" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:31:26.95942" loc="govppmux/plugin_impl_govppmux.go:175"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:31:26.95969" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:31:26.96007" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:31:26.96034" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:31:26.96063" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:31:26.96095" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:31:26.96122" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:31:26.96156" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:31:26.96181" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:31:26.96419" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:31:26.96451" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:26.96476" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:31:26.96511" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:31:26.96536" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:31:26.96561" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:31:26.96582" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:26.96754"
level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:31:26.96607" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:31:26.96737" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=kafka msg="kafka config not found  - skip loading this plugin" time="2023-08-14 11:31:26.96754" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:31:26.96768" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:31:26.96774" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin msg="config  not found" time="2023-08-14 11:31:26.96783" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:31:26.96796" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:31:26.96808" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:26.96812" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:26.96818" loc="vpp/handlers.go:73"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:26.96877"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:26.96838" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:26.96888" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:31:26.96896" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:31:26.96903" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:31:26.96908" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:31:26.96916" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:26.96933" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:31:26.96940" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler acl using preferred version: 23.06" time="2023-08-14 11:31:26.96954" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler acl: 23.06" time="2023-08-14 11:31:26.96963" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:31:26.96980" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler abf using preferred version: 23.06" time="2023-08-14 11:31:26.97051" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler abf: 23.06" time="2023-08-14 11:31:26.97059" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:31:26.97070" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler dns using preferred version: 23.06" time="2023-08-14 11:31:26.97076" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler dns: 23.06" time="2023-08-14 11:31:26.97081" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:31:26.97090" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler ipfix using preferred version: 23.06" time="2023-08-14 11:31:26.97096" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler ipfix: 23.06" time="2023-08-14 11:31:26.97102" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:31:26.97121" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler ipsec using preferred version: 23.06" time="2023-08-14 11:31:26.97130" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler ipsec: 23.06" time="2023-08-14 11:31:26.97141" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:31:26.97152" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:26.97158" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:26.97163" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:26.97172" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:26.97188" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:31:26.97197" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:26.97210" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:26.97216" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:26.97226" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:26.97240" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:31:26.97269" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler nat using preferred version: 23.06" time="2023-08-14 11:31:26.97288" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler nat: 23.06" time="2023-08-14 11:31:26.97296" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:31:26.97320" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler punt using preferred version: 23.06" time="2023-08-14 11:31:26.97334" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler punt: 23.06" time="2023-08-14 11:31:26.97347" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:31:26.97360" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler stn using preferred version: 23.06" time="2023-08-14 11:31:26.97376" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler stn: 23.06" time="2023-08-14 11:31:26.97382" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:31:26.97396" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler srv6 using preferred version: 23.06" time="2023-08-14 11:31:26.97409" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler srv6: 23.06" time="2023-08-14 11:31:26.97415" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:31:26.97426" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler wireguard using preferred version: 23.06" time="2023-08-14 11:31:26.97439" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler wireguard: 23.06" time="2023-08-14 11:31:26.97447" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:31:26.97456" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:31:26.97464" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:26.97471" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:31:26.97479" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:31:26.97486" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:26.97493" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:31:26.97508" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:31:26.97526" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:31:26.97537" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:31:26.97544" loc="orchestrator/orchestrator.go:92"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin msg="Start watching interface state events" time="2023-08-14 11:31:26.97554" loc="ifplugin/publish_state.go:18"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=linux-nsplugin.ms-descriptor msg="detected container as microservice: Name=/e2e-test-vppagent-agent0 ID=4d84d73f33a89a63ca2bae18e77f0d9048dd854031b1dc0a441b5fb94e84a11b Created=2023-08-14 11:31:26.494438538 +0000 UTC State.StartedAt=2023-08-14 11:31:26.751100356 +0000 UTC" time="2023-08-14 11:31:26.97005" loc="descriptor/microservice.go:176"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] id=4d84d73f33a89a63ca2bae18e77f0d9048dd854031b1dc0a441b5fb94e84a11b label=agent0 level=debug logger=linux-nsplugin.ms-descriptor msg="Discovered new microservice" pid=37091 time="2023-08-14 11:31:26.97566" loc="descriptor/microservice.go:202"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin.dhcp-descriptor msg="Started watcher on DHCP notifications" time="2023-08-14 11:31:26.97008" loc="descriptor/dhcp.go:219"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin msg="start watching DHCP leases" time="2023-08-14 11:31:26.97588" loc="vpp2306/watch_vppcalls.go:128"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:31:26.97551" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:31:26.97603" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:31:26.97611" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:31:26.97617" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:26.97622" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:31:26.97629" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:26.97637" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:26.97643" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:26.97650" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:26.97656" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:26.97661" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:26.97668" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler ipsec using preferred version: 23.06" time="2023-08-14 11:31:26.97674" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler ipsec: 23.06" time="2023-08-14 11:31:26.97681" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler abf using preferred version: 23.06" time="2023-08-14 11:31:26.97686" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler abf: 23.06" time="2023-08-14 11:31:26.97693" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler acl using preferred version: 23.06" time="2023-08-14 11:31:26.97698" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler acl: 23.06" time="2023-08-14 11:31:26.97705" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler nat using preferred version: 23.06" time="2023-08-14 11:31:26.97710" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler nat: 23.06" time="2023-08-14 11:31:26.97718" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler punt using preferred version: 23.06" time="2023-08-14 11:31:26.97723" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler punt: 23.06" time="2023-08-14 11:31:26.97730" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler wireguard using preferred version: 23.06" time="2023-08-14 11:31:26.97735" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler wireguard: 23.06" time="2023-08-14 11:31:26.97742" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:31:26.97750" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler core using preferred version: 23.06" time="2023-08-14 11:31:26.97756" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler telemetry using preferred version: 23.06" time="2023-08-14 11:31:26.97761" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler telemetry: 23.06" time="2023-08-14 11:31:26.97768" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler interface using preferred version: 23.06" time="2023-08-14 11:31:26.97773" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler interface: 23.06" time="2023-08-14 11:31:26.97780" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l2 using preferred version: 23.06" time="2023-08-14 11:31:26.97785" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l2: 23.06" time="2023-08-14 11:31:26.97792" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler l3 using preferred version: 23.06" time="2023-08-14 11:31:26.97797" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler l3: 23.06" time="2023-08-14 11:31:26.97804" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler ipsec using preferred version: 23.06" time="2023-08-14 11:31:26.97809" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler ipsec: 23.06" time="2023-08-14 11:31:26.97817" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler abf using preferred version: 23.06" time="2023-08-14 11:31:26.97821" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler abf: 23.06" time="2023-08-14 11:31:26.97828" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler acl using preferred version: 23.06" time="2023-08-14 11:31:26.97834" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler acl: 23.06" time="2023-08-14 11:31:26.97841" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler nat using preferred version: 23.06" time="2023-08-14 11:31:26.97846" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler nat: 23.06" time="2023-08-14 11:31:26.97862" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler punt using preferred version: 23.06" time="2023-08-14 11:31:26.97875" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler punt: 23.06" time="2023-08-14 11:31:26.97879" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler wireguard using preferred version: 23.06" time="2023-08-14 11:31:26.97887" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler wireguard: 23.06" time="2023-08-14 11:31:26.97891" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:31:26.97901" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:31:26.97908" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:31:26.97914" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:31:26.97922" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:31:26.97929" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:31:26.97935" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="VPP handler telemetry using preferred version: 23.06" time="2023-08-14 11:31:26.97957" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=global msg="found compatible version for handler telemetry: 23.06" time="2023-08-14 11:31:26.97963" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:31:26.97968" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:31:26.97979" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:31:26.97986" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:31:26.97993" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:31:26.98000" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:31:26.98020" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:31:26.98027" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:31:26.98032" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:31:26.98043" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:31:26.98050" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:31:26.98057" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:31:26.98063" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:31:26.98070" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:26.98076" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:31:26.98084" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:31:26.98090" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:26.98094" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:31:26.98102" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:31:26.98106" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:26.98114" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:31:26.98120" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:31:26.98127" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:31:26.98133" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:31:26.98141" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:31:26.98149" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:31:26.98155" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:31:26.98162" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:31:26.98169" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:31:26.98175" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:31:26.98188" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:31:26.98202" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:31:26.98215" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:31:26.98225" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:31:26.98241" loc="vpp2306/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:31:26.98260" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:31:26.98273" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:31:26.98284" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/4d84d73f33a8] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" t

Check failure on line 1 in tests/e2e/e2etest/e2e.go

See this annotation in the file changed.

@github-actions github-actions / JUnit Test Report

e2e.TestNATPools 

Failed
Raw output 
=== RUN   TestNATPools
--- FAIL: TestNATPools (13.54s)
View more details on GitHub Actions