{"level":"warn","ts":"2026-04-16T16:25:56.630846Z","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-16T16:25:56.630945Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121: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.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121: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-16T16:25:56.631019Z","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-16T16:25:56.631033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-04-16T16:25:56.631049Z","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-16T16:25:56.631767Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-04-16T16:25:56.63185Z","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.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121: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-16T16:25:56.639081Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.997561ms"} {"level":"info","ts":"2026-04-16T16:25:56.654012Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-04-16T16:25:56.654108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:25:56.654129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:25:56.654149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:25:56.654158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:25:56.654203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-04-16T16:25:56.667451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:25:56.669802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:25:56.672477Z","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-16T16:25:56.676055Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:25:56.676279Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:25:56.676403Z","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-16T16:25:56.676491Z","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-16T16:25:56.676507Z","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-16T16:25:56.677722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-04-16T16:25:56.679354Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-04-16T16:25:56.681252Z","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-16T16:25:56.681326Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-04-16T16:25:56.681362Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-04-16T16:25:56.681543Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:25:56.681582Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:25:57.554922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:25:57.555009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:25:57.555034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-04-16T16:25:57.555062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:25:57.555073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-04-16T16:25:57.555089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:25:57.555102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-04-16T16:25:57.55606Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:25:57.558728Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:25:57.558888Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:25:57.559659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:25:57.559808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:25:57.560116Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:25:57.560467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:25:57.561485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:25:57.568Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-04-16T16:25:57.568224Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:25:57.56824Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-16T16:26:21.831524Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034967852s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:26:21.831838Z","caller":"traceutil/trace.go:171","msg":"trace[1932328971] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"456.318462ms","start":"2026-04-16T16:26:21.375494Z","end":"2026-04-16T16:26:21.831813Z","steps":["trace[1932328971] 'read index received' (duration: 456.23097ms)","trace[1932328971] 'applied index is now lower than readState.Index' (duration: 86.862µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:26:21.831951Z","caller":"traceutil/trace.go:171","msg":"trace[143827947] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.035458723s","start":"2026-04-16T16:26:20.796458Z","end":"2026-04-16T16:26:21.831916Z","steps":["trace[143827947] 'process raft request' (duration: 1.035260759s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:21.832018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.880634ms","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-16T16:26:21.832055Z","caller":"traceutil/trace.go:171","msg":"trace[70238485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"403.959195ms","start":"2026-04-16T16:26:21.42809Z","end":"2026-04-16T16:26:21.832049Z","steps":["trace[70238485] 'agreement among raft nodes before linearized reading' (duration: 403.860073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:21.83208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:21.428052Z","time spent":"404.018307ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:26:21.832061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.558367ms","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-16T16:26:21.832165Z","caller":"traceutil/trace.go:171","msg":"trace[680748851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"456.688691ms","start":"2026-04-16T16:26:21.375462Z","end":"2026-04-16T16:26:21.83215Z","steps":["trace[680748851] 'agreement among raft nodes before linearized reading' (duration: 456.500306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:21.832222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:21.375449Z","time spent":"456.765332ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:26:21.832613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:20.796439Z","time spent":"1.035573016s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:25.396561Z","caller":"traceutil/trace.go:171","msg":"trace[800807435] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"205.488027ms","start":"2026-04-16T16:26:25.191042Z","end":"2026-04-16T16:26:25.39653Z","steps":["trace[800807435] 'process raft request' (duration: 205.079868ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:25.582284Z","caller":"traceutil/trace.go:171","msg":"trace[1357206086] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"152.125044ms","start":"2026-04-16T16:26:25.430133Z","end":"2026-04-16T16:26:25.582258Z","steps":["trace[1357206086] 'process raft request' (duration: 151.980431ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:25.598745Z","caller":"traceutil/trace.go:171","msg":"trace[264017102] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"168.30581ms","start":"2026-04-16T16:26:25.430405Z","end":"2026-04-16T16:26:25.59871Z","steps":["trace[264017102] 'process raft request' (duration: 168.086635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.820585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332004161794597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:26:27.972057Z","caller":"traceutil/trace.go:171","msg":"trace[1819435197] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"678.145621ms","start":"2026-04-16T16:26:27.293887Z","end":"2026-04-16T16:26:27.972032Z","steps":["trace[1819435197] 'process raft request' (duration: 678.001137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.972183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.293865Z","time spent":"678.267344ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.990491Z","caller":"traceutil/trace.go:171","msg":"trace[1792683490] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"682.582728ms","start":"2026-04-16T16:26:27.307876Z","end":"2026-04-16T16:26:27.990459Z","steps":["trace[1792683490] 'process raft request' (duration: 682.449195ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:27.990446Z","caller":"traceutil/trace.go:171","msg":"trace[1013842704] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"685.485842ms","start":"2026-04-16T16:26:27.304953Z","end":"2026-04-16T16:26:27.990439Z","steps":["trace[1013842704] 'process raft request' (duration: 685.318419ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:27.990418Z","caller":"traceutil/trace.go:171","msg":"trace[1032479612] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"688.847196ms","start":"2026-04-16T16:26:27.301526Z","end":"2026-04-16T16:26:27.990373Z","steps":["trace[1032479612] 'process raft request' (duration: 688.59196ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:27.990545Z","caller":"traceutil/trace.go:171","msg":"trace[61225940] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"679.703735ms","start":"2026-04-16T16:26:27.310832Z","end":"2026-04-16T16:26:27.990535Z","steps":["trace[61225940] 'process raft request' (duration: 679.565302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.990627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.30787Z","time spent":"682.679551ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:26:27.990722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.310789Z","time spent":"679.863069ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.990729Z","caller":"traceutil/trace.go:171","msg":"trace[1382952807] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"675.717368ms","start":"2026-04-16T16:26:27.314984Z","end":"2026-04-16T16:26:27.990701Z","steps":["trace[1382952807] 'process raft request' (duration: 675.464822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.990717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.301498Z","time spent":"689.142702ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:26:27.990797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.314928Z","time spent":"675.83521ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.990863Z","caller":"traceutil/trace.go:171","msg":"trace[71347835] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"672.448316ms","start":"2026-04-16T16:26:27.318395Z","end":"2026-04-16T16:26:27.990843Z","steps":["trace[71347835] 'process raft request' (duration: 672.090668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.990664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.304946Z","time spent":"685.625615ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:26:27.991008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.318379Z","time spent":"672.553448ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.991151Z","caller":"traceutil/trace.go:171","msg":"trace[1300528027] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"664.900401ms","start":"2026-04-16T16:26:27.326237Z","end":"2026-04-16T16:26:27.991138Z","steps":["trace[1300528027] 'process raft request' (duration: 664.458991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.991209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.326208Z","time spent":"664.975052ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.991358Z","caller":"traceutil/trace.go:171","msg":"trace[785164424] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"653.782107ms","start":"2026-04-16T16:26:27.337546Z","end":"2026-04-16T16:26:27.991328Z","steps":["trace[785164424] 'process raft request' (duration: 653.560712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.991489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.337521Z","time spent":"653.879188ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.991679Z","caller":"traceutil/trace.go:171","msg":"trace[1436662570] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"638.668335ms","start":"2026-04-16T16:26:27.353Z","end":"2026-04-16T16:26:27.991669Z","steps":["trace[1436662570] 'process raft request' (duration: 638.513682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.991779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.352974Z","time spent":"638.739837ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.991843Z","caller":"traceutil/trace.go:171","msg":"trace[663936167] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"621.659052ms","start":"2026-04-16T16:26:27.370175Z","end":"2026-04-16T16:26:27.991834Z","steps":["trace[663936167] 'process raft request' (duration: 621.431447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.991892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.37016Z","time spent":"621.714964ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:27.992098Z","caller":"traceutil/trace.go:171","msg":"trace[1055723596] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"502.928148ms","start":"2026-04-16T16:26:27.489157Z","end":"2026-04-16T16:26:27.992085Z","steps":["trace[1055723596] 'process raft request' (duration: 502.631602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.992149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.489127Z","time spent":"502.99959ms","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:26:27.992318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.670681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:27.992363Z","caller":"traceutil/trace.go:171","msg":"trace[1577644236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"672.756132ms","start":"2026-04-16T16:26:27.319595Z","end":"2026-04-16T16:26:27.992351Z","steps":["trace[1577644236] 'agreement among raft nodes before linearized reading' (duration: 672.633059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:27.992396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:27.319582Z","time spent":"672.806544ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:26:27.992534Z","caller":"traceutil/trace.go:171","msg":"trace[1237798902] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:477; }","duration":"672.424806ms","start":"2026-04-16T16:26:27.319636Z","end":"2026-04-16T16:26:27.99206Z","steps":["trace[1237798902] 'read index received' (duration: 652.325865ms)","trace[1237798902] 'applied index is now lower than readState.Index' (duration: 20.098431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:28.000579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.307493ms","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-16T16:26:28.000626Z","caller":"traceutil/trace.go:171","msg":"trace[316911295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"138.382144ms","start":"2026-04-16T16:26:27.862235Z","end":"2026-04-16T16:26:28.000617Z","steps":["trace[316911295] 'agreement among raft nodes before linearized reading' (duration: 138.283512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:28.000744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.496621ms","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-16T16:26:28.000974Z","caller":"traceutil/trace.go:171","msg":"trace[369281912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"140.534782ms","start":"2026-04-16T16:26:27.860219Z","end":"2026-04-16T16:26:28.000754Z","steps":["trace[369281912] 'agreement among raft nodes before linearized reading' (duration: 140.504772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:28.001088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.910924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:26:28.001104Z","caller":"traceutil/trace.go:171","msg":"trace[273931872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"147.927584ms","start":"2026-04-16T16:26:27.853171Z","end":"2026-04-16T16:26:28.001098Z","steps":["trace[273931872] 'agreement among raft nodes before linearized reading' (duration: 147.894563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:29.083125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332004161794650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:26:29.584053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332004161794650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:26:29.891338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489715006s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:26:29.891476Z","caller":"traceutil/trace.go:171","msg":"trace[1109139015] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.608063328s","start":"2026-04-16T16:26:28.283387Z","end":"2026-04-16T16:26:29.89145Z","steps":["trace[1109139015] 'process raft request' (duration: 117.966774ms)","trace[1109139015] 'compare' (duration: 1.488844996s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:29.891568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:28.28336Z","time spent":"1.60816576s","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:30.084511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332004161794650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:26:30.468512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.850367128s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T16:26:30.582548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999966229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T16:26:30.582677Z","caller":"traceutil/trace.go:171","msg":"trace[2012991941] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000174603s","start":"2026-04-16T16:26:28.582476Z","end":"2026-04-16T16:26:30.582651Z","steps":["trace[2012991941] 'agreement among raft nodes before linearized reading' (duration: 2.00000208s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:30.582735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:28.582455Z","time spent":"2.000267725s","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:26:30.707299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.332228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:26:30.707495Z","caller":"traceutil/trace.go:171","msg":"trace[99606136] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"2.376909069s","start":"2026-04-16T16:26:28.330569Z","end":"2026-04-16T16:26:30.707478Z","steps":["trace[99606136] 'process raft request' (duration: 2.376838648s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:30.707552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:28.330546Z","time spent":"2.37698155s","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:30.707544Z","caller":"traceutil/trace.go:171","msg":"trace[399664858] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"2.420711228s","start":"2026-04-16T16:26:28.286779Z","end":"2026-04-16T16:26:30.70749Z","steps":["trace[399664858] 'process raft request' (duration: 2.182105995s)","trace[399664858] 'compare' (duration: 238.022381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:30.707728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:28.286752Z","time spent":"2.420881772s","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:31.317041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999984712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T16:26:31.317242Z","caller":"traceutil/trace.go:171","msg":"trace[1006504054] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000237708s","start":"2026-04-16T16:26:29.316978Z","end":"2026-04-16T16:26:31.317216Z","steps":["trace[1006504054] 'agreement among raft nodes before linearized reading' (duration: 2.000021193s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:31.317322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:29.316957Z","time spent":"2.00035095s","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/16 16:26:31 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-16T16:26:32.655037Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.186134192s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T16:26:33.324703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000066925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-16T16:26:33.324819Z","caller":"traceutil/trace.go:171","msg":"trace[430818753] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000252529s","start":"2026-04-16T16:26:31.324548Z","end":"2026-04-16T16:26:33.324801Z","steps":["trace[430818753] 'agreement among raft nodes before linearized reading' (duration: 2.000096615s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.324864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:31.324532Z","time spent":"2.00032143s","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/16 16:26:33 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-16T16:26:33.34077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.236922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:26:33.341016Z","caller":"traceutil/trace.go:171","msg":"trace[1993367886] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"3.43398976s","start":"2026-04-16T16:26:29.907001Z","end":"2026-04-16T16:26:33.34099Z","steps":["trace[1993367886] 'process raft request' (duration: 2.748430982s)","trace[1993367886] 'compare' (duration: 685.069308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:33.341153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:29.906978Z","time spent":"3.434089153s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:33.341166Z","caller":"traceutil/trace.go:171","msg":"trace[47608454] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:533; }","duration":"4.75861251s","start":"2026-04-16T16:26:28.582533Z","end":"2026-04-16T16:26:33.341145Z","steps":["trace[47608454] 'read index received' (duration: 1.886300896s)","trace[47608454] 'applied index is now lower than readState.Index' (duration: 2.872310664s)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:26:33.34154Z","caller":"traceutil/trace.go:171","msg":"trace[40318289] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"3.36449411s","start":"2026-04-16T16:26:29.977028Z","end":"2026-04-16T16:26:33.341522Z","steps":["trace[40318289] 'process raft request' (duration: 3.363928888s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.341635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:29.976988Z","time spent":"3.364600122s","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:33.362571Z","caller":"traceutil/trace.go:171","msg":"trace[604012958] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"2.621366289s","start":"2026-04-16T16:26:30.74118Z","end":"2026-04-16T16:26:33.362546Z","steps":["trace[604012958] 'process raft request' (duration: 2.621288067s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:33.362667Z","caller":"traceutil/trace.go:171","msg":"trace[588656476] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"2.776321315s","start":"2026-04-16T16:26:30.586267Z","end":"2026-04-16T16:26:33.362588Z","steps":["trace[588656476] 'process raft request' (duration: 2.776051579s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.362739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.741158Z","time spent":"2.621508942s","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:33.362741Z","caller":"traceutil/trace.go:171","msg":"trace[323827954] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"2.628223419s","start":"2026-04-16T16:26:30.734426Z","end":"2026-04-16T16:26:33.362649Z","steps":["trace[323827954] 'process raft request' (duration: 2.627993244s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.36297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.734404Z","time spent":"2.628456514s","remote":"127.0.0.1:42454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:26:33.362967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.586245Z","time spent":"2.776522599s","remote":"127.0.0.1:42490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:33.363543Z","caller":"traceutil/trace.go:171","msg":"trace[503954746] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"385.543987ms","start":"2026-04-16T16:26:32.977976Z","end":"2026-04-16T16:26:33.36352Z","steps":["trace[503954746] 'process raft request' (duration: 385.383703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.363569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.907600644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T16:26:33.364206Z","caller":"traceutil/trace.go:171","msg":"trace[749818646] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:1; response_revision:524; }","duration":"2.908263098s","start":"2026-04-16T16:26:30.455929Z","end":"2026-04-16T16:26:33.364192Z","steps":["trace[749818646] 'agreement among raft nodes before linearized reading' (duration: 2.907574153s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.364264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.455911Z","time spent":"2.908322059s","remote":"127.0.0.1:42434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.121\" "} {"level":"warn","ts":"2026-04-16T16:26:33.363578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.333313216s","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-16T16:26:33.364309Z","caller":"traceutil/trace.go:171","msg":"trace[479362709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"3.334088893s","start":"2026-04-16T16:26:30.030207Z","end":"2026-04-16T16:26:33.364296Z","steps":["trace[479362709] 'agreement among raft nodes before linearized reading' (duration: 3.333274825s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.364344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.030186Z","time spent":"3.334148635s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:26:33.36369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.189160966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T16:26:33.364455Z","caller":"traceutil/trace.go:171","msg":"trace[995221618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"3.189978704s","start":"2026-04-16T16:26:30.174468Z","end":"2026-04-16T16:26:33.364447Z","steps":["trace[995221618] 'agreement among raft nodes before linearized reading' (duration: 3.189132846s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.364478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.174407Z","time spent":"3.190064226s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:26:33.363762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.327937309s","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-16T16:26:33.364559Z","caller":"traceutil/trace.go:171","msg":"trace[1759721611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"3.328750606s","start":"2026-04-16T16:26:30.035801Z","end":"2026-04-16T16:26:33.364551Z","steps":["trace[1759721611] 'agreement among raft nodes before linearized reading' (duration: 3.327924338s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.364606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.035794Z","time spent":"3.328786427s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:26:33.364461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:32.977945Z","time spent":"386.138739ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:33.363813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.329565144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:26:33.364744Z","caller":"traceutil/trace.go:171","msg":"trace[1688623752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"3.330494454s","start":"2026-04-16T16:26:30.034242Z","end":"2026-04-16T16:26:33.364737Z","steps":["trace[1688623752] 'agreement among raft nodes before linearized reading' (duration: 3.329533973s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:33.364847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:30.034211Z","time spent":"3.330627468s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:26:37.818034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332004161794809,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:26:38.011454Z","caller":"traceutil/trace.go:171","msg":"trace[1956010552] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"694.51115ms","start":"2026-04-16T16:26:37.31691Z","end":"2026-04-16T16:26:38.011421Z","steps":["trace[1956010552] 'read index received' (duration: 693.187681ms)","trace[1956010552] 'applied index is now lower than readState.Index' (duration: 1.319129ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:26:38.011598Z","caller":"traceutil/trace.go:171","msg":"trace[1868048947] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"796.731716ms","start":"2026-04-16T16:26:37.214812Z","end":"2026-04-16T16:26:38.011544Z","steps":["trace[1868048947] 'process raft request' (duration: 795.838707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.011736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.798896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.011785Z","caller":"traceutil/trace.go:171","msg":"trace[183829295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"694.933659ms","start":"2026-04-16T16:26:37.31684Z","end":"2026-04-16T16:26:38.011774Z","steps":["trace[183829295] 'agreement among raft nodes before linearized reading' (duration: 694.774846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.011825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.316813Z","time spent":"695.00423ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:26:38.011842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.214769Z","time spent":"796.953671ms","remote":"127.0.0.1:42512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:38.011882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.069588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.011936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.851933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.012Z","caller":"traceutil/trace.go:171","msg":"trace[600687483] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:567; }","duration":"419.935636ms","start":"2026-04-16T16:26:37.592045Z","end":"2026-04-16T16:26:38.011981Z","steps":["trace[600687483] 'agreement among raft nodes before linearized reading' (duration: 419.805083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.59204Z","time spent":"420.003167ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:26:38.012076Z","caller":"traceutil/trace.go:171","msg":"trace[1107521244] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"675.297284ms","start":"2026-04-16T16:26:37.336675Z","end":"2026-04-16T16:26:38.011973Z","steps":["trace[1107521244] 'agreement among raft nodes before linearized reading' (duration: 675.031308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.336656Z","time spent":"675.474127ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:26:38.012286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.169588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.012324Z","caller":"traceutil/trace.go:171","msg":"trace[215617714] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"531.26441ms","start":"2026-04-16T16:26:37.481051Z","end":"2026-04-16T16:26:38.012315Z","steps":["trace[215617714] 'agreement among raft nodes before linearized reading' (duration: 531.203749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.290907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.012348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.481006Z","time spent":"531.335302ms","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.012364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.469169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.012373Z","caller":"traceutil/trace.go:171","msg":"trace[1629748786] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:567; }","duration":"470.358419ms","start":"2026-04-16T16:26:37.542003Z","end":"2026-04-16T16:26:38.012361Z","steps":["trace[1629748786] 'agreement among raft nodes before linearized reading' (duration: 470.292178ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:38.012398Z","caller":"traceutil/trace.go:171","msg":"trace[542399505] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:567; }","duration":"314.531939ms","start":"2026-04-16T16:26:37.697854Z","end":"2026-04-16T16:26:38.012386Z","steps":["trace[542399505] 'agreement among raft nodes before linearized reading' (duration: 314.459868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.69783Z","time spent":"314.603502ms","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:26:38.012454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.424786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.012486Z","caller":"traceutil/trace.go:171","msg":"trace[1120451199] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:567; }","duration":"420.457516ms","start":"2026-04-16T16:26:37.592021Z","end":"2026-04-16T16:26:38.012478Z","steps":["trace[1120451199] 'agreement among raft nodes before linearized reading' (duration: 420.409285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.591965Z","time spent":"420.546748ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.01249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.347452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.01253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.404302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.01267Z","caller":"traceutil/trace.go:171","msg":"trace[1612475350] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"531.593307ms","start":"2026-04-16T16:26:37.481066Z","end":"2026-04-16T16:26:38.012659Z","steps":["trace[1612475350] 'agreement among raft nodes before linearized reading' (duration: 531.441934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.604774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.012697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.019612ms","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-16T16:26:38.012711Z","caller":"traceutil/trace.go:171","msg":"trace[235585584] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:567; }","duration":"470.645325ms","start":"2026-04-16T16:26:37.542058Z","end":"2026-04-16T16:26:38.012703Z","steps":["trace[235585584] 'agreement among raft nodes before linearized reading' (duration: 470.596754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.957965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.012736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.542052Z","time spent":"470.677076ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"info","ts":"2026-04-16T16:26:38.012734Z","caller":"traceutil/trace.go:171","msg":"trace[2068713319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"607.071594ms","start":"2026-04-16T16:26:37.405651Z","end":"2026-04-16T16:26:38.012722Z","steps":["trace[2068713319] 'agreement among raft nodes before linearized reading' (duration: 606.971982ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:38.012758Z","caller":"traceutil/trace.go:171","msg":"trace[703494001] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:567; }","duration":"336.013385ms","start":"2026-04-16T16:26:37.676734Z","end":"2026-04-16T16:26:38.012747Z","steps":["trace[703494001] 'agreement among raft nodes before linearized reading' (duration: 335.963734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.405638Z","time spent":"607.122055ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:26:38.012794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.676721Z","time spent":"336.061887ms","remote":"127.0.0.1:45096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.012679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.712099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.012848Z","caller":"traceutil/trace.go:171","msg":"trace[1832719172] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:567; }","duration":"335.929003ms","start":"2026-04-16T16:26:37.67691Z","end":"2026-04-16T16:26:38.012839Z","steps":["trace[1832719172] 'agreement among raft nodes before linearized reading' (duration: 335.747799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.01288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.676904Z","time spent":"335.967165ms","remote":"127.0.0.1:45096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:26:38.012892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.660292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vlshj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T16:26:38.012927Z","caller":"traceutil/trace.go:171","msg":"trace[461861606] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vlshj; range_end:; response_count:1; response_revision:567; }","duration":"658.720154ms","start":"2026-04-16T16:26:37.354198Z","end":"2026-04-16T16:26:38.012918Z","steps":["trace[461861606] 'agreement among raft nodes before linearized reading' (duration: 658.644312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.165312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.012964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.354187Z","time spent":"658.771825ms","remote":"127.0.0.1:42584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vlshj\" "} {"level":"warn","ts":"2026-04-16T16:26:38.012989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.612725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.013008Z","caller":"traceutil/trace.go:171","msg":"trace[1598131688] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"676.201563ms","start":"2026-04-16T16:26:37.336764Z","end":"2026-04-16T16:26:38.012966Z","steps":["trace[1598131688] 'agreement among raft nodes before linearized reading' (duration: 676.146572ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:38.013031Z","caller":"traceutil/trace.go:171","msg":"trace[285069478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:567; }","duration":"352.692367ms","start":"2026-04-16T16:26:37.660328Z","end":"2026-04-16T16:26:38.013021Z","steps":["trace[285069478] 'agreement among raft nodes before linearized reading' (duration: 352.630026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.336698Z","time spent":"676.335545ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.013066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.660302Z","time spent":"352.755099ms","remote":"127.0.0.1:45082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.012595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.894918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T16:26:38.013142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.89989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:26:38.013162Z","caller":"traceutil/trace.go:171","msg":"trace[1743415015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"606.46434ms","start":"2026-04-16T16:26:37.406686Z","end":"2026-04-16T16:26:38.013151Z","steps":["trace[1743415015] 'agreement among raft nodes before linearized reading' (duration: 605.785125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.406655Z","time spent":"606.535572ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:26:38.013235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.520275ms","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-16T16:26:38.01326Z","caller":"traceutil/trace.go:171","msg":"trace[330624364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"608.602166ms","start":"2026-04-16T16:26:37.404652Z","end":"2026-04-16T16:26:38.013254Z","steps":["trace[330624364] 'agreement among raft nodes before linearized reading' (duration: 608.534585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.024764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.013288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.404626Z","time spent":"608.656398ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T16:26:38.013311Z","caller":"traceutil/trace.go:171","msg":"trace[1937887686] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:567; }","duration":"353.087356ms","start":"2026-04-16T16:26:37.660213Z","end":"2026-04-16T16:26:38.0133Z","steps":["trace[1937887686] 'agreement among raft nodes before linearized reading' (duration: 353.031455ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:38.013171Z","caller":"traceutil/trace.go:171","msg":"trace[148094746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"612.972411ms","start":"2026-04-16T16:26:37.40019Z","end":"2026-04-16T16:26:38.013163Z","steps":["trace[148094746] 'agreement among raft nodes before linearized reading' (duration: 612.807808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.01335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.400158Z","time spent":"613.187796ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:26:38.013352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.660195Z","time spent":"353.147947ms","remote":"127.0.0.1:45082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:26:38.012293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.658381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.013478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.832429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.013488Z","caller":"traceutil/trace.go:171","msg":"trace[1999017842] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:567; }","duration":"314.849917ms","start":"2026-04-16T16:26:37.698622Z","end":"2026-04-16T16:26:38.013472Z","steps":["trace[1999017842] 'agreement among raft nodes before linearized reading' (duration: 313.641001ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:38.01351Z","caller":"traceutil/trace.go:171","msg":"trace[985401861] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:567; }","duration":"381.864959ms","start":"2026-04-16T16:26:37.631634Z","end":"2026-04-16T16:26:38.013498Z","steps":["trace[985401861] 'agreement among raft nodes before linearized reading' (duration: 381.817398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.698551Z","time spent":"314.971709ms","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:26:38.013567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.197799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.013601Z","caller":"traceutil/trace.go:171","msg":"trace[1816867923] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:567; }","duration":"383.275701ms","start":"2026-04-16T16:26:37.630316Z","end":"2026-04-16T16:26:38.013591Z","steps":["trace[1816867923] 'agreement among raft nodes before linearized reading' (duration: 383.216439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.63028Z","time spent":"383.351192ms","remote":"127.0.0.1:45076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.012638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.786189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.012715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.481033Z","time spent":"531.669808ms","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:26:38.013684Z","caller":"traceutil/trace.go:171","msg":"trace[1312229206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:567; }","duration":"335.865572ms","start":"2026-04-16T16:26:37.677806Z","end":"2026-04-16T16:26:38.013672Z","steps":["trace[1312229206] 'agreement among raft nodes before linearized reading' (duration: 334.81279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.677785Z","time spent":"335.926383ms","remote":"127.0.0.1:45082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:26:38.012599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.97963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.013799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.530196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:38.01354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.631612Z","time spent":"381.919381ms","remote":"127.0.0.1:45076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-16T16:26:38.013838Z","caller":"traceutil/trace.go:171","msg":"trace[1146348230] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:567; }","duration":"383.619658ms","start":"2026-04-16T16:26:37.630209Z","end":"2026-04-16T16:26:38.013829Z","steps":["trace[1146348230] 'agreement among raft nodes before linearized reading' (duration: 383.556486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.013869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.630191Z","time spent":"383.669539ms","remote":"127.0.0.1:45076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:26:38.012639Z","caller":"traceutil/trace.go:171","msg":"trace[1862035579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"531.508185ms","start":"2026-04-16T16:26:37.481121Z","end":"2026-04-16T16:26:38.012629Z","steps":["trace[1862035579] 'agreement among raft nodes before linearized reading' (duration: 531.339701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.012408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.541988Z","time spent":"470.41105ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:26:38.013957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.481033Z","time spent":"532.911615ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"info","ts":"2026-04-16T16:26:38.013804Z","caller":"traceutil/trace.go:171","msg":"trace[874125930] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:567; }","duration":"316.199886ms","start":"2026-04-16T16:26:37.697591Z","end":"2026-04-16T16:26:38.01379Z","steps":["trace[874125930] 'agreement among raft nodes before linearized reading' (duration: 314.98388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.014025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.69758Z","time spent":"316.430861ms","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T16:26:38.01418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.568631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:38.01431Z","caller":"traceutil/trace.go:171","msg":"trace[697277115] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:567; }","duration":"421.683133ms","start":"2026-04-16T16:26:37.592566Z","end":"2026-04-16T16:26:38.014249Z","steps":["trace[697277115] 'agreement among raft nodes before linearized reading' (duration: 421.54399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:38.014374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:37.592558Z","time spent":"421.790446ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-04-16T16:26:38.818357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332004161794813,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:26:39.163888Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127217416s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:26:39.164656Z","caller":"traceutil/trace.go:171","msg":"trace[461773836] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.135861633s","start":"2026-04-16T16:26:38.028773Z","end":"2026-04-16T16:26:39.164634Z","steps":["trace[461773836] 'process raft request' (duration: 1.135816832s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:39.164658Z","caller":"traceutil/trace.go:171","msg":"trace[1370448563] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.136072118s","start":"2026-04-16T16:26:38.02855Z","end":"2026-04-16T16:26:39.164622Z","steps":["trace[1370448563] 'process raft request' (duration: 1.1357141s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:39.164808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:38.028769Z","time spent":"1.135945165s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:39.164823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:38.028533Z","time spent":"1.13618455s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:39.165046Z","caller":"traceutil/trace.go:171","msg":"trace[1369228887] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.136289402s","start":"2026-04-16T16:26:38.028742Z","end":"2026-04-16T16:26:39.165032Z","steps":["trace[1369228887] 'process raft request' (duration: 1.135791831s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:39.165116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:38.028738Z","time spent":"1.136339123s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:26:40.269445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105059059s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:26:40.269913Z","caller":"traceutil/trace.go:171","msg":"trace[1121574549] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"2.235334171s","start":"2026-04-16T16:26:38.034536Z","end":"2026-04-16T16:26:40.26987Z","steps":["trace[1121574549] 'process raft request' (duration: 2.235086796s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:40.270091Z","caller":"traceutil/trace.go:171","msg":"trace[864064002] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:587; }","duration":"1.9528038s","start":"2026-04-16T16:26:38.317247Z","end":"2026-04-16T16:26:40.270051Z","steps":["trace[864064002] 'read index received' (duration: 846.994805ms)","trace[864064002] 'applied index is now lower than readState.Index' (duration: 1.105806395s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:40.270128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:38.034526Z","time spent":"2.235492504s","remote":"127.0.0.1:42584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:26:40.270163Z","caller":"traceutil/trace.go:171","msg":"trace[39093002] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"2.180305769s","start":"2026-04-16T16:26:38.089822Z","end":"2026-04-16T16:26:40.270127Z","steps":["trace[39093002] 'process raft request' (duration: 2.179964591s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:40.270303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.953043506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:26:40.270317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:38.089789Z","time spent":"2.180454552s","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:26:40.270344Z","caller":"traceutil/trace.go:171","msg":"trace[1147351089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"1.953108467s","start":"2026-04-16T16:26:38.317223Z","end":"2026-04-16T16:26:40.270332Z","steps":["trace[1147351089] 'agreement among raft nodes before linearized reading' (duration: 1.952972134s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:40.270382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:26:38.317128Z","time spent":"1.95324564s","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:26:40.270867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.123048ms","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-16T16:26:40.270923Z","caller":"traceutil/trace.go:171","msg":"trace[723680017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"232.21817ms","start":"2026-04-16T16:26:40.038687Z","end":"2026-04-16T16:26:40.270905Z","steps":["trace[723680017] 'agreement among raft nodes before linearized reading' (duration: 231.823652ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:42.011426Z","caller":"traceutil/trace.go:171","msg":"trace[118653162] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"203.885113ms","start":"2026-04-16T16:26:41.807512Z","end":"2026-04-16T16:26:42.011397Z","steps":["trace[118653162] 'process raft request' (duration: 203.405133ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:44.179537Z","caller":"traceutil/trace.go:171","msg":"trace[995316152] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"165.211488ms","start":"2026-04-16T16:26:44.014286Z","end":"2026-04-16T16:26:44.179498Z","steps":["trace[995316152] 'process raft request' (duration: 165.025094ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:44.295256Z","caller":"traceutil/trace.go:171","msg":"trace[1766753372] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"109.608811ms","start":"2026-04-16T16:26:44.185621Z","end":"2026-04-16T16:26:44.29523Z","steps":["trace[1766753372] 'process raft request' (duration: 109.182141ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:44.299126Z","caller":"traceutil/trace.go:171","msg":"trace[1020768989] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"103.624312ms","start":"2026-04-16T16:26:44.195476Z","end":"2026-04-16T16:26:44.299101Z","steps":["trace[1020768989] 'read index received' (duration: 99.36423ms)","trace[1020768989] 'applied index is now lower than readState.Index' (duration: 4.259202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:44.299367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.872627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-16T16:26:44.299411Z","caller":"traceutil/trace.go:171","msg":"trace[1212938998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:651; }","duration":"103.962029ms","start":"2026-04-16T16:26:44.195436Z","end":"2026-04-16T16:26:44.299398Z","steps":["trace[1212938998] 'agreement among raft nodes before linearized reading' (duration: 103.775015ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:26:44.299447Z","caller":"traceutil/trace.go:171","msg":"trace[228022003] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"110.98363ms","start":"2026-04-16T16:26:44.188428Z","end":"2026-04-16T16:26:44.299411Z","steps":["trace[228022003] 'process raft request' (duration: 110.562471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:56.559812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.270368ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T16:26:56.560108Z","caller":"traceutil/trace.go:171","msg":"trace[148543698] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"244.662165ms","start":"2026-04-16T16:26:56.315415Z","end":"2026-04-16T16:26:56.560077Z","steps":["trace[148543698] 'read index received' (duration: 52.631µs)","trace[148543698] 'applied index is now lower than readState.Index' (duration: 244.606864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:26:56.560372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.907981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:26:56.561948Z","caller":"traceutil/trace.go:171","msg":"trace[277935240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"246.583376ms","start":"2026-04-16T16:26:56.315338Z","end":"2026-04-16T16:26:56.561921Z","steps":["trace[277935240] 'agreement among raft nodes before linearized reading' (duration: 244.946932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:26:56.560464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.796625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T16:26:56.562089Z","caller":"traceutil/trace.go:171","msg":"trace[1821134410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"137.414889ms","start":"2026-04-16T16:26:56.424623Z","end":"2026-04-16T16:26:56.562038Z","steps":["trace[1821134410] 'agreement among raft nodes before linearized reading' (duration: 135.745553ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:19.557372Z","caller":"traceutil/trace.go:171","msg":"trace[1080703634] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"157.238251ms","start":"2026-04-16T16:27:19.400109Z","end":"2026-04-16T16:27:19.557347Z","steps":["trace[1080703634] 'process raft request' (duration: 119.303265ms)","trace[1080703634] 'compare' (duration: 37.762572ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:27:19.559241Z","caller":"traceutil/trace.go:171","msg":"trace[300183832] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"157.114279ms","start":"2026-04-16T16:27:19.402095Z","end":"2026-04-16T16:27:19.559209Z","steps":["trace[300183832] 'process raft request' (duration: 156.986976ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:21.543027Z","caller":"traceutil/trace.go:171","msg":"trace[1958377183] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"137.371381ms","start":"2026-04-16T16:27:21.405633Z","end":"2026-04-16T16:27:21.543004Z","steps":["trace[1958377183] 'process raft request' (duration: 137.238758ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:37.868786Z","caller":"traceutil/trace.go:171","msg":"trace[390323506] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"154.444915ms","start":"2026-04-16T16:27:37.714322Z","end":"2026-04-16T16:27:37.868767Z","steps":["trace[390323506] 'process raft request' (duration: 154.311632ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:37.868817Z","caller":"traceutil/trace.go:171","msg":"trace[475671495] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1049; }","duration":"151.289789ms","start":"2026-04-16T16:27:37.717485Z","end":"2026-04-16T16:27:37.868775Z","steps":["trace[475671495] 'read index received' (duration: 151.270669ms)","trace[475671495] 'applied index is now lower than readState.Index' (duration: 15.89µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:27:37.869162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.654357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:27:37.869214Z","caller":"traceutil/trace.go:171","msg":"trace[455824710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"151.766849ms","start":"2026-04-16T16:27:37.717433Z","end":"2026-04-16T16:27:37.8692Z","steps":["trace[455824710] 'agreement among raft nodes before linearized reading' (duration: 151.475663ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:27:37.910714Z","caller":"traceutil/trace.go:171","msg":"trace[487012473] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"124.934421ms","start":"2026-04-16T16:27:37.785747Z","end":"2026-04-16T16:27:37.910681Z","steps":["trace[487012473] 'process raft request' (duration: 124.718286ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:28:52.375348Z","caller":"traceutil/trace.go:171","msg":"trace[2049599998] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"128.072786ms","start":"2026-04-16T16:28:52.247248Z","end":"2026-04-16T16:28:52.37532Z","steps":["trace[2049599998] 'process raft request' (duration: 121.123384ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:28:53.807689Z","caller":"traceutil/trace.go:171","msg":"trace[1234957134] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"348.816105ms","start":"2026-04-16T16:28:53.458841Z","end":"2026-04-16T16:28:53.807657Z","steps":["trace[1234957134] 'read index received' (duration: 348.633021ms)","trace[1234957134] 'applied index is now lower than readState.Index' (duration: 181.744µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:28:53.807985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.126071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T16:28:53.808046Z","caller":"traceutil/trace.go:171","msg":"trace[1325309885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"349.242714ms","start":"2026-04-16T16:28:53.458782Z","end":"2026-04-16T16:28:53.808025Z","steps":["trace[1325309885] 'agreement among raft nodes before linearized reading' (duration: 349.029809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:28:53.808091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:28:53.458755Z","time spent":"349.324436ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T16:28:53.808444Z","caller":"traceutil/trace.go:171","msg":"trace[109072596] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"394.947538ms","start":"2026-04-16T16:28:53.413476Z","end":"2026-04-16T16:28:53.808423Z","steps":["trace[109072596] 'process raft request' (duration: 394.007248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:28:53.80857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:28:53.413455Z","time spent":"395.041819ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:28:54.615911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.095189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:28:54.616023Z","caller":"traceutil/trace.go:171","msg":"trace[78105226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"300.218631ms","start":"2026-04-16T16:28:54.315779Z","end":"2026-04-16T16:28:54.615997Z","steps":["trace[78105226] 'range keys from in-memory index tree' (duration: 299.998457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:28:54.616076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:28:54.315707Z","time spent":"300.356045ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:28:54.616195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.643437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T16:28:54.616239Z","caller":"traceutil/trace.go:171","msg":"trace[1581251904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"175.722099ms","start":"2026-04-16T16:28:54.440505Z","end":"2026-04-16T16:28:54.616227Z","steps":["trace[1581251904] 'range keys from in-memory index tree' (duration: 175.486383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:28:54.61656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.013691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T16:28:54.616615Z","caller":"traceutil/trace.go:171","msg":"trace[866787909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"237.102503ms","start":"2026-04-16T16:28:54.379497Z","end":"2026-04-16T16:28:54.6166Z","steps":["trace[866787909] 'range keys from in-memory index tree' (duration: 236.796866ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:28:54.806249Z","caller":"traceutil/trace.go:171","msg":"trace[110423041] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"185.725873ms","start":"2026-04-16T16:28:54.620475Z","end":"2026-04-16T16:28:54.8062Z","steps":["trace[110423041] 'process raft request' (duration: 147.594084ms)","trace[110423041] 'compare' (duration: 38.007646ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:28:57.935996Z","caller":"traceutil/trace.go:171","msg":"trace[1490985302] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"112.455986ms","start":"2026-04-16T16:28:57.823505Z","end":"2026-04-16T16:28:57.935961Z","steps":["trace[1490985302] 'process raft request' (duration: 112.16227ms)"],"step_count":1}