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, 90 passed, 8 skipped, 3 failed.
Annotations
Check failure on line 322 in tests/e2e/e2etest/e2e.go
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=9b873530fada\nRUNNER_DEBUG=1\nPWD=/\nHOME=/root\nVPP_AGENT=prod_vpp_agent\nSHLVL=0\nTESTDATA_DIR=/home/runner/work/vpp-agent/vpp-agent/tests/e2e/e2etest/resources\nGOTESTSUM_JUNITFILE=/testreport/e2e.xml\nPATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\nGITHUB_WORKFLOW=Test\nTEST_SUITE=e2e" time="2023-08-14 11:31:37.72559"
e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc00057cb80 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:31:37.74193"
containerruntime.go:213: started Agent agent0 container b851efbe799fe1649ca1b76c7670ef334be379205f737228dbb93a5a840940f1
cid=b851efbe799f container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:31:37.97450"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:37.97471"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:37.97520"
[container::/e2e-test-vppagent-agent0/b851efbe799f] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:31:37.97293" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:31:37.97355" loc="agent/agent.go:179"
[container::/e2e-test-vppagent-agent0/b851efbe799f] __
[container::/e2e-test-vppagent-agent0/b851efbe799f] _ _____ ___ _______ ____ ____ ___ / /_
[container::/e2e-test-vppagent-agent0/b851efbe799f] | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/ vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/b851efbe799f] |___/ .__/ .__/ \_'_/\_' /\__/_//_\__/ Mon Aug 14 11:12:22 UTC 2023 (19 minutes ago)
[container::/e2e-test-vppagent-agent0/b851efbe799f] /_/ /_/ /___/ root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/b851efbe799f]
[container::/e2e-test-vppagent-agent0/b851efbe799f] BuildDate="Mon Aug 14 11:12:22 UTC 2023" CommitHash=f4fe5e7@master level=info logger=agent msg="Starting agent version: f4fe5e7" time="2023-08-14 11:31:38.00975" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:31:38.01038" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:31:38.01060" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:31:38.01082" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:31:38.01097" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:31:38.01124" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:31:38.01143" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:31:38.01155" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:31:38.01169" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:31:38.01196" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:31:38.01219" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:31:38.01239" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:31:38.01260" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:31:38.01281" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:31:38.01303" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:31:38.01322" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:31:38.01342" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:31:38.01363" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:31:38.01381" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:31:38.01399" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:31:38.01419" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:31:38.01436" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:31:38.01455" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:31:38.01475" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:31:38.01493" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:31:38.01511" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:31:38.01530" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="config: &{ReconnectResync:true ReplyTimeout:1s ConnectViaShm:false ShmPrefix: BinAPISocketPath: StatsSocketPath: RetryRequestCount:0 RetryRequestTimeout:500ms RetryConnectCount:0 RetryConnectTimeout:1s ProxyEnabled:true HealthCheckProbeInterval:1s HealthCheckReplyTimeout:250ms HealthCheckThreshold:1 TraceEnabled:false}" time="2023-08-14 11:31:38.01551" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:31:38.01570" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: abf has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01587" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: acl has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01606" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: ipsec has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01624" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: l2 has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01642" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: l3 has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01659" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: ipfix has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01678" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: srv6 has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01696" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: stn has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01714" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: interface has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01732" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: punt has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01750" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: telemetry has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01767" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: nat has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01784" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: wireguard has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01801" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: core has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01819" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="- handler: dns has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:38.01838" loc="govppmux/plugin_impl_govppmux.go:120"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:38.07575"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:38.07619"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:38.17637"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:38.17664"
[container::/e2e-test-vppagent-agent0/b851efbe799f] /usr/bin/vpp[36947]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/b851efbe799f] /usr/bin/vpp[36947]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="first connection to VPP established (took 219ms)" time="2023-08-14 11:31:38.23717" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="binapi version 23.06 core incompatible (35/521 messages)" time="2023-08-14 11:31:38.23815" loc="binapi/binapi.go:47"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="binapi version 22.02 fully COMPATIBLE (848 messages)" time="2023-08-14 11:31:38.23984" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:31:38.24207" loc="govppmux/plugin_impl_govppmux.go:135"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="connection to VPP established (took 4ms)" time="2023-08-14 11:31:38.24622" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="binapi version 22.02 fully COMPATIBLE (848 messages)" time="2023-08-14 11:31:38.24713" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler core using preferred version: 22.02" time="2023-08-14 11:31:38.24718" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="vpp# show version verbose\nVersion: v22.02.0-11~g73fdb095b~b30\nCompiled by: root\nCompile host: c0d7a3632202\nCompile date: 2022-06-21T02:14:26\nCompile location: /w/workspace/vpp-merge-2202-ubuntu2004-x86_64\nCompiler: Clang/LLVM 11.0.0 \nCurrent PID: 36947\n" time="2023-08-14 11:31:38.24765" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="vpp# show version cmdline:\nCommand line arguments: /usr/bin/vpp unix { nodaemon cli-listen /run/vpp/cli.sock cli-no-pager full-coredump } plugins { plugin dpdk_plugin.so { disable } } socksvr { socket-name /run/vpp/api.sock } statseg { socket-name /run/vpp/stats.sock per-node-counters on }" time="2023-08-14 11:31:38.24794" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/b851efbe799f] ClientID=0 PID=36947 level=info logger=govpp msg="VPP version: 22.02.0-11~g73fdb095b~b30" time="2023-08-14 11:31:38.24838" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:31:38.24862" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="VPP loaded 67 plugins" time="2023-08-14 11:31:38.24906" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:31:38.24909" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:31:38.24946" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:31:38.24949" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:31:38.24951" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:31:38.24970" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:31:38.24992" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:31:38.24995" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:31:38.25000" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:31:38.25025" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:31:38.25034" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:31:38.25048" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:31:38.25054" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:31:38.25057" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:31:38.25077" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:31:38.25087" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:31:38.25108" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:31:38.25111" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:31:38.25112" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:31:38.25138" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:31:38.25156" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:31:38.25163" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:31:38.25167" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:31:38.25189" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:31:38.25210" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:31:38.25213" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:31:38.25214" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:31:38.25233" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:31:38.25265" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:31:38.25282" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:31:38.25298" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:31:38.25313" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:31:38.25330" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:31:38.25333" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:31:38.25352" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:31:38.25357" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:31:38.25373" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:31:38.25376" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:31:38.25411" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:31:38.25414" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:31:38.25415" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:31:38.25434" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:31:38.25437" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:31:38.25453" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:31:38.25474" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:31:38.25491" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: nsh - Network Service Header (NSH)" time="2023-08-14 11:31:38.25495" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:31:38.25497" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:31:38.25528" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:31:38.25537" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:31:38.25554" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:31:38.25560" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:31:38.25562" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:38.25571" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:38.25596" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:38.25599" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:38.25628" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:31:38.25631" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:31:38.25632" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:31:38.25634" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: tlsmbedtls - Transport Layer Security (TLS) Engine, Mbedtls Based" time="2023-08-14 11:31:38.25653" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:31:38.25675" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:31:38.25693" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:31:38.25696" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:31:38.25712" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:31:38.25715" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:31:38.25732" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:31:38.25735" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:31:38.26033" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:31:38.26081" loc="govppmux/plugin_impl_govppmux.go:175"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:31:38.26085" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:31:38.26092" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:31:38.26109" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:31:38.26117" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:31:38.26134" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:31:38.26144" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:31:38.26160" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:31:38.26166" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:31:38.26212" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:31:38.26218" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:38.26221" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:31:38.26242" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:31:38.26246" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:31:38.26257" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:31:38.26263" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:31:38.26270" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:31:38.26274" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=kafka msg="kafka config not found - skip loading this plugin" time="2023-08-14 11:31:38.26280" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:31:38.26284" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:31:38.26292" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin msg="config not found" time="2023-08-14 11:31:38.26296" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:31:38.26300" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:31:38.26303" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:38.26311" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:38.26315" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:38.26351" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:38.26355" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:31:38.26361" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:31:38.26367" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:31:38.26372" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:31:38.26377" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:38.26379" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:31:38.26383" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler acl using preferred version: 22.02" time="2023-08-14 11:31:38.26385" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler acl: 22.02" time="2023-08-14 11:31:38.26387" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:31:38.26400" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler abf using preferred version: 22.02" time="2023-08-14 11:31:38.26402" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler abf: 22.02" time="2023-08-14 11:31:38.26404" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:31:38.26412" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler dns using preferred version: 22.02" time="2023-08-14 11:31:38.26418" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler dns: 22.02" time="2023-08-14 11:31:38.26426" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:31:38.26433" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler ipfix using preferred version: 22.02" time="2023-08-14 11:31:38.26434" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler ipfix: 22.02" time="2023-08-14 11:31:38.26437" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:31:38.26452" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.02" time="2023-08-14 11:31:38.26456" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler ipsec: 22.02" time="2023-08-14 11:31:38.26458" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:31:38.26477" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:38.26478" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:38.26480" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:38.26491" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:38.26493" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:31:38.26511" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:38.26513" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:38.26515" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:38.26527" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:38.26531" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:31:38.26550" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler nat using preferred version: 22.02" time="2023-08-14 11:31:38.26552" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler nat: 22.02" time="2023-08-14 11:31:38.26553" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:31:38.26576" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler punt using preferred version: 22.02" time="2023-08-14 11:31:38.26578" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler punt: 22.02" time="2023-08-14 11:31:38.26584" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:31:38.26591" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler stn using preferred version: 22.02" time="2023-08-14 11:31:38.26596" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler stn: 22.02" time="2023-08-14 11:31:38.26597" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:31:38.26607" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler srv6 using preferred version: 22.02" time="2023-08-14 11:31:38.26609" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler srv6: 22.02" time="2023-08-14 11:31:38.26611" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:31:38.26626" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.02" time="2023-08-14 11:31:38.26629" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler wireguard: 22.02" time="2023-08-14 11:31:38.26631" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:31:38.26642" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:31:38.26648" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:38.26650" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:31:38.26661" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:31:38.26667" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:38.26672" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:31:38.26693" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:31:38.26723" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:31:38.26733" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:31:38.27748" loc="orchestrator/orchestrator.go:92"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:38.27773"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:38.27797"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:31:38.27839" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin.dhcp-descriptor msg="Started watcher on DHCP notifications" time="2023-08-14 11:31:38.28001" loc="descriptor/dhcp.go:219"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:31:38.28009" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:31:38.28089" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:31:38.28100" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:38.28108" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:31:38.28116" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:38.28163" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:38.28190" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:38.28304" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:38.28325" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:38.28350" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:38.28367" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.02" time="2023-08-14 11:31:38.28382" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler ipsec: 22.02" time="2023-08-14 11:31:38.28398" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler abf using preferred version: 22.02" time="2023-08-14 11:31:38.28416" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler abf: 22.02" time="2023-08-14 11:31:38.28427" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler acl using preferred version: 22.02" time="2023-08-14 11:31:38.28443" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler acl: 22.02" time="2023-08-14 11:31:38.28454" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler nat using preferred version: 22.02" time="2023-08-14 11:31:38.28480" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler nat: 22.02" time="2023-08-14 11:31:38.28491" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler punt using preferred version: 22.02" time="2023-08-14 11:31:38.28508" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler punt: 22.02" time="2023-08-14 11:31:38.28524" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.02" time="2023-08-14 11:31:38.28549" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler wireguard: 22.02" time="2023-08-14 11:31:38.28580" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:31:38.28605" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler core using preferred version: 22.02" time="2023-08-14 11:31:38.28633" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.02" time="2023-08-14 11:31:38.28654" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler telemetry: 22.02" time="2023-08-14 11:31:38.28673" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:38.28706" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:38.28726" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:38.28762" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:38.28784" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:38.28819" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:38.28837" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=linux-nsplugin.ms-descriptor msg="detected container as microservice: Name=/e2e-test-vppagent-agent0 ID=b851efbe799fe1649ca1b76c7670ef334be379205f737228dbb93a5a840940f1 Created=2023-08-14 11:31:37.727163518 +0000 UTC State.StartedAt=2023-08-14 11:31:37.97033699 +0000 UTC" time="2023-08-14 11:31:38.28862" loc="descriptor/microservice.go:176"
[container::/e2e-test-vppagent-agent0/b851efbe799f] id=b851efbe799fe1649ca1b76c7670ef334be379205f737228dbb93a5a840940f1 label=agent0 level=debug logger=linux-nsplugin.ms-descriptor msg="Discovered new microservice" pid=36909 time="2023-08-14 11:31:38.28877" loc="descriptor/microservice.go:202"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.02" time="2023-08-14 11:31:38.28897" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler ipsec: 22.02" time="2023-08-14 11:31:38.29049" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler abf using preferred version: 22.02" time="2023-08-14 11:31:38.29060" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler abf: 22.02" time="2023-08-14 11:31:38.29068" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler acl using preferred version: 22.02" time="2023-08-14 11:31:38.29109" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler acl: 22.02" time="2023-08-14 11:31:38.29119" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler nat using preferred version: 22.02" time="2023-08-14 11:31:38.29145" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler nat: 22.02" time="2023-08-14 11:31:38.29157" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler punt using preferred version: 22.02" time="2023-08-14 11:31:38.29180" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler punt: 22.02" time="2023-08-14 11:31:38.29201" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.02" time="2023-08-14 11:31:38.29229" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler wireguard: 22.02" time="2023-08-14 11:31:38.29250" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin msg="Start watching interface state events" time="2023-08-14 11:31:38.28023" loc="ifplugin/publish_state.go:18"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin msg="start watching DHCP leases" time="2023-08-14 11:31:38.28046" loc="vpp2202/watch_vppcalls.go:128"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:31:38.29287" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:31:38.29296" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:31:38.29299" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:31:38.29316" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:31:38.29320" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:31:38.29347" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.02" time="2023-08-14 11:31:38.29399" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=global msg="found compatible version for handler telemetry: 22.02" time="2023-08-14 11:31:38.29402" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:31:38.29421" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:31:38.29443" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:31:38.29461" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:31:38.29465" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:31:38.29467" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:31:38.29514" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:31:38.29518" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:31:38.29548" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:31:38.29567" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:31:38.29581" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:31:38.29598" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:31:38.29615" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:31:38.29624" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:38.29637" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:31:38.29653" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:31:38.29669" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:38.29672" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:31:38.29674" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:31:38.29675" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:38.29706" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:31:38.29709" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:31:38.29732" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:31:38.29740" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:31:38.29753" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:31:38.29760" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:31:38.29775" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:31:38.29801" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:31:38.29814" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:31:38.29830" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:31:38.29843" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:31:38.29856" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:31:38.29869" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:31:38.29886" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:31:38.29908" loc="vpp2202/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:31:38.29932" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:31:38.29936" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:31:38.29942" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" time="2023-08-14 11:31:38.29967" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin vpp-abfplugin: status check probe registered" time="2023-08-14 11:31:38.29970" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): vpp-dnsplugin" time="2023-08-14 11:31:38.29989" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin vpp-dnsplugin: status check probe registered" time="2023-08-14 11:31:38.30001" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-- AfterInit(): vpp-ipfixplugin (not used)" time="2023-08-14 11:31:38.30005" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): vpp-ipsec-plugin" time="2023-08-14 11:31:38.30013" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin vpp-ipsec-plugin: status check probe registered" time="2023-08-14 11:31:38.30025" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=agent msg="-> AfterInit(): vpp-l2plugin" time="2023-08-14 11:31:38.30035" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/b851efbe799f] level=debug logger=status-check msg="Plugin vpp-l2plugin: status check probe registered" time="2023-08-14 11:31:38.30042" loc="
Check failure on line 322 in tests/e2e/e2etest/e2e.go
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=9b873530fada\nRUNNER_DEBUG=1\nPWD=/\nHOME=/root\nVPP_AGENT=prod_vpp_agent\nSHLVL=0\nTESTDATA_DIR=/home/runner/work/vpp-agent/vpp-agent/tests/e2e/e2etest/resources\nGOTESTSUM_JUNITFILE=/testreport/e2e.xml\nPATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\nGITHUB_WORKFLOW=Test\nTEST_SUITE=e2e" time="2023-08-14 11:31:44.59896"
e2e.go:190: Using docker client endpoint: unix:///var/run/docker.sock
level=debug logger=global msg="starting container: {ContainerOptions:0xc0003d95c0 Pull:false AttachLogs:true}" name="Agent agent0" time="2023-08-14 11:31:44.61677"
containerruntime.go:213: started Agent agent0 container fca94fab5d79bdac31f0773a9643104c3b06700f76c4718781b6cfdab47e7d5e
cid=fca94fab5d79 container=/e2e-test-vppagent-agent0 level=debug logger=global msg="container started" name="Agent agent0" time="2023-08-14 11:31:44.86571"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:44.86583"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:44.86610"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] BuildDate= CommitHash= level=info logger=agent msg="Starting agent version: v0.0.0-dev" time="2023-08-14 11:31:44.86540" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=agent msg="Agent started with 2 plugins (took 0s)" time="2023-08-14 11:31:44.86649" loc="agent/agent.go:179"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] __
[container::/e2e-test-vppagent-agent0/fca94fab5d79] _ _____ ___ _______ ____ ____ ___ / /_
[container::/e2e-test-vppagent-agent0/fca94fab5d79] | |/ / _ \/ _ /___/ _ '/ _ '/ -_/ _ / __/ vpp-agent f4fe5e7
[container::/e2e-test-vppagent-agent0/fca94fab5d79] |___/ .__/ .__/ \_'_/\_' /\__/_//_\__/ Mon Aug 14 11:12:22 UTC 2023 (19 minutes ago)
[container::/e2e-test-vppagent-agent0/fca94fab5d79] /_/ /_/ /___/ root@buildkitsandbox (go1.20.7 linux/amd64)
[container::/e2e-test-vppagent-agent0/fca94fab5d79]
[container::/e2e-test-vppagent-agent0/fca94fab5d79] BuildDate="Mon Aug 14 11:12:22 UTC 2023" CommitHash=f4fe5e7@master level=info logger=agent msg="Starting agent version: f4fe5e7" time="2023-08-14 11:31:44.90485" loc="agent/agent.go:137"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="Microservice label is set to agent0" time="2023-08-14 11:31:44.90541" loc="servicelabel/plugin_impl_servicelabel.go:45"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): kvscheduler" time="2023-08-14 11:31:44.90554" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=kvscheduler msg="kvscheduler config not found" time="2023-08-14 11:31:44.90557" loc="kvscheduler/plugin_scheduler.go:223"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=kvscheduler msg="KVScheduler configuration: {RecordTransactionHistory:true TransactionHistoryAgeLimit:1440 PermanentlyRecordedInitPeriod:60 EnableTxnSimulation:false PrintTxnSummary:true}" time="2023-08-14 11:31:44.90561" loc="kvscheduler/plugin_scheduler.go:173"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/txn-history" time="2023-08-14 11:31:44.90571" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/key-timeline" time="2023-08-14 11:31:44.90576" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/graph-snapshot" time="2023-08-14 11:31:44.90580" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/flag-stats" time="2023-08-14 11:31:44.90584" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/downstream-resync" time="2023-08-14 11:31:44.90587" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/dump" time="2023-08-14 11:31:44.90590" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/status" time="2023-08-14 11:31:44.90593" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/graph" time="2023-08-14 11:31:44.90596" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /scheduler/stats" time="2023-08-14 11:31:44.90599" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): resync" time="2023-08-14 11:31:44.90603" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): etcd" time="2023-08-14 11:31:44.90606" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=etcd msg="ETCD config not found, skip loading this plugin" time="2023-08-14 11:31:44.90608" loc="etcd/plugin_impl_etcd.go:293"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): kvdb-etcd-datasync" time="2023-08-14 11:31:44.90612" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): consul" time="2023-08-14 11:31:44.90614" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=consul msg="Consul config not found, skip loading this plugin" time="2023-08-14 11:31:44.90616" loc="consul/plugin.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): kvdb-consul-datasync" time="2023-08-14 11:31:44.90618" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): redis" time="2023-08-14 11:31:44.90620" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=redis msg="Redis config not found, skip loading this plugin" time="2023-08-14 11:31:44.90622" loc="redis/plugin_impl_redis.go:123"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): kvdb-redis-datasync" time="2023-08-14 11:31:44.90625" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): status-check" time="2023-08-14 11:31:44.90626" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): govpp" time="2023-08-14 11:31:44.90629" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="config file \"\" not found, using default config" time="2023-08-14 11:31:44.90632" loc="govppmux/config.go:85"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="config: &{ReconnectResync:true ReplyTimeout:1s ConnectViaShm:false ShmPrefix: BinAPISocketPath: StatsSocketPath: RetryRequestCount:0 RetryRequestTimeout:500ms RetryConnectCount:0 RetryConnectTimeout:1s ProxyEnabled:true HealthCheckProbeInterval:1s HealthCheckReplyTimeout:250ms HealthCheckThreshold:1 TraceEnabled:false}" time="2023-08-14 11:31:44.90636" loc="govppmux/plugin_impl_govppmux.go:101"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="found 15 registered VPP handlers" time="2023-08-14 11:31:44.90638" loc="govppmux/plugin_impl_govppmux.go:117"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: acl has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90641" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: nat has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90642" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: srv6 has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90644" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: dns has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90645" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: l2 has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90647" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: l3 has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90648" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: telemetry has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90649" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: wireguard has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90651" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: core has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90653" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: stn has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90655" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: punt has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90656" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: ipfix has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90658" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: abf has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90659" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: interface has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90661" loc="govppmux/plugin_impl_govppmux.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="- handler: ipsec has 3 versions: [22.02 22.10 23.06]" time="2023-08-14 11:31:44.90662" loc="govppmux/plugin_impl_govppmux.go:120"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:44.96648"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:44.96704"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:45.06752"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:45.06820"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] /usr/bin/vpp[37111]: perfmon: skipping source 'intel-uncore' - intel_uncore_init: no uncore units found
[container::/e2e-test-vppagent-agent0/fca94fab5d79] /usr/bin/vpp[37111]: vat-plug/load: vat_plugin_register: oddbuf plugin not loaded...
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="first connection to VPP established (took 219ms)" time="2023-08-14 11:31:45.12545" loc="govppmux/plugin_impl_govppmux.go:215"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="binapi version 22.02 fully COMPATIBLE (848 messages)" time="2023-08-14 11:31:45.12654" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="connecting to VPP.." time="2023-08-14 11:31:45.12735" loc="govppmux/plugin_impl_govppmux.go:135"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="connection to VPP established (took 4ms)" time="2023-08-14 11:31:45.13088" loc="govppmux/plugin_impl_govppmux.go:146"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="binapi version 22.02 fully COMPATIBLE (848 messages)" time="2023-08-14 11:31:45.13175" loc="binapi/binapi.go:66"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler core using preferred version: 22.02" time="2023-08-14 11:31:45.13179" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="vpp# show version verbose\nVersion: v22.02.0-11~g73fdb095b~b30\nCompiled by: root\nCompile host: c0d7a3632202\nCompile date: 2022-06-21T02:14:26\nCompile location: /w/workspace/vpp-merge-2202-ubuntu2004-x86_64\nCompiler: Clang/LLVM 11.0.0 \nCurrent PID: 37111\n" time="2023-08-14 11:31:45.13229" loc="govppmux/plugin_impl_govppmux.go:363"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="vpp# show version cmdline:\nCommand line arguments: /usr/bin/vpp unix { nodaemon cli-listen /run/vpp/cli.sock cli-no-pager full-coredump } plugins { plugin dpdk_plugin.so { disable } } socksvr { socket-name /run/vpp/api.sock } statseg { socket-name /run/vpp/stats.sock per-node-counters on }" time="2023-08-14 11:31:45.13258" loc="govppmux/plugin_impl_govppmux.go:370"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] ClientID=0 PID=37111 level=info logger=govpp msg="VPP version: 22.02.0-11~g73fdb095b~b30" time="2023-08-14 11:31:45.13298" loc="govppmux/plugin_impl_govppmux.go:384"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="VPP has 0 core modules: []" time="2023-08-14 11:31:45.13318" loc="govppmux/plugin_impl_govppmux.go:390"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="VPP loaded 67 plugins" time="2023-08-14 11:31:45.13366" loc="govppmux/plugin_impl_govppmux.go:399"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: abf - Access Control List (ACL) Based Forwarding" time="2023-08-14 11:31:45.13370" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: acl - Access Control Lists (ACL)" time="2023-08-14 11:31:45.13410" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: adl - Allow/deny list plugin" time="2023-08-14 11:31:45.13413" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: af_xdp - AF_XDP Device Plugin" time="2023-08-14 11:31:45.13445" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: arping - Arping (arping)" time="2023-08-14 11:31:45.13449" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: avf - Intel Adaptive Virtual Function (AVF) Device Driver" time="2023-08-14 11:31:45.13468" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: bufmon - Buffers monitoring plugin" time="2023-08-14 11:31:45.13473" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: builtinurl - vpp built-in URL support" time="2023-08-14 11:31:45.13499" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: cdp - Cisco Discovery Protocol (CDP)" time="2023-08-14 11:31:45.13512" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: cnat - CNat Translate" time="2023-08-14 11:31:45.13518" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: crypto_ipsecmb - Intel IPSEC Multi-buffer Crypto Engine" time="2023-08-14 11:31:45.13537" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: crypto_native - Intel IA32 Software Crypto Engine" time="2023-08-14 11:31:45.13539" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: crypto_openssl - OpenSSL Crypto Engine" time="2023-08-14 11:31:45.13540" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: crypto_sw_scheduler - SW Scheduler Crypto Async Engine plugin" time="2023-08-14 11:31:45.13567" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: ct6 - IPv6 Connection Tracker" time="2023-08-14 11:31:45.13570" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: det44 - Deterministic NAT (CGN)" time="2023-08-14 11:31:45.13572" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: dhcp - Dynamic Host Configuration Protocol (DHCP)" time="2023-08-14 11:31:45.13595" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: dispatch_trace - Dispatch Trace" time="2023-08-14 11:31:45.13610" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: dns - Simple DNS name resolver" time="2023-08-14 11:31:45.13618" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: dslite - Dual-Stack Lite" time="2023-08-14 11:31:45.13634" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: flowprobe - Flow per Packet" time="2023-08-14 11:31:45.13636" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: geneve - GENEVE Tunnels" time="2023-08-14 11:31:45.13638" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: gtpu - GPRS Tunnelling Protocol, User Data (GTPv1-U)" time="2023-08-14 11:31:45.13639" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: hs_apps - Host Stack Applications" time="2023-08-14 11:31:45.13641" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: hsi - Host Stack Intercept (HSI)" time="2023-08-14 11:31:45.13670" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: http_static - HTTP Static Server" time="2023-08-14 11:31:45.13671" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: igmp - Internet Group Management Protocol (IGMP)" time="2023-08-14 11:31:45.13675" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: ikev2 - Internet Key Exchange (IKEv2) Protocol" time="2023-08-14 11:31:45.13703" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: ila - Identifier Locator Addressing (ILA) for IPv6" time="2023-08-14 11:31:45.13704" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: ioam - Inbound Operations, Administration, and Maintenance (OAM)" time="2023-08-14 11:31:45.13706" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: l2tp - Layer 2 Tunneling Protocol v3 (L2TP)" time="2023-08-14 11:31:45.13707" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: l3xc - L3 Cross-Connect (L3XC)" time="2023-08-14 11:31:45.13734" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: lacp - Link Aggregation Control Protocol (LACP)" time="2023-08-14 11:31:45.13737" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: lb - Load Balancer (LB)" time="2023-08-14 11:31:45.13739" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: lisp - Locator ID Separation Protocol (LISP)" time="2023-08-14 11:31:45.13741" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: lldp - Link Layer Discovery Protocol (LLDP)" time="2023-08-14 11:31:45.13754" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: mactime - Time-based MAC Source Address Filter" time="2023-08-14 11:31:45.13756" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: map - Mapping of Address and Port (MAP)" time="2023-08-14 11:31:45.13797" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: mdata - Buffer metadata change tracker." time="2023-08-14 11:31:45.13816" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: memif - Packet Memory Interface (memif) -- Experimental" time="2023-08-14 11:31:45.13819" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: mss_clamp - TCP MSS clamping plugin" time="2023-08-14 11:31:45.13838" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: nat - Network Address Translation (NAT)" time="2023-08-14 11:31:45.13846" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: nat44_ei - IPv4 Endpoint-Independent NAT (NAT44 EI)" time="2023-08-14 11:31:45.13861" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: nat64 - NAT64" time="2023-08-14 11:31:45.13893" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: nat66 - NAT66" time="2023-08-14 11:31:45.13914" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: nsh - Network Service Header (NSH)" time="2023-08-14 11:31:45.13917" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: nsim - Network Delay Simulator" time="2023-08-14 11:31:45.13940" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: perfmon - Performance Monitor" time="2023-08-14 11:31:45.13949" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: ping - Ping (ping)" time="2023-08-14 11:31:45.13971" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: pnat - Policy 1:1 NAT" time="2023-08-14 11:31:45.13974" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: pppoe - PPP over Ethernet (PPPoE)" time="2023-08-14 11:31:45.13996" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: rdma - RDMA IBverbs Device Driver" time="2023-08-14 11:31:45.14013" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: srv6ad - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:45.14016" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: srv6adflow - Dynamic Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:45.14018" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: srv6am - Masquerading Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:45.14020" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: srv6as - Static Segment Routing for IPv6 (SRv6) Proxy" time="2023-08-14 11:31:45.14042" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: srv6mobile - SRv6 GTP Endpoint Functions" time="2023-08-14 11:31:45.14057" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: stn - VPP Steals the NIC (STN) for Container Integration" time="2023-08-14 11:31:45.14062" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: svs - Source Virtual Routing and Forwarding (VRF) Select" time="2023-08-14 11:31:45.14085" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: tlsmbedtls - Transport Layer Security (TLS) Engine, Mbedtls Based" time="2023-08-14 11:31:45.14102" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: tlsopenssl - Transport Layer Security (TLS) Engine, OpenSSL Based" time="2023-08-14 11:31:45.14104" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: tlspicotls - Transport Layer Security (TLS) Engine, Picotls Based" time="2023-08-14 11:31:45.14106" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: tracedump - Streaming packet trace dump plugin" time="2023-08-14 11:31:45.14108" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: urpf - Unicast Reverse Path Forwarding (uRPF)" time="2023-08-14 11:31:45.14134" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: vmxnet3 - VMWare Vmxnet3 Device Driver" time="2023-08-14 11:31:45.14147" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: vrrp - VRRP v3 (RFC 5798)" time="2023-08-14 11:31:45.14156" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg=" - plugin: wireguard - Wireguard Protocol" time="2023-08-14 11:31:45.14158" loc="govppmux/plugin_impl_govppmux.go:401"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=govpp msg="starting VPP proxy" time="2023-08-14 11:31:45.14460" loc="govppmux/plugin_impl_govppmux.go:502"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=govpp msg="VPP proxy ready" time="2023-08-14 11:31:45.14507" loc="govppmux/plugin_impl_govppmux.go:175"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /govppmux/stats" time="2023-08-14 11:31:45.14511" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /_goRPC_" time="2023-08-14 11:31:45.14529" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /vpp/command" time="2023-08-14 11:31:45.14557" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): netalloc" time="2023-08-14 11:31:45.14578" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): linux-nsplugin" time="2023-08-14 11:31:45.14598" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-nsplugin msg="Linux NsPlugin config not found" time="2023-08-14 11:31:45.14617" loc="nsplugin/ns_plugin.go:216"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-nsplugin msg="Using docker client endpoint: unix:///var/run/docker.sock" time="2023-08-14 11:31:45.14632" loc="descriptor/microservice.go:105"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-nsplugin msg="Namespace plugin initialized" time="2023-08-14 11:31:45.14652" loc="nsplugin/ns_plugin.go:111"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): linux-ifplugin" time="2023-08-14 11:31:45.14656" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-ifplugin msg="Linux IfPlugin config not found" time="2023-08-14 11:31:45.14689" loc="ifplugin/ifplugin.go:180"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-ifplugin msg="Linux interface plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:45.14697" loc="ifplugin/ifplugin.go:89"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): initfileregistry" time="2023-08-14 11:31:45.14727" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=initfileregistry msg="InitFileRegistry plugin config not found" time="2023-08-14 11:31:45.14753" loc="localregistry/initfileregistry.go:173"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=initfileregistry msg="Initialization configuration file(/root/initial-config.yaml) not found. Skipping its preloading." time="2023-08-14 11:31:45.14777" loc="localregistry/initfileregistry.go:224"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): aggregator" time="2023-08-14 11:31:45.14798" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=aggregator msg="Aggregator plugin config not found" time="2023-08-14 11:31:45.14815" loc="watcher/aggregator.go:355"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): kafka" time="2023-08-14 11:31:45.14838" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=kafka msg="kafka config not found - skip loading this plugin" time="2023-08-14 11:31:45.14853" loc="kafka/plugin_impl_kafka.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): msgsync" time="2023-08-14 11:31:45.14860" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-ifplugin" time="2023-08-14 11:31:45.14876" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin msg="config not found" time="2023-08-14 11:31:45.14906" loc="ifplugin/config.go:54"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishErrors dependency" time="2023-08-14 11:31:45.14909" loc="ifplugin/ifplugin.go:368"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin msg="setting default noop writer for PublishStatistics dependency" time="2023-08-14 11:31:45.15101" loc="ifplugin/ifplugin.go:372"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:45.15131" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:45.15168" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:45.15359" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:45.15388" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin.if-descriptor msg="Watch ifplugin_ifstate" time="2023-08-14 11:31:45.15426" loc="mem/inmemory_name_mapping.go:179"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin msg="subscribe to 1 status prefixes: [vpp/status/v2/interface/]" time="2023-08-14 11:31:45.15462" loc="ifplugin/ifplugin.go:264"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=warning logger=aggregator msg="found local registry (localclient) in watchers, ignoring it.." time="2023-08-14 11:31:45.15488" loc="watcher/aggregator.go:123"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=aggregator msg="Watch for vpp-if-state with 1 prefixes" time="2023-08-14 11:31:45.15511" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:45.15541" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-aclplugin" time="2023-08-14 11:31:45.15565" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler acl using preferred version: 22.02" time="2023-08-14 11:31:45.15588" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler acl: 22.02" time="2023-08-14 11:31:45.16018" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-abfplugin" time="2023-08-14 11:31:45.16025" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler abf using preferred version: 22.02" time="2023-08-14 11:31:45.16027" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler abf: 22.02" time="2023-08-14 11:31:45.16030" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-dnsplugin" time="2023-08-14 11:31:45.16035" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler dns using preferred version: 22.02" time="2023-08-14 11:31:45.16037" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler dns: 22.02" time="2023-08-14 11:31:45.16039" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-ipfixplugin" time="2023-08-14 11:31:45.16043" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler ipfix using preferred version: 22.02" time="2023-08-14 11:31:45.16047" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler ipfix: 22.02" time="2023-08-14 11:31:45.16049" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-ipsec-plugin" time="2023-08-14 11:31:45.16058" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.02" time="2023-08-14 11:31:45.16075" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler ipsec: 22.02" time="2023-08-14 11:31:45.16077" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-l2plugin" time="2023-08-14 11:31:45.16106" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:45.16109" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:45.16117" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:45.16125" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:45.16135" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-l3plugin" time="2023-08-14 11:31:45.16141" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:45.16152" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:45.16154" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:45.16169" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:45.16172" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-natplugin" time="2023-08-14 11:31:45.16210" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler nat using preferred version: 22.02" time="2023-08-14 11:31:45.16213" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler nat: 22.02" time="2023-08-14 11:31:45.16223" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin.dhcp-descriptor msg="Started watcher on DHCP notifications" time="2023-08-14 11:31:45.16249" loc="descriptor/dhcp.go:219"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-punt-plugin" time="2023-08-14 11:31:45.16270" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler punt using preferred version: 22.02" time="2023-08-14 11:31:45.16281" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler punt: 22.02" time="2023-08-14 11:31:45.16289" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin msg="start watching DHCP leases" time="2023-08-14 11:31:45.16318" loc="vpp2202/watch_vppcalls.go:128"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-stn-plugin" time="2023-08-14 11:31:45.16319" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler stn using preferred version: 22.02" time="2023-08-14 11:31:45.16349" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler stn: 22.02" time="2023-08-14 11:31:45.16355" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin msg="Start watching interface state events" time="2023-08-14 11:31:45.16407" loc="ifplugin/publish_state.go:18"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-srplugin" time="2023-08-14 11:31:45.16421" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler srv6 using preferred version: 22.02" time="2023-08-14 11:31:45.16423" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler srv6: 22.02" time="2023-08-14 11:31:45.16426" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): vpp-wg-plugin" time="2023-08-14 11:31:45.16440" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.02" time="2023-08-14 11:31:45.16453" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler wireguard: 22.02" time="2023-08-14 11:31:45.16455" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): linux-l3plugin" time="2023-08-14 11:31:45.16469" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-l3plugin msg="Linux L3Plugin config not found" time="2023-08-14 11:31:45.16472" loc="l3plugin/l3plugin.go:113"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-l3plugin msg="Linux L3 plugin config: &{Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:45.16475" loc="l3plugin/l3plugin.go:71"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): linux-iptablesplugin" time="2023-08-14 11:31:45.16489" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-iptablesplugin msg="Linux IPTablesPlugin config not found" time="2023-08-14 11:31:45.16501" loc="iptablesplugin/iptablesplugin.go:120"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=linux-iptablesplugin msg="Linux iptables config: &{HandlerConfig:{MinRuleCountForPerfRuleAddition:2147483647} Disabled:false GoRoutinesCnt:10}" time="2023-08-14 11:31:45.16505" loc="iptablesplugin/iptablesplugin.go:76"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): grpc" time="2023-08-14 11:31:45.16534" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=grpc msg="Prometheus server metrics for gRPC enabled" time="2023-08-14 11:31:45.16560" loc="grpc/plugin_impl_grpc.go:113"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): orchestrator" time="2023-08-14 11:31:45.16569" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=orchestrator msg="registering generic manager and meta service" time="2023-08-14 11:31:45.16571" loc="orchestrator/orchestrator.go:92"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=orchestrator msg="registering grpc reflection service" time="2023-08-14 11:31:45.16578" loc="orchestrator/orchestrator.go:98"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=orchestrator msg="Found 45 registered models" time="2023-08-14 11:31:45.16587" loc="orchestrator/orchestrator.go:106"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=dispatcher msg="Watching 44 key prefixes from KVScheduler" time="2023-08-14 11:31:45.16590" loc="orchestrator/orchestrator.go:113"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=aggregator msg="Watch for orchestrator with 45 prefixes" time="2023-08-14 11:31:45.16602" loc="watcher/aggregator.go:154"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=aggregator msg="added localclient as aggregated watcher" time="2023-08-14 11:31:45.16604" loc="watcher/aggregator.go:202"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): configurator" time="2023-08-14 11:31:45.16606" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:45.16619" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:45.16620" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:45.16632" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:45.16634" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:45.16636" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:45.16638" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.02" time="2023-08-14 11:31:45.16650" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler ipsec: 22.02" time="2023-08-14 11:31:45.16653" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler abf using preferred version: 22.02" time="2023-08-14 11:31:45.16657" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler abf: 22.02" time="2023-08-14 11:31:45.16659" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler acl using preferred version: 22.02" time="2023-08-14 11:31:45.16670" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler acl: 22.02" time="2023-08-14 11:31:45.16672" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler nat using preferred version: 22.02" time="2023-08-14 11:31:45.16674" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler nat: 22.02" time="2023-08-14 11:31:45.16684" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler punt using preferred version: 22.02" time="2023-08-14 11:31:45.16688" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler punt: 22.02" time="2023-08-14 11:31:45.16699" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.02" time="2023-08-14 11:31:45.16703" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler wireguard: 22.02" time="2023-08-14 11:31:45.16704" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): restpapi" time="2023-08-14 11:31:45.16718" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler core using preferred version: 22.02" time="2023-08-14 11:31:45.16720" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.02" time="2023-08-14 11:31:45.16722" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler telemetry: 22.02" time="2023-08-14 11:31:45.16733" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler interface using preferred version: 22.02" time="2023-08-14 11:31:45.16735" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler interface: 22.02" time="2023-08-14 11:31:45.16736" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l2 using preferred version: 22.02" time="2023-08-14 11:31:45.16738" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l2: 22.02" time="2023-08-14 11:31:45.16749" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler l3 using preferred version: 22.02" time="2023-08-14 11:31:45.16751" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler l3: 22.02" time="2023-08-14 11:31:45.16753" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler ipsec using preferred version: 22.02" time="2023-08-14 11:31:45.16765" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler ipsec: 22.02" time="2023-08-14 11:31:45.16767" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler abf using preferred version: 22.02" time="2023-08-14 11:31:45.16769" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler abf: 22.02" time="2023-08-14 11:31:45.16771" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler acl using preferred version: 22.02" time="2023-08-14 11:31:45.16784" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler acl: 22.02" time="2023-08-14 11:31:45.16786" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler nat using preferred version: 22.02" time="2023-08-14 11:31:45.16794" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler nat: 22.02" time="2023-08-14 11:31:45.16804" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler punt using preferred version: 22.02" time="2023-08-14 11:31:45.16808" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler punt: 22.02" time="2023-08-14 11:31:45.16810" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler wireguard using preferred version: 22.02" time="2023-08-14 11:31:45.16822" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler wireguard: 22.02" time="2023-08-14 11:31:45.16824" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): probe" time="2023-08-14 11:31:45.16830" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): prometheus" time="2023-08-14 11:31:45.16832" loc="agent/agent.go:220"
level=debug msg="=> sending http request: GET http://172.17.0.3:9191/readiness" time="2023-08-14 11:31:45.16860"
level=debug msg="<- http response ERROR: Get \"http://172.17.0.3:9191/readiness\": dial tcp 172.17.0.3:9191: connect: connection refused" time="2023-08-14 11:31:45.16893"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): telemetry" time="2023-08-14 11:31:45.16836" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=telemetry msg="Telemetry config not found. Using default config: &{PollingInterval:30s Disabled:false PrometheusDisabled:false Skipped:[]}" time="2023-08-14 11:31:45.16840" loc="telemetry/config.go:58"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=telemetry msg="polling period changed to 30s" time="2023-08-14 11:31:45.16843" loc="telemetry/telemetry.go:111"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=telemetry msg="registering prometheus registry path: /metrics" time="2023-08-14 11:31:45.16845" loc="telemetry/prometheus.go:163"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="VPP handler telemetry using preferred version: 22.02" time="2023-08-14 11:31:45.16891" loc="vpp/handlers.go:87"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=global msg="found compatible version for handler telemetry: 22.02" time="2023-08-14 11:31:45.16894" loc="vpp/handlers.go:73"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /metrics/{metric}" time="2023-08-14 11:31:45.16904" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> Init(): VPPAgent" time="2023-08-14 11:31:45.16912" loc="agent/agent.go:220"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin VPPAgent: status check probe registered" time="2023-08-14 11:31:45.16914" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): service-label (not used)" time="2023-08-14 11:31:45.16930" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): http" time="2023-08-14 11:31:45.16932" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=info logger=http msg="Serving on http://0.0.0.0:9191" time="2023-08-14 11:31:45.16970" loc="rest/plugin_impl_rest.go:121"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): logs" time="2023-08-14 11:31:45.16974" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /log/{logger}/{level}" time="2023-08-14 11:31:45.16978" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=http msg="Registering handler: /log/list" time="2023-08-14 11:31:45.16985" loc="rest/plugin_impl_rest.go:132"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): kvscheduler (not used)" time="2023-08-14 11:31:45.16998" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): resync (not used)" time="2023-08-14 11:31:45.17000" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): etcd" time="2023-08-14 11:31:45.17002" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): kvdb-etcd-datasync" time="2023-08-14 11:31:45.17013" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=kvdb-etcd-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:45.17014" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): consul (not used)" time="2023-08-14 11:31:45.17016" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): kvdb-consul-datasync" time="2023-08-14 11:31:45.17019" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=kvdb-consul-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:45.17030" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): redis" time="2023-08-14 11:31:45.17033" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): kvdb-redis-datasync" time="2023-08-14 11:31:45.17045" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=kvdb-redis-datasync msg="KVPlugin is nil or disabled, skipping AfterInit" time="2023-08-14 11:31:45.17047" loc="kvdbsync/plugin_impl_dbsync.go:75"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): status-check" time="2023-08-14 11:31:45.17048" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): govpp" time="2023-08-14 11:31:45.17060" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin govpp: status check probe registered" time="2023-08-14 11:31:45.17062" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] lastErr="<nil>" level=info logger=status-check msg="Agent plugin state update." plugin=govpp state=ok time="2023-08-14 11:31:45.17064" loc="statuscheck/plugin_impl_statuscheck.go:184"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): netalloc (not used)" time="2023-08-14 11:31:45.17078" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): linux-nsplugin (not used)" time="2023-08-14 11:31:45.17080" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): linux-ifplugin (not used)" time="2023-08-14 11:31:45.17082" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): initfileregistry (not used)" time="2023-08-14 11:31:45.17083" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): aggregator (not used)" time="2023-08-14 11:31:45.17093" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): kafka" time="2023-08-14 11:31:45.17095" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=kafka msg="kafka plugin disabled, skipping AfterInit" time="2023-08-14 11:31:45.17096" loc="kafka/plugin_impl_kafka.go:116"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): msgsync" time="2023-08-14 11:31:45.17099" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-ifplugin" time="2023-08-14 11:31:45.17110" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=vpp-ifplugin.if-handler msg="start watching interface events" time="2023-08-14 11:31:45.17115" loc="vpp2202/watch_vppcalls.go:55"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-ifplugin: status check probe registered" time="2023-08-14 11:31:45.17138" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-aclplugin" time="2023-08-14 11:31:45.17140" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-aclplugin: status check probe registered" time="2023-08-14 11:31:45.17150" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-abfplugin" time="2023-08-14 11:31:45.17152" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-abfplugin: status check probe registered" time="2023-08-14 11:31:45.17154" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-dnsplugin" time="2023-08-14 11:31:45.17155" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-dnsplugin: status check probe registered" time="2023-08-14 11:31:45.17166" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-- AfterInit(): vpp-ipfixplugin (not used)" time="2023-08-14 11:31:45.17168" loc="agent/agent.go:242"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-ipsec-plugin" time="2023-08-14 11:31:45.17169" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-ipsec-plugin: status check probe registered" time="2023-08-14 11:31:45.17171" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-l2plugin" time="2023-08-14 11:31:45.17189" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-l2plugin: status check probe registered" time="2023-08-14 11:31:45.17193" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-l3plugin" time="2023-08-14 11:31:45.17196" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-l3plugin: status check probe registered" time="2023-08-14 11:31:45.17197" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agent msg="-> AfterInit(): vpp-natplugin" time="2023-08-14 11:31:45.17200" loc="agent/agent.go:237"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=status-check msg="Plugin vpp-natplugin: status check probe registered" time="2023-08-14 11:31:45.17202" loc="statuscheck/plugin_impl_statuscheck.go:138"
[container::/e2e-test-vppagent-agent0/fca94fab5d79] level=debug logger=agen
Check failure on line 1 in tests/e2e/e2etest/e2e.go
github-actions / JUnit Test Report
e2e.TestNATPools
Failed
Raw output
=== RUN TestNATPools
--- FAIL: TestNATPools (13.74s)
Loading