{"level":"warn","ts":"2026-04-22T18:21:46.509607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T18:21:46.509864Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.117:2380","--initial-cluster=instance=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T18:21:46.510011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T18:21:46.510082Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-22T18:21:46.510153Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T18:21:46.510973Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-04-22T18:21:46.511127Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.117:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T18:21:46.515657Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.235588ms"} {"level":"info","ts":"2026-04-22T18:21:46.524987Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-04-22T18:21:46.525113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T18:21:46.525144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-04-22T18:21:46.525183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T18:21:46.525211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-04-22T18:21:46.525261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-04-22T18:21:46.530245Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T18:21:46.531448Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T18:21:46.532582Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T18:21:46.53537Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T18:21:46.535582Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T18:21:46.53578Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T18:21:46.53584Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T18:21:46.535848Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T18:21:46.536269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-04-22T18:21:46.537172Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-22T18:21:46.543776Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T18:21:46.544036Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T18:21:46.544075Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T18:21:46.544432Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-22T18:21:46.544452Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-22T18:21:46.626311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T18:21:46.626374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T18:21:46.626418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-04-22T18:21:46.626441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-04-22T18:21:46.626454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-22T18:21:46.626476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-04-22T18:21:46.62655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-22T18:21:46.627413Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.117:2379]}","request-path":"/0/members/5f9f905057b5b268/attributes","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T18:21:46.627458Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T18:21:46.627668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T18:21:46.627951Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T18:21:46.627977Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T18:21:46.62807Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:46.630391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T18:21:46.631247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-04-22T18:21:46.631463Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:46.631659Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:46.631922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-22T18:22:22.863168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.55139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:22:22.863333Z","caller":"traceutil/trace.go:171","msg":"trace[614205783] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"159.999466ms","start":"2026-04-22T18:22:22.703316Z","end":"2026-04-22T18:22:22.863315Z","steps":["trace[614205783] 'process raft request' (duration: 32.658291ms)","trace[614205783] 'compare' (duration: 126.384548ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:22:23.08222Z","caller":"traceutil/trace.go:171","msg":"trace[353300794] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"144.417422ms","start":"2026-04-22T18:22:22.937769Z","end":"2026-04-22T18:22:23.082187Z","steps":["trace[353300794] 'read index received' (duration: 143.634296ms)","trace[353300794] 'applied index is now lower than readState.Index' (duration: 781.906µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:22:23.082424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.625853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T18:22:23.082456Z","caller":"traceutil/trace.go:171","msg":"trace[1049299458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"144.717355ms","start":"2026-04-22T18:22:22.937728Z","end":"2026-04-22T18:22:23.082445Z","steps":["trace[1049299458] 'agreement among raft nodes before linearized reading' (duration: 144.561273ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:22:23.082435Z","caller":"traceutil/trace.go:171","msg":"trace[635057503] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"177.662517ms","start":"2026-04-22T18:22:22.904662Z","end":"2026-04-22T18:22:23.082325Z","steps":["trace[635057503] 'process raft request' (duration: 176.955092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:22:23.57985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.535113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:22:23.580022Z","caller":"traceutil/trace.go:171","msg":"trace[105538990] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"433.376558ms","start":"2026-04-22T18:22:23.146627Z","end":"2026-04-22T18:22:23.580004Z","steps":["trace[105538990] 'read index received' (duration: 230.613554ms)","trace[105538990] 'applied index is now lower than readState.Index' (duration: 202.762014ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:22:23.580058Z","caller":"traceutil/trace.go:171","msg":"trace[2129602274] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"462.051827ms","start":"2026-04-22T18:22:23.117958Z","end":"2026-04-22T18:22:23.580009Z","steps":["trace[2129602274] 'process raft request' (duration: 259.269472ms)","trace[2129602274] 'compare' (duration: 202.373612ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:22:23.580229Z","caller":"traceutil/trace.go:171","msg":"trace[52297000] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"438.091374ms","start":"2026-04-22T18:22:23.142076Z","end":"2026-04-22T18:22:23.580167Z","steps":["trace[52297000] 'process raft request' (duration: 437.878092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:22:23.580199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:22:23.117936Z","time spent":"462.200167ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T18:22:23.580319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.66429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lwng8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T18:22:23.580421Z","caller":"traceutil/trace.go:171","msg":"trace[725042433] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lwng8; range_end:; response_count:1; response_revision:618; }","duration":"433.809371ms","start":"2026-04-22T18:22:23.146583Z","end":"2026-04-22T18:22:23.580392Z","steps":["trace[725042433] 'agreement among raft nodes before linearized reading' (duration: 433.529649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:22:23.580392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:22:23.142045Z","time spent":"438.250755ms","remote":"127.0.0.1:52674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T18:22:23.580479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:22:23.146562Z","time spent":"433.906623ms","remote":"127.0.0.1:52394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-lwng8\" "} {"level":"warn","ts":"2026-04-22T18:22:39.175498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.87568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:22:39.175608Z","caller":"traceutil/trace.go:171","msg":"trace[1816334597] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"286.900159ms","start":"2026-04-22T18:22:38.888689Z","end":"2026-04-22T18:22:39.17559Z","steps":["trace[1816334597] 'process raft request' (duration: 109.835898ms)","trace[1816334597] 'compare' (duration: 176.761969ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:23:11.977879Z","caller":"traceutil/trace.go:171","msg":"trace[1913046722] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"143.335617ms","start":"2026-04-22T18:23:11.83452Z","end":"2026-04-22T18:23:11.977856Z","steps":["trace[1913046722] 'read index received' (duration: 143.106582ms)","trace[1913046722] 'applied index is now lower than readState.Index' (duration: 227.515µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:23:11.97795Z","caller":"traceutil/trace.go:171","msg":"trace[896169609] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"145.191398ms","start":"2026-04-22T18:23:11.832724Z","end":"2026-04-22T18:23:11.977915Z","steps":["trace[896169609] 'process raft request' (duration: 144.977683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:23:11.978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.458459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:23:11.978036Z","caller":"traceutil/trace.go:171","msg":"trace[1851951734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"143.536892ms","start":"2026-04-22T18:23:11.834489Z","end":"2026-04-22T18:23:11.978026Z","steps":["trace[1851951734] 'agreement among raft nodes before linearized reading' (duration: 143.46189ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:23:35.684748Z","caller":"traceutil/trace.go:171","msg":"trace[791621064] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"109.079427ms","start":"2026-04-22T18:23:35.575644Z","end":"2026-04-22T18:23:35.684724Z","steps":["trace[791621064] 'process raft request' (duration: 108.942705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:23:59.596775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.093146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:23:59.596973Z","caller":"traceutil/trace.go:171","msg":"trace[355202240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"100.305659ms","start":"2026-04-22T18:23:59.496636Z","end":"2026-04-22T18:23:59.596942Z","steps":["trace[355202240] 'range keys from in-memory index tree' (duration: 99.995344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:23:59.59717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.145445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:23:59.59778Z","caller":"traceutil/trace.go:171","msg":"trace[1721365211] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1848; }","duration":"279.819405ms","start":"2026-04-22T18:23:59.317928Z","end":"2026-04-22T18:23:59.597747Z","steps":["trace[1721365211] 'count revisions from in-memory index tree' (duration: 279.044154ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:23:59.79847Z","caller":"traceutil/trace.go:171","msg":"trace[1819638892] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"107.367646ms","start":"2026-04-22T18:23:59.69107Z","end":"2026-04-22T18:23:59.798438Z","steps":["trace[1819638892] 'process raft request' (duration: 107.212773ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:00.069257Z","caller":"traceutil/trace.go:171","msg":"trace[289458089] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"203.729898ms","start":"2026-04-22T18:23:59.865481Z","end":"2026-04-22T18:24:00.069211Z","steps":["trace[289458089] 'process raft request' (duration: 116.457234ms)","trace[289458089] 'compare' (duration: 86.851597ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:24:34.730106Z","caller":"traceutil/trace.go:171","msg":"trace[26160054] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2177; }","duration":"218.613382ms","start":"2026-04-22T18:24:34.511459Z","end":"2026-04-22T18:24:34.730072Z","steps":["trace[26160054] 'read index received' (duration: 216.436186ms)","trace[26160054] 'applied index is now lower than readState.Index' (duration: 2.175676ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:24:34.730164Z","caller":"traceutil/trace.go:171","msg":"trace[96538413] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"220.0699ms","start":"2026-04-22T18:24:34.510032Z","end":"2026-04-22T18:24:34.730102Z","steps":["trace[96538413] 'process raft request' (duration: 217.868203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:24:34.730278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.793095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:24:34.730352Z","caller":"traceutil/trace.go:171","msg":"trace[1202494262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"218.899287ms","start":"2026-04-22T18:24:34.511431Z","end":"2026-04-22T18:24:34.730331Z","steps":["trace[1202494262] 'agreement among raft nodes before linearized reading' (duration: 218.742315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:24:34.730488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.748602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T18:24:34.730539Z","caller":"traceutil/trace.go:171","msg":"trace[477998445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2100; }","duration":"171.864253ms","start":"2026-04-22T18:24:34.558664Z","end":"2026-04-22T18:24:34.730528Z","steps":["trace[477998445] 'agreement among raft nodes before linearized reading' (duration: 171.66747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:24:34.730599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.017071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T18:24:34.730693Z","caller":"traceutil/trace.go:171","msg":"trace[1499835960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2100; }","duration":"166.163772ms","start":"2026-04-22T18:24:34.56451Z","end":"2026-04-22T18:24:34.730674Z","steps":["trace[1499835960] 'agreement among raft nodes before linearized reading' (duration: 165.910739ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:34.958965Z","caller":"traceutil/trace.go:171","msg":"trace[467641475] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"222.697413ms","start":"2026-04-22T18:24:34.736221Z","end":"2026-04-22T18:24:34.958919Z","steps":["trace[467641475] 'process raft request' (duration: 218.679094ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:34.959156Z","caller":"traceutil/trace.go:171","msg":"trace[1146629866] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"205.408028ms","start":"2026-04-22T18:24:34.753716Z","end":"2026-04-22T18:24:34.959125Z","steps":["trace[1146629866] 'read index received' (duration: 201.168036ms)","trace[1146629866] 'applied index is now lower than readState.Index' (duration: 4.237422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:24:34.959357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.58564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:24:34.959388Z","caller":"traceutil/trace.go:171","msg":"trace[139228663] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2102; }","duration":"134.708861ms","start":"2026-04-22T18:24:34.82467Z","end":"2026-04-22T18:24:34.959379Z","steps":["trace[139228663] 'agreement among raft nodes before linearized reading' (duration: 134.61379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:24:34.959401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.676141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T18:24:34.959498Z","caller":"traceutil/trace.go:171","msg":"trace[1225718362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2102; }","duration":"205.817193ms","start":"2026-04-22T18:24:34.753645Z","end":"2026-04-22T18:24:34.959462Z","steps":["trace[1225718362] 'agreement among raft nodes before linearized reading' (duration: 205.56451ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:25:40.534267Z","caller":"traceutil/trace.go:171","msg":"trace[1118907311] linearizableReadLoop","detail":"{readStateIndex:2649; appliedIndex:2648; }","duration":"183.742462ms","start":"2026-04-22T18:25:40.350483Z","end":"2026-04-22T18:25:40.534226Z","steps":["trace[1118907311] 'read index received' (duration: 182.55439ms)","trace[1118907311] 'applied index is now lower than readState.Index' (duration: 1.185922ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:25:40.534354Z","caller":"traceutil/trace.go:171","msg":"trace[559549194] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"183.882824ms","start":"2026-04-22T18:25:40.350439Z","end":"2026-04-22T18:25:40.534322Z","steps":["trace[559549194] 'process raft request' (duration: 182.612011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:25:40.534578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.069626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"warn","ts":"2026-04-22T18:25:40.534651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.781166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T18:25:40.534663Z","caller":"traceutil/trace.go:171","msg":"trace[1844848460] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2556; }","duration":"184.211407ms","start":"2026-04-22T18:25:40.350432Z","end":"2026-04-22T18:25:40.534644Z","steps":["trace[1844848460] 'agreement among raft nodes before linearized reading' (duration: 183.980305ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:25:40.534705Z","caller":"traceutil/trace.go:171","msg":"trace[1087793372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2556; }","duration":"153.862767ms","start":"2026-04-22T18:25:40.380826Z","end":"2026-04-22T18:25:40.534689Z","steps":["trace[1087793372] 'agreement among raft nodes before linearized reading' (duration: 153.605914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:25:40.534878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.749847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T18:25:40.534932Z","caller":"traceutil/trace.go:171","msg":"trace[1938665042] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:2556; }","duration":"130.837628ms","start":"2026-04-22T18:25:40.40408Z","end":"2026-04-22T18:25:40.534917Z","steps":["trace[1938665042] 'agreement among raft nodes before linearized reading' (duration: 130.653026ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:26:12.512252Z","caller":"traceutil/trace.go:171","msg":"trace[78369177] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"101.750334ms","start":"2026-04-22T18:26:12.410468Z","end":"2026-04-22T18:26:12.512219Z","steps":["trace[78369177] 'process raft request' (duration: 101.25185ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:26:12.998488Z","caller":"traceutil/trace.go:171","msg":"trace[897563766] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"270.030493ms","start":"2026-04-22T18:26:12.72843Z","end":"2026-04-22T18:26:12.998461Z","steps":["trace[897563766] 'process raft request' (duration: 269.851861ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:06.155381Z","caller":"traceutil/trace.go:171","msg":"trace[2044534128] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"106.469184ms","start":"2026-04-22T18:30:06.048886Z","end":"2026-04-22T18:30:06.155355Z","steps":["trace[2044534128] 'process raft request' (duration: 106.268471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:10.169652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.157448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T18:30:10.170099Z","caller":"traceutil/trace.go:171","msg":"trace[1073736088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4656; }","duration":"130.418632ms","start":"2026-04-22T18:30:10.0394Z","end":"2026-04-22T18:30:10.169819Z","steps":["trace[1073736088] 'range keys from in-memory index tree' (duration: 129.579908ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:10.221941Z","caller":"traceutil/trace.go:171","msg":"trace[1676925259] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"120.677457ms","start":"2026-04-22T18:30:10.101238Z","end":"2026-04-22T18:30:10.221915Z","steps":["trace[1676925259] 'process raft request' (duration: 120.519395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:15.970349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.357156ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-22T18:30:15.970507Z","caller":"traceutil/trace.go:171","msg":"trace[953909639] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4950; }","duration":"141.543614ms","start":"2026-04-22T18:30:15.828941Z","end":"2026-04-22T18:30:15.970484Z","steps":["trace[953909639] 'read index received' (duration: 68.892µs)","trace[953909639] 'applied index is now lower than readState.Index' (duration: 141.473302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:30:15.970689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.736927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d324b9e7-57ba-412f-b5bd-1b130f248fa1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-22T18:30:15.970762Z","caller":"traceutil/trace.go:171","msg":"trace[5521476] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d324b9e7-57ba-412f-b5bd-1b130f248fa1; range_end:; response_count:1; response_revision:4771; }","duration":"141.894529ms","start":"2026-04-22T18:30:15.828853Z","end":"2026-04-22T18:30:15.970747Z","steps":["trace[5521476] 'agreement among raft nodes before linearized reading' (duration: 141.698486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:16.595253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.738851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-22T18:30:16.595345Z","caller":"traceutil/trace.go:171","msg":"trace[1891777778] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4783; }","duration":"125.895114ms","start":"2026-04-22T18:30:16.469428Z","end":"2026-04-22T18:30:16.595324Z","steps":["trace[1891777778] 'range keys from in-memory index tree' (duration: 125.530757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:27.837116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.060691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:30:27.837291Z","caller":"traceutil/trace.go:171","msg":"trace[251579657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4936; }","duration":"340.306605ms","start":"2026-04-22T18:30:27.496965Z","end":"2026-04-22T18:30:27.837272Z","steps":["trace[251579657] 'range keys from in-memory index tree' (duration: 339.94061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:30:27.837372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:30:27.496934Z","time spent":"340.428298ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T18:30:27.837725Z","caller":"traceutil/trace.go:171","msg":"trace[1694930336] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"213.680935ms","start":"2026-04-22T18:30:27.624008Z","end":"2026-04-22T18:30:27.837689Z","steps":["trace[1694930336] 'process raft request' (duration: 191.089885ms)","trace[1694930336] 'compare' (duration: 21.869078ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:30:28.244316Z","caller":"traceutil/trace.go:171","msg":"trace[1616787566] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"101.696089ms","start":"2026-04-22T18:30:28.142595Z","end":"2026-04-22T18:30:28.244291Z","steps":["trace[1616787566] 'process raft request' (duration: 101.528017ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:28.277265Z","caller":"traceutil/trace.go:171","msg":"trace[327015465] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"134.414684ms","start":"2026-04-22T18:30:28.142824Z","end":"2026-04-22T18:30:28.277239Z","steps":["trace[327015465] 'process raft request' (duration: 134.18078ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:28.277589Z","caller":"traceutil/trace.go:171","msg":"trace[1774314556] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"131.556877ms","start":"2026-04-22T18:30:28.146019Z","end":"2026-04-22T18:30:28.277576Z","steps":["trace[1774314556] 'process raft request' (duration: 131.163201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:30:28.277638Z","caller":"traceutil/trace.go:171","msg":"trace[378104790] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"131.416005ms","start":"2026-04-22T18:30:28.146213Z","end":"2026-04-22T18:30:28.277629Z","steps":["trace[378104790] 'process raft request' (duration: 131.264923ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:31:47.275506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2917} {"level":"info","ts":"2026-04-22T18:31:47.346712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2917,"took":"69.485ms","hash":3686291334,"current-db-size-bytes":52871168,"current-db-size":"53 MB","current-db-size-in-use-bytes":29126656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-22T18:31:47.346781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3686291334,"revision":2917,"compact-revision":-1} {"level":"info","ts":"2026-04-22T18:31:52.391118Z","caller":"traceutil/trace.go:171","msg":"trace[1348383474] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"124.602544ms","start":"2026-04-22T18:31:52.266492Z","end":"2026-04-22T18:31:52.391094Z","steps":["trace[1348383474] 'process raft request' (duration: 124.433352ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:34:20.115472Z","caller":"traceutil/trace.go:171","msg":"trace[85963954] transaction","detail":"{read_only:false; response_revision:6661; number_of_response:1; }","duration":"141.791105ms","start":"2026-04-22T18:34:19.973656Z","end":"2026-04-22T18:34:20.115447Z","steps":["trace[85963954] 'process raft request' (duration: 141.667724ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:47.281989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5584} {"level":"info","ts":"2026-04-22T18:36:47.363275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5584,"took":"78.888276ms","hash":2267724760,"current-db-size-bytes":52871168,"current-db-size":"53 MB","current-db-size-in-use-bytes":18296832,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T18:36:47.363422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2267724760,"revision":5584,"compact-revision":2917}