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

This check has been archived and is scheduled for deletion. Learn more about checks retention
GitHub Actions / JUnit Test Report failed Aug 14, 2023 in 0s

101 tests run, 89 passed, 8 skipped, 4 failed.

Annotations

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

See this annotation in the file changed.

@github-actions github-actions / JUnit Test Report

e2e.TestInterfaceConnAfPacket 

Failed
Raw output 
=== RUN   TestInterfaceConnAfPacket
level=debug logger=global msg="Environ:\nGOTESTSUM_FORMAT=testname\nHOSTNAME=7537f2e6158e\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:21:19.03035"
    e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc000512580 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:21:19.04517"
    containerruntime.go:213: started Agent agent0 container e90dddcb39191de04ea8a85cf6dcaafed866e2d02b4a9163a5db4c1825b11fe2
cid=e90dddcb3919 container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:21:19.26606"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:21:19.26619"
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:21:19.26651"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:21:19.26686" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:21:19.26745" loc="agent/agent.go:179"
[container::/e2e-test-vppagent-agent0/e90dddcb3919]                                        __
[container::/e2e-test-vppagent-agent0/e90dddcb3919]   _  _____  ___ _______ ____ ____ ___ / /_  
[container::/e2e-test-vppagent-agent0/e90dddcb3919]  | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/  vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/e90dddcb3919]  |___/ .__/ .__/   \_'_/\_' /\__/_//_\__/   Mon Aug 14 11:12:22 UTC 2023 (9 minutes ago)
[container::/e2e-test-vppagent-agent0/e90dddcb3919]     /_/  /_/           /___/                root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/e90dddcb3919] 
[container::/e2e-test-vppagent-agent0/e90dddcb3919] 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:21:19.30866" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:21:19.30898" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:21:19.30929" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:21:19.30946" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:21:19.30965" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:21:19.30989" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:21:19.31006" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:21:19.31024" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:21:19.31041" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:21:19.31058" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:21:19.31074" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:21:19.31100" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:21:19.31115" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:21:19.31133" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:21:19.31150" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:21:19.31166" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:21:19.31185" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:21:19.31202" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:21:19.31216" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:21:19.31233" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:21:19.31248" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:21:19.31263" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:21:19.31277" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:21:19.31294" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:21:19.31306" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:21:19.31321" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:21:19.31335" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] 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:21:19.31354" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:21:19.31369" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: core       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31383" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: interface  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31398" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: l2         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31412" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: l3         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31425" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: ipfix      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31439" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: srv6       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31452" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: stn        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31465" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: ipsec      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31480" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: nat        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31498" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: wireguard  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31512" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: abf        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31525" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: acl        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31537" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: punt       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31550" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: telemetry  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31563" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="- handler: dns        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:21:19.31576" 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:21:19.36703"
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:21:19.36731"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] vpp[29850]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/e90dddcb3919] vpp[29850]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="first connection to VPP established (took 147ms)" time="2023-08-14 11:21:19.46249" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:21:19.46357" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="binapi version 22.10 fully COMPATIBLE (856 messages)" time="2023-08-14 11:21:19.46451" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:21:19.46630" loc="govppmux/plugin_impl_govppmux.go:135"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:21:19.46757"
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:21:19.46788"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="connection to VPP established (took 5ms)" time="2023-08-14 11:21:19.47090" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:21:19.47156" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="binapi version 22.10 fully COMPATIBLE (856 messages)" time="2023-08-14 11:21:19.47244" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler core using preferred version: 22.10" time="2023-08-14 11:21:19.47248" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="vpp# show version verbose\nVersion:                  v22.10.1-1~g1b93285ce~b25\nCompiled by:              root\nCompile host:             49234abbf517\nCompile date:             2023-04-03T07:53:07\nCompile location:         /w/workspace/vpp-merge-2210-ubuntu2004-x86_64\nCompiler:                 Clang/LLVM 10.0.0 \nCurrent PID:              29850\n" time="2023-08-14 11:21:19.47297" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] 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:21:19.47324" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] ClientID=0 PID=29850 level=info logger=govpp msg="VPP version: 22.10.1-1~g1b93285ce~b25" time="2023-08-14 11:21:19.47365" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:21:19.47391" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="VPP loaded 69 plugins" time="2023-08-14 11:21:19.47438" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:21:19.47442" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:21:19.47475" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:21:19.47479" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:21:19.47504" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:21:19.47510" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:21:19.47512" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:21:19.47541" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:21:19.47550" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:21:19.47574" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:21:19.47580" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:21:19.47604" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:21:19.47620" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:21:19.47638" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:21:19.47651" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:21:19.47673" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:21:19.47675" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:21:19.47694" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:21:19.47699" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: dma_intel - Intel DSA Backend" time="2023-08-14 11:21:19.47725" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:21:19.47739" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:21:19.47753" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:21:19.47758" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:21:19.47759" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:21:19.47769" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:21:19.47782" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:21:19.47801" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: http - Hypertext Transfer Protocol (HTTP)" time="2023-08-14 11:21:19.47803" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:21:19.47805" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:21:19.47806" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:21:19.47847" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:21:19.47868" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:21:19.47883" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:21:19.47891" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:21:19.47909" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:21:19.47917" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:21:19.47945" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:21:19.47961" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:21:19.47963" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:21:19.47979" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:21:19.47997" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:21:19.48001" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:21:19.48029" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:21:19.48033" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:21:19.48060" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:21:19.48064" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:21:19.48089" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:21:19.48092" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: nsh - Network Service Header (NSH)" time="2023-08-14 11:21:19.48094" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:21:19.48121" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:21:19.48124" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:21:19.48126" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:21:19.48151" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:21:19.48154" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: prom - Prometheus Stats Exporter" time="2023-08-14 11:21:19.48155" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:21:19.48183" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:21:19.48186" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:21:19.48202" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:21:19.48217" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:21:19.48230" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:21:19.48247" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:21:19.48249" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:21:19.48252" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:21:19.48280" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:21:19.48282" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:21:19.48308" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:21:19.48311" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:21:19.48336" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:21:19.48351" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:21:19.48367" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:21:19.48698" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:21:19.48773" loc="govppmux/plugin_impl_govppmux.go:175"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:21:19.48802" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:21:19.48831" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:21:19.48858" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:21:19.48890" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:21:19.48928" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:21:19.48957" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:21:19.48995" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:21:19.49018" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:21:19.49043" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:21:19.49068" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:21:19.49094" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:21:19.49140" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:21:19.49169" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:21:19.49197" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:21:19.49233" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:21:19.49481" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:21:19.49488" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=kafka msg="kafka config not found  - skip loading this plugin" time="2023-08-14 11:21:19.49496" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:21:19.49523" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:21:19.49526" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin msg="config  not found" time="2023-08-14 11:21:19.49532" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:21:19.49536" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:21:19.49584" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:21:19.49598" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:21:19.49618" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:21:19.49659" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:21:19.49667" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:21:19.49675" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:21:19.49685" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:21:19.49699" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:21:19.49715" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:21:19.49727" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:21:19.49743" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:21:19.49757" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:21:19.49772" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:21:19.49794" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:21:19.49805" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:21:19.49821" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:21:19.49842" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler dns using preferred version: 22.10" time="2023-08-14 11:21:19.49853" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler dns: 22.10" time="2023-08-14 11:21:19.49907" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:21:19.49928" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler ipfix using preferred version: 22.10" time="2023-08-14 11:21:19.49936" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler ipfix: 22.10" time="2023-08-14 11:21:19.49943" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:21:19.49961" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:21:19.49980" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:21:19.49998" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:21:19.50043" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:21:19.50056" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:21:19.50081" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:21:19.50114" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:21:19.50145" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:21:19.50178" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:21:19.50186" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:21:19.50215" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:21:19.50247" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:21:19.50252" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:21:19.50299" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:21:19.50303" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:21:19.50336" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:21:19.50370" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:21:19.50376" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:21:19.50379" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:21:19.50420" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler stn using preferred version: 22.10" time="2023-08-14 11:21:19.50426" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler stn: 22.10" time="2023-08-14 11:21:19.50429" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:21:19.50471" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler srv6 using preferred version: 22.10" time="2023-08-14 11:21:19.50491" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler srv6: 22.10" time="2023-08-14 11:21:19.50511" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:21:19.50709" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:21:19.50742" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:21:19.50767" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:21:19.50802" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:21:19.50835" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:21:19.50973" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:21:19.51411" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:21:19.51439" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:21:19.51466" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:21:19.51505" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:21:19.51574" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:21:19.51595" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:21:19.51606" loc="orchestrator/orchestrator.go:92"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:21:19.51623" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:21:19.51638" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:21:19.51677" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:21:19.51697" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:21:19.51701" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:21:19.51734" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:21:19.51744" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:21:19.51766" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:21:19.51983" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:21:19.51992" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:21:19.52006" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:21:19.52012" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:21:19.52024" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:21:19.52047" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:21:19.52071" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:21:19.52081" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:21:19.52091" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:21:19.52114" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:21:19.52126" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:21:19.52133" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:21:19.52141" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:21:19.52171" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:21:19.52183" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:21:19.52197" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:21:19.52214" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler core using preferred version: 22.10" time="2023-08-14 11:21:19.52222" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.10" time="2023-08-14 11:21:19.52241" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler telemetry: 22.10" time="2023-08-14 11:21:19.52244" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:21:19.52283" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:21:19.52286" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:21:19.52293" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:21:19.52300" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:21:19.52315" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:21:19.52328" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:21:19.52332" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:21:19.52344" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin.dhcp-descriptor msg="Started watcher on DHCP notifications" time="2023-08-14 11:21:19.49896" loc="descriptor/dhcp.go:219"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin msg="Start watching interface state events" time="2023-08-14 11:21:19.49902" loc="ifplugin/publish_state.go:18"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin msg="start watching DHCP leases" time="2023-08-14 11:21:19.52500" loc="vpp2210/watch_vppcalls.go:128"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=linux-nsplugin.ms-descriptor msg="detected container as microservice: Name=/e2e-test-vppagent-agent0 ID=e90dddcb39191de04ea8a85cf6dcaafed866e2d02b4a9163a5db4c1825b11fe2 Created=2023-08-14 11:21:19.032367779 +0000 UTC State.StartedAt=2023-08-14 11:21:19.262642953 +0000 UTC" time="2023-08-14 11:21:19.51245" loc="descriptor/microservice.go:176"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] id=e90dddcb39191de04ea8a85cf6dcaafed866e2d02b4a9163a5db4c1825b11fe2 label=agent0 level=debug logger=linux-nsplugin.ms-descriptor msg="Discovered new microservice" pid=29812 time="2023-08-14 11:21:19.52515" loc="descriptor/microservice.go:202"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:21:19.52551" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:21:19.52554" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:21:19.52559" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:21:19.52561" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:21:19.52563" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:21:19.52566" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:21:19.52569" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:21:19.52572" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:21:19.52575" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:21:19.52577" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:21:19.52592" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:21:19.52603" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:21:19.52605" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:21:19.52619" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:21:19.52641" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:21:19.52674" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.10" time="2023-08-14 11:21:19.52720" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=global msg="found compatible version for handler telemetry: 22.10" time="2023-08-14 11:21:19.52722" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:21:19.52765" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:21:19.52778" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:21:19.52781" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:21:19.52788" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:21:19.52793" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:21:19.52822" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:21:19.52826" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:21:19.52830" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:21:19.52840" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:21:19.52847" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:21:19.52850" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:21:19.52858" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:21:19.52861" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:21:19.52866" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:21:19.52869" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:21:19.52874" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:21:19.52877" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:21:19.52881" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:21:19.52883" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:21:19.52886" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:21:19.52890" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:21:19.52895" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:21:19.52897" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:21:19.52899" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:21:19.52906" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:21:19.52910" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:21:19.52912" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:21:19.52915" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:21:19.52919" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:21:19.52920" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:21:19.52923" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:21:19.52927" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:21:19.52930" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:21:19.52962" loc="vpp2210/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:21:19.53001" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:21:19.53003" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:21:19.53007" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" time="2023-08-14 11:21:19.53009" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin vpp-abfplugin: status check probe registered" time="2023-08-14 11:21:19.53011" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): vpp-dnsplugin" time="2023-08-14 11:21:19.53016" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin vpp-dnsplugin: status check probe registered" time="2023-08-14 11:21:19.53019" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-- AfterInit(): vpp-ipfixplugin (not used)" time="2023-08-14 11:21:19.53025" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-> AfterInit(): vpp-ipsec-plugin" time="2023-08-14 11:21:19.53027" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=status-check msg="Plugin vpp-ipsec-plugin: status check probe registered" time="2023-08-14 11:21:19.53028" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/e90dddcb3919] level=debug logger=agent msg="-

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=7537f2e6158e\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:30:42.19145"
    e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc0002acc40 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:30:42.20281"
    containerruntime.go:213: started Agent agent0 container 26389563a72b0084b28a7593a719c1c43079bfedd9dd1436a4dba37827a8827b
cid=26389563a72b container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:30:42.43809"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:30:42.43820"
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:30:42.43847"
[container::/e2e-test-vppagent-agent0/26389563a72b] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:30:42.43894" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:30:42.43951" loc="agent/agent.go:179"
[container::/e2e-test-vppagent-agent0/26389563a72b]                                        __
[container::/e2e-test-vppagent-agent0/26389563a72b]   _  _____  ___ _______ ____ ____ ___ / /_  
[container::/e2e-test-vppagent-agent0/26389563a72b]  | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/  vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/26389563a72b]  |___/ .__/ .__/   \_'_/\_' /\__/_//_\__/   Mon Aug 14 11:12:22 UTC 2023 (18 minutes ago)
[container::/e2e-test-vppagent-agent0/26389563a72b]     /_/  /_/           /___/                root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/26389563a72b] 
[container::/e2e-test-vppagent-agent0/26389563a72b] 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:30:42.46321" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:30:42.46397" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:30:42.46410" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:30:42.46413" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:30:42.46417" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:30:42.46426" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:30:42.46430" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:30:42.46433" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:30:42.46435" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:30:42.46438" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:30:42.46440" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:30:42.46442" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:30:42.46444" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:30:42.46446" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:30:42.46450" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:30:42.46452" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:30:42.46454" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:30:42.46457" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:30:42.46459" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:30:42.46461" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:30:42.46463" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:30:42.46465" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:30:42.46467" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:30:42.46470" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:30:42.46472" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:30:42.46473" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:30:42.46476" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/26389563a72b] 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:30:42.46479" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:30:42.46481" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: acl        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46483" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: srv6       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46485" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: ipsec      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46486" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: l2         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46488" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: nat        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46489" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: punt       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46490" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: wireguard  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46492" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: dns        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46494" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: stn        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46496" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: abf        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46497" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: interface  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46498" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: l3         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46500" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: core       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46501" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: telemetry  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46503" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="- handler: ipfix      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:42.46504" 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:30:42.53923"
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:30:42.53947"
[container::/e2e-test-vppagent-agent0/26389563a72b] vpp[36643]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/26389563a72b] vpp[36643]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="first connection to VPP established (took 158ms)" time="2023-08-14 11:30:42.62281" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:30:42.62343" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="binapi version 22.10 fully COMPATIBLE (856 messages)" time="2023-08-14 11:30:42.62430" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:30:42.62509" loc="govppmux/plugin_impl_govppmux.go:135"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="connection to VPP established (took 4ms)" time="2023-08-14 11:30:42.62861" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="binapi version 22.10 fully COMPATIBLE (856 messages)" time="2023-08-14 11:30:42.62949" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler core using preferred version: 22.10" time="2023-08-14 11:30:42.62953" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="vpp# show version verbose\nVersion:                  v22.10.1-1~g1b93285ce~b25\nCompiled by:              root\nCompile host:             49234abbf517\nCompile date:             2023-04-03T07:53:07\nCompile location:         /w/workspace/vpp-merge-2210-ubuntu2004-x86_64\nCompiler:                 Clang/LLVM 10.0.0 \nCurrent PID:              36643\n" time="2023-08-14 11:30:42.62996" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/26389563a72b] 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:30:42.63023" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/26389563a72b] ClientID=0 PID=36643 level=info logger=govpp msg="VPP version: 22.10.1-1~g1b93285ce~b25" time="2023-08-14 11:30:42.63060" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:30:42.63071" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="VPP loaded 69 plugins" time="2023-08-14 11:30:42.63121" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:30:42.63124" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:30:42.63126" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:30:42.63129" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:30:42.63131" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:30:42.63132" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:30:42.63134" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:30:42.63142" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:30:42.63146" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:30:42.63148" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:30:42.63149" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:30:42.63151" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:30:42.63153" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:30:42.63159" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:30:42.63167" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:30:42.63169" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:30:42.63170" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:30:42.63172" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:30:42.63174" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: dma_intel - Intel DSA Backend" time="2023-08-14 11:30:42.63176" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:30:42.63177" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:30:42.63180" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:30:42.63191" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:30:42.63192" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:30:42.63194" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:30:42.63195" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:30:42.63203" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: http - Hypertext Transfer Protocol (HTTP)" time="2023-08-14 11:30:42.63215" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:30:42.63217" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:30:42.63218" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:30:42.63220" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:30:42.63223" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:30:42.63224" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:30:42.63235" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:30:42.63237" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:30:42.63239" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:30:42.63240" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:30:42.63243" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:30:42.63245" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:30:42.63257" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:30:42.63259" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:30:42.63261" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:30:42.63263" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:30:42.63265" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:30:42.63278" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:30:42.63280" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:30:42.63282" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:30:42.63285" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: nsh - Network Service Header (NSH)" time="2023-08-14 11:30:42.63286" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:30:42.63298" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:30:42.63300" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:30:42.63301" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:30:42.63303" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:30:42.63306" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: prom - Prometheus Stats Exporter" time="2023-08-14 11:30:42.63312" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:30:42.63321" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:42.63322" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:42.63323" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:42.63326" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:42.63332" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:30:42.63339" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:30:42.63340" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:30:42.63342" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:30:42.63346" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:30:42.63347" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:30:42.63358" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:30:42.63360" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:30:42.63361" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:30:42.63363" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:30:42.63366" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:30:42.63659" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:30:42.63774" loc="govppmux/plugin_impl_govppmux.go:175"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:30:42.63800" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:30:42.63809" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:30:42.63814" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:30:42.63824" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:30:42.63836" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:30:42.63846" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:30:42.63854" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:30:42.63858" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:30:42.63864" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:30:42.63869" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:30:42.63873" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:30:42.63893" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:30:42.63899" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:30:42.63910" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:30:42.63914" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:30:42.63918" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:30:42.63921" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=kafka msg="kafka config not found  - skip loading this plugin" time="2023-08-14 11:30:42.63930" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:30:42.63933" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:30:42.63937" loc="agent/agent.go:220"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:30:42.63969"
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:30:42.64001"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=vpp-ifplugin msg="config  not found" time="2023-08-14 11:30:42.63940" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:30:42.63951" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:30:42.63952" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:42.63956" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:42.63959" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:42.63987" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:42.63990" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:30:42.64003" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:30:42.64150" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:30:42.64154" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:30:42.64159" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:30:42.64164" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:30:42.64173" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:30:42.64176" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:30:42.64178" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:30:42.64192" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:30:42.64196" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:30:42.64199" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:30:42.64205" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler dns using preferred version: 22.10" time="2023-08-14 11:30:42.64213" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler dns: 22.10" time="2023-08-14 11:30:42.64215" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:30:42.64221" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler ipfix using preferred version: 22.10" time="2023-08-14 11:30:42.64227" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler ipfix: 22.10" time="2023-08-14 11:30:42.64231" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:30:42.64239" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:30:42.64241" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:30:42.64248" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:30:42.64260" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:42.64262" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:42.64268" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:42.64274" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:42.64279" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:30:42.64290" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:42.64294" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:42.64296" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:42.64302" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:42.64304" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:30:42.64323" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:30:42.64326" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:30:42.64335" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:30:42.64354" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:30:42.64357" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:30:42.64359" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:30:42.64370" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler stn using preferred version: 22.10" time="2023-08-14 11:30:42.64373" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler stn: 22.10" time="2023-08-14 11:30:42.64375" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:30:42.64385" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler srv6 using preferred version: 22.10" time="2023-08-14 11:30:42.64389" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler srv6: 22.10" time="2023-08-14 11:30:42.64391" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:30:42.64440" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:30:42.64443" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:30:42.64445" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:30:42.64452" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:30:42.64460" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:30:42.64464" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:30:42.64470" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:30:42.64474" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:30:42.64483" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:30:42.64502" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:30:42.64525" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:30:42.64537" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:30:42.64540" loc="orchestrator/orchestrator.go:92"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:30:42.64545" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:30:42.64552" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:30:42.64560" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:30:42.64563" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:30:42.64573" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:30:42.64576" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:42.64581" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:42.64583" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:42.64592" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:42.64594" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:42.64596" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:42.64601" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:30:42.64604" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:30:42.64614" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:30:42.64619" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:30:42.64623" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:30:42.64625" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:30:42.64627" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:30:42.64630" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:30:42.64638" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:30:42.64641" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:30:42.64643" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:30:42.64646" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:30:42.64649" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:30:42.64653" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler core using preferred version: 22.10" time="2023-08-14 11:30:42.64662" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.10" time="2023-08-14 11:30:42.64664" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler telemetry: 22.10" time="2023-08-14 11:30:42.64666" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:42.64670" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:42.64671" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:42.64674" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:42.64684" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:42.64687" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:42.64689" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:30:42.64693" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:30:42.64695" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:30:42.64698" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:30:42.64707" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:30:42.64716" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:30:42.64717" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:30:42.64720" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:30:42.64729" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:30:42.64732" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:30:42.64735" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:30:42.64738" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:30:42.64740" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:30:42.64750" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:30:42.64752" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:30:42.64758" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:30:42.64761" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:30:42.64766" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:30:42.64768" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.10" time="2023-08-14 11:30:42.65249" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=global msg="found compatible version for handler telemetry: 22.10" time="2023-08-14 11:30:42.65252" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:30:42.65255" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:30:42.65282" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:30:42.65287" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:30:42.65291" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:30:42.65308" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:30:42.65340" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:30:42.65343" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:30:42.65346" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:30:42.65383" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:30:42.65390" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:30:42.65392" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:30:42.65410" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:30:42.65414" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:30:42.65436" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:30:42.65442" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:30:42.65444" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:30:42.65446" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:30:42.65458" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:30:42.65460" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:30:42.65461" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:30:42.65465" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:30:42.65482" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:30:42.65493" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:30:42.65496" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:30:42.65500" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:30:42.65510" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:30:42.65512" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:30:42.65515" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:30:42.65525" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:30:42.65527" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:30:42.65528" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:30:42.65530" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:30:42.65539" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:30:42.65563" loc="vpp2210/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:30:42.65718" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:30:42.65722" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:30:42.65723" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" time="2023-08-14 11:30:42.65725" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-abfplugin: status check probe registered" time="2023-08-14 11:30:42.65727" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-dnsplugin" time="2023-08-14 11:30:42.65728" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-dnsplugin: status check probe registered" time="2023-08-14 11:30:42.65730" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-- AfterInit(): vpp-ipfixplugin (not used)" time="2023-08-14 11:30:42.65731" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-ipsec-plugin" time="2023-08-14 11:30:42.65733" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-ipsec-plugin: status check probe registered" time="2023-08-14 11:30:42.65734" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-l2plugin" time="2023-08-14 11:30:42.65736" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-l2plugin: status check probe registered" time="2023-08-14 11:30:42.65738" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-l3plugin" time="2023-08-14 11:30:42.65740" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-l3plugin: status check probe registered" time="2023-08-14 11:30:42.65741" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-natplugin" time="2023-08-14 11:30:42.65743" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=status-check msg="Plugin vpp-natplugin: status check probe registered" time="2023-08-14 11:30:42.65744" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-punt-plugin" time="2023-08-14 11:30:42.65746" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563a72b] level=debug logger=agent msg="-> AfterInit(): vpp-stn-plugin" time="2023-08-14 11:30:42.65747" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/26389563

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=7537f2e6158e\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:30:48.94574"
    e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc0003ff280 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:30:48.96869"
    containerruntime.go:213: started Agent agent0 container a4d830481c6016352e677e35147fd5abefb615b97370f9c191ebc9c52202c6c5
cid=a4d830481c60 container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:30:49.20133"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:30:49.20143"
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:30:49.20167"
[container::/e2e-test-vppagent-agent0/a4d830481c60] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:30:49.20105" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:30:49.20147" loc="agent/agent.go:179"
[container::/e2e-test-vppagent-agent0/a4d830481c60]                                        __
[container::/e2e-test-vppagent-agent0/a4d830481c60]   _  _____  ___ _______ ____ ____ ___ / /_  
[container::/e2e-test-vppagent-agent0/a4d830481c60]  | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/  vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/a4d830481c60]  |___/ .__/ .__/   \_'_/\_' /\__/_//_\__/   Mon Aug 14 11:12:22 UTC 2023 (18 minutes ago)
[container::/e2e-test-vppagent-agent0/a4d830481c60]     /_/  /_/           /___/                root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/a4d830481c60] 
[container::/e2e-test-vppagent-agent0/a4d830481c60] 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:30:49.22873" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:30:49.22885" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:30:49.22901" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:30:49.22906" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:30:49.22911" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:30:49.22922" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:30:49.22930" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:30:49.22936" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:30:49.22938" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:30:49.22942" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:30:49.22945" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:30:49.22948" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:30:49.22951" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:30:49.22953" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:30:49.22959" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:30:49.22961" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:30:49.22965" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:30:49.22970" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:30:49.22972" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:30:49.22974" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:30:49.22979" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:30:49.22981" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:30:49.22985" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:30:49.22988" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:30:49.22990" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:30:49.22992" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:30:49.22997" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/a4d830481c60] 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:30:49.23000" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:30:49.23004" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: l3         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23006" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: nat        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23008" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: core       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23010" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: telemetry  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23014" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: srv6       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23015" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: abf        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23017" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: acl        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23018" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: punt       has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23022" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: wireguard  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23023" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: dns        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23025" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: stn        has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23026" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: interface  has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23029" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: l2         has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23031" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: ipsec      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23032" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="- handler: ipfix      has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:30:49.23034" 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:30:49.30238"
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:30:49.30286"
[container::/e2e-test-vppagent-agent0/a4d830481c60] vpp[36804]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/a4d830481c60] vpp[36804]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="first connection to VPP established (took 158ms)" time="2023-08-14 11:30:49.38792" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:30:49.38961" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="binapi version 22.10 fully COMPATIBLE (856 messages)" time="2023-08-14 11:30:49.39052" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:30:49.39120" loc="govppmux/plugin_impl_govppmux.go:135"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="connection to VPP established (took 4ms)" time="2023-08-14 11:30:49.39550" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="binapi version 22.02 core incompatible (5/491 messages)" time="2023-08-14 11:30:49.39662" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="binapi version 22.10 fully COMPATIBLE (856 messages)" time="2023-08-14 11:30:49.39783" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler core using preferred version: 22.10" time="2023-08-14 11:30:49.39786" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="vpp# show version verbose\nVersion:                  v22.10.1-1~g1b93285ce~b25\nCompiled by:              root\nCompile host:             49234abbf517\nCompile date:             2023-04-03T07:53:07\nCompile location:         /w/workspace/vpp-merge-2210-ubuntu2004-x86_64\nCompiler:                 Clang/LLVM 10.0.0 \nCurrent PID:              36804\n" time="2023-08-14 11:30:49.39826" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/a4d830481c60] 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:30:49.39857" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/a4d830481c60] ClientID=0 PID=36804 level=info logger=govpp msg="VPP version: 22.10.1-1~g1b93285ce~b25" time="2023-08-14 11:30:49.39898" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:30:49.39909" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="VPP loaded 69 plugins" time="2023-08-14 11:30:49.39947" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:30:49.39950" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:30:49.39952" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:30:49.39955" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:30:49.39957" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:30:49.39958" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:30:49.39962" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:30:49.39964" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:30:49.39965" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:30:49.39968" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:30:49.39970" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:30:49.39973" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:30:49.39974" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:30:49.39976" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:30:49.39978" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:30:49.39982" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:30:49.39983" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:30:49.39985" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:30:49.39986" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: dma_intel - Intel DSA Backend" time="2023-08-14 11:30:49.39988" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:30:49.39991" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:30:49.39992" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:30:49.39993" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:30:49.39995" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:30:49.39996" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:30:49.39998" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:30:49.39999" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: http - Hypertext Transfer Protocol (HTTP)" time="2023-08-14 11:30:49.40000" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:30:49.40002" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:30:49.40003" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:30:49.40005" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:30:49.40006" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:30:49.40007" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:30:49.40009" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:30:49.40010" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:30:49.40013" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:30:49.40014" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:30:49.40016" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:30:49.40017" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:30:49.40020" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:30:49.40021" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:30:49.40023" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:30:49.40024" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:30:49.40025" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:30:49.40029" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:30:49.40030" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:30:49.40032" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:30:49.40033" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: nsh - Network Service Header (NSH)" time="2023-08-14 11:30:49.40034" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:30:49.40037" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:30:49.40039" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:30:49.40040" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:30:49.40042" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:30:49.40044" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: prom - Prometheus Stats Exporter" time="2023-08-14 11:30:49.40046" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:30:49.40047" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:49.40048" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:49.40050" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:49.40052" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:30:49.40054" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:30:49.40055" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:30:49.40057" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:30:49.40059" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:30:49.40061" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:30:49.40062" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:30:49.40064" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:30:49.40067" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:30:49.40068" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:30:49.40070" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:30:49.40071" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:30:49.40272" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:30:49.40307" loc="govppmux/plugin_impl_govppmux.go:175"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:30:49.40324"
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:30:49.40368"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:30:49.40310" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:30:49.40315" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:30:49.40318" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:30:49.40323" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:30:49.40329" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:30:49.40332" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:30:49.40337" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:30:49.40339" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:30:49.40341" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:30:49.40344" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:30:49.40346" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:30:49.40355" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:30:49.40357" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:30:49.40360" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:30:49.40362" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:30:49.40364" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:30:49.40366" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=kafka msg="kafka config not found  - skip loading this plugin" time="2023-08-14 11:30:49.40369" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:30:49.40371" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:30:49.40373" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin msg="config  not found" time="2023-08-14 11:30:49.40376" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:30:49.40377" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:30:49.40379" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:49.40380" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:49.40382" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:49.40397" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:49.40399" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:30:49.40404" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:30:49.40407" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:30:49.40409" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:30:49.40410" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:30:49.40412" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:30:49.40414" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:30:49.40415" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:30:49.40417" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:30:49.40420" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:30:49.40422" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:30:49.40423" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:30:49.40428" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler dns using preferred version: 22.10" time="2023-08-14 11:30:49.40429" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler dns: 22.10" time="2023-08-14 11:30:49.40431" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:30:49.40434" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler ipfix using preferred version: 22.10" time="2023-08-14 11:30:49.40435" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler ipfix: 22.10" time="2023-08-14 11:30:49.40437" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:30:49.40441" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:30:49.40442" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:30:49.40444" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:30:49.40449" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:49.40450" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:49.40452" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:49.40454" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:49.40456" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:30:49.40459" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:49.40461" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:49.40462" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:49.40466" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:49.40467" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:30:49.40476" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:30:49.40478" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:30:49.40479" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:30:49.40486" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:30:49.40488" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:30:49.40489" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:30:49.40493" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler stn using preferred version: 22.10" time="2023-08-14 11:30:49.40496" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler stn: 22.10" time="2023-08-14 11:30:49.40497" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:30:49.40501" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler srv6 using preferred version: 22.10" time="2023-08-14 11:30:49.40503" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler srv6: 22.10" time="2023-08-14 11:30:49.40505" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:30:49.40511" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:30:49.40514" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:30:49.40515" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:30:49.40518" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:30:49.40522" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:30:49.40524" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:30:49.40528" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:30:49.40531" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:30:49.40533" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:30:49.40542" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:30:49.40554" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:30:49.40569" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:30:49.40572" loc="orchestrator/orchestrator.go:92"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:30:49.40575" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:30:49.40582" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:30:49.40599" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:30:49.40603" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:30:49.40605" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:30:49.40610" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:49.40627" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:49.40629" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:49.40632" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:49.40634" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:49.40648" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:49.40651" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:30:49.40654" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:30:49.40655" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:30:49.40658" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:30:49.40665" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:30:49.40667" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:30:49.40670" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:30:49.40673" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:30:49.40674" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:30:49.40686" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:30:49.40690" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:30:49.40693" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:30:49.40695" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:30:49.40703" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler core using preferred version: 22.10" time="2023-08-14 11:30:49.40745" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.10" time="2023-08-14 11:30:49.40747" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler telemetry: 22.10" time="2023-08-14 11:30:49.40749" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler interface using preferred version: 22.10" time="2023-08-14 11:30:49.40751" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler interface: 22.10" time="2023-08-14 11:30:49.40752" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l2 using preferred version: 22.10" time="2023-08-14 11:30:49.40758" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l2: 22.10" time="2023-08-14 11:30:49.40760" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler l3 using preferred version: 22.10" time="2023-08-14 11:30:49.40762" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler l3: 22.10" time="2023-08-14 11:30:49.40765" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.10" time="2023-08-14 11:30:49.40775" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler ipsec: 22.10" time="2023-08-14 11:30:49.40778" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler abf using preferred version: 22.10" time="2023-08-14 11:30:49.40780" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler abf: 22.10" time="2023-08-14 11:30:49.40783" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler acl using preferred version: 22.10" time="2023-08-14 11:30:49.40786" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler acl: 22.10" time="2023-08-14 11:30:49.40787" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler nat using preferred version: 22.10" time="2023-08-14 11:30:49.40799" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler nat: 22.10" time="2023-08-14 11:30:49.40801" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler punt using preferred version: 22.10" time="2023-08-14 11:30:49.40803" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler punt: 22.10" time="2023-08-14 11:30:49.40805" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.10" time="2023-08-14 11:30:49.40807" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler wireguard: 22.10" time="2023-08-14 11:30:49.40809" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:30:49.40885" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:30:49.40889" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:30:49.40892" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:30:49.40898" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:30:49.40900" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:30:49.40908" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin.dhcp-descriptor msg="Started watcher on DHCP notifications" time="2023-08-14 11:30:49.42907" loc="descriptor/dhcp.go:219"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin msg="start watching DHCP leases" time="2023-08-14 11:30:49.42914" loc="vpp2210/watch_vppcalls.go:128"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin msg="Start watching interface state events" time="2023-08-14 11:30:49.42917" loc="ifplugin/publish_state.go:18"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.10" time="2023-08-14 11:30:49.43031" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=global msg="found compatible version for handler telemetry: 22.10" time="2023-08-14 11:30:49.43035" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:30:49.43038" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:30:49.43045" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:30:49.43047" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:30:49.43049" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:30:49.43051" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:30:49.43066" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:30:49.43068" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:30:49.43070" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:30:49.43120" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:30:49.43147" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:30:49.43172" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:30:49.43175" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:30:49.43197" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:30:49.43219" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:30:49.43246" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:30:49.43251" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:30:49.43252" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:30:49.43254" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:30:49.43256" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:30:49.43257" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:30:49.43259" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:30:49.43261" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:30:49.43262" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:30:49.43264" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:30:49.43267" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:30:49.43269" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:30:49.43270" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:30:49.43370" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:30:49.43395" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:30:49.43419" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:30:49.43426" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:30:49.43451" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:30:49.43475" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:30:49.43530" loc="vpp2210/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:30:49.43541" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:30:49.43543" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:30:49.43545" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" time="2023-08-14 11:30:49.43547" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin vpp-abfplugin: status check probe registered" time="2023-08-14 11:30:49.43548" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-dnsplugin" time="2023-08-14 11:30:49.43550" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin vpp-dnsplugin: status check probe registered" time="2023-08-14 11:30:49.43552" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-- AfterInit(): vpp-ipfixplugin (not used)" time="2023-08-14 11:30:49.43553" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-ipsec-plugin" time="2023-08-14 11:30:49.43555" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin vpp-ipsec-plugin: status check probe registered" time="2023-08-14 11:30:49.43557" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-l2plugin" time="2023-08-14 11:30:49.43633" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=status-check msg="Plugin vpp-l2plugin: status check probe registered" time="2023-08-14 11:30:49.43657" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=agent msg="-> AfterInit(): vpp-l3plugin" time="2023-08-14 11:30:49.43678" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/a4d830481c60] level=debug logger=linux-nsplugin.ms-descriptor msg="detected container as microservice: Name=/e2e-test-vppagent-agent0 ID=a4d830481c6016352e677e35147fd5abefb615b97370f

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.51s)
View more details on GitHub Actions