{"level":"warn","ts":"2026-04-01T22:43:52.45322Z","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-01T22:43:52.4533Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-01T22:43:52.453367Z","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-01T22:43:52.45338Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-01T22:43:52.453395Z","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-01T22:43:52.45401Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-01T22:43:52.454086Z","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.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-01T22:43:52.459114Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.813917ms"} {"level":"info","ts":"2026-04-01T22:43:52.465569Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-01T22:43:52.465687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T22:43:52.465728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-01T22:43:52.465754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T22:43:52.465778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-01T22:43:52.465847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-01T22:43:52.472445Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T22:43:52.473955Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T22:43:52.475281Z","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-01T22:43:52.478368Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T22:43:52.478689Z","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-01T22:43:52.478652Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T22:43:52.478745Z","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-01T22:43:52.478756Z","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-01T22:43:52.482779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-01T22:43:52.483308Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-01T22:43:52.485403Z","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-01T22:43:52.486538Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-01T22:43:52.486588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-01T22:43:52.487649Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T22:43:52.487701Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T22:43:52.86686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T22:43:52.866921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T22:43:52.866945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-01T22:43:52.866965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-01T22:43:52.866976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-01T22:43:52.866992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-01T22:43:52.867005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-01T22:43:52.867993Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T22:43:52.869425Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T22:43:52.869518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T22:43:52.870102Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T22:43:52.87013Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T22:43:52.870255Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T22:43:52.870294Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T22:43:52.870471Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T22:43:52.870527Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T22:43:52.874318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T22:43:52.876674Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"warn","ts":"2026-04-01T22:43:58.267168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.869748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:43:58.267488Z","caller":"traceutil/trace.go:171","msg":"trace[1265320332] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"109.470405ms","start":"2026-04-01T22:43:58.157966Z","end":"2026-04-01T22:43:58.267436Z","steps":["trace[1265320332] 'read index received' (duration: 35.191µs)","trace[1265320332] 'applied index is now lower than readState.Index' (duration: 109.432634ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:43:58.267584Z","caller":"traceutil/trace.go:171","msg":"trace[1687439927] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"123.644667ms","start":"2026-04-01T22:43:58.143912Z","end":"2026-04-01T22:43:58.267556Z","steps":["trace[1687439927] 'compare' (duration: 114.700233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:43:58.267725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.726863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:43:58.267816Z","caller":"traceutil/trace.go:171","msg":"trace[645704505] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:258; }","duration":"109.826535ms","start":"2026-04-01T22:43:58.15796Z","end":"2026-04-01T22:43:58.267787Z","steps":["trace[645704505] 'agreement among raft nodes before linearized reading' (duration: 109.62809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:43:59.340768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898291924276319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T22:43:59.73913Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.370388567s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-01T22:43:59.820623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451792666s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:43:59.821004Z","caller":"traceutil/trace.go:171","msg":"trace[904623507] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"1.545767344s","start":"2026-04-01T22:43:58.275216Z","end":"2026-04-01T22:43:59.820984Z","steps":["trace[904623507] 'process raft request' (duration: 1.545709402s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:43:59.821306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.275208Z","time spent":"1.545837336s","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:43:59.821638Z","caller":"traceutil/trace.go:171","msg":"trace[1669143964] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"1.547467568s","start":"2026-04-01T22:43:58.274105Z","end":"2026-04-01T22:43:59.821572Z","steps":["trace[1669143964] 'process raft request' (duration: 1.546719848s)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:43:59.821736Z","caller":"traceutil/trace.go:171","msg":"trace[1501246001] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"1.550555209s","start":"2026-04-01T22:43:58.27116Z","end":"2026-04-01T22:43:59.821716Z","steps":["trace[1501246001] 'process raft request' (duration: 97.537042ms)","trace[1501246001] 'compare' (duration: 1.450569473s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:43:59.821839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.271138Z","time spent":"1.550637461s","remote":"127.0.0.1:42862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:43:59.821862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.274081Z","time spent":"1.547671203s","remote":"127.0.0.1:42708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:43:59.821652Z","caller":"traceutil/trace.go:171","msg":"trace[1409444557] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"1.550416726s","start":"2026-04-01T22:43:58.271184Z","end":"2026-04-01T22:43:59.821601Z","steps":["trace[1409444557] 'process raft request' (duration: 1.549555833s)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:43:59.822144Z","caller":"traceutil/trace.go:171","msg":"trace[355773790] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"1.547007176s","start":"2026-04-01T22:43:58.27512Z","end":"2026-04-01T22:43:59.822128Z","steps":["trace[355773790] 'process raft request' (duration: 1.545751484s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:43:59.822153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.27117Z","time spent":"1.550938029s","remote":"127.0.0.1:42570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:43:59.82223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.275101Z","time spent":"1.547073157s","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:00.177675Z","caller":"traceutil/trace.go:171","msg":"trace[1501473406] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:267; }","duration":"1.33739277s","start":"2026-04-01T22:43:58.840263Z","end":"2026-04-01T22:44:00.177656Z","steps":["trace[1501473406] 'read index received' (duration: 899.071347ms)","trace[1501473406] 'applied index is now lower than readState.Index' (duration: 438.320423ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:00.177767Z","caller":"traceutil/trace.go:171","msg":"trace[877332636] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"1.320360763s","start":"2026-04-01T22:43:58.857401Z","end":"2026-04-01T22:44:00.177762Z","steps":["trace[877332636] 'process raft request' (duration: 1.320227209s)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:00.177762Z","caller":"traceutil/trace.go:171","msg":"trace[1727277308] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"1.321755s","start":"2026-04-01T22:43:58.855978Z","end":"2026-04-01T22:44:00.177733Z","steps":["trace[1727277308] 'process raft request' (duration: 1.321542805s)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:00.177813Z","caller":"traceutil/trace.go:171","msg":"trace[1673072669] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"1.320234139s","start":"2026-04-01T22:43:58.857561Z","end":"2026-04-01T22:44:00.177795Z","steps":["trace[1673072669] 'process raft request' (duration: 1.320080695s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.177884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.857374Z","time spent":"1.320428034s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:00.177894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337622396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"warn","ts":"2026-04-01T22:44:00.17791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.857534Z","time spent":"1.320333312s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:00.177922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.855939Z","time spent":"1.321905584s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:00.177975Z","caller":"traceutil/trace.go:171","msg":"trace[1353780488] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:263; }","duration":"1.337717449s","start":"2026-04-01T22:43:58.84023Z","end":"2026-04-01T22:44:00.177948Z","steps":["trace[1353780488] 'agreement among raft nodes before linearized reading' (duration: 1.337590075s)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:00.177698Z","caller":"traceutil/trace.go:171","msg":"trace[1345214406] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"1.320526487s","start":"2026-04-01T22:43:58.857157Z","end":"2026-04-01T22:44:00.177683Z","steps":["trace[1345214406] 'process raft request' (duration: 1.320446615s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.178022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.840211Z","time spent":"1.337803s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3998,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-01T22:44:00.178082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.857141Z","time spent":"1.320908316s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:00.177737Z","caller":"traceutil/trace.go:171","msg":"trace[282834997] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"1.321112292s","start":"2026-04-01T22:43:58.856619Z","end":"2026-04-01T22:44:00.177731Z","steps":["trace[282834997] 'process raft request' (duration: 1.320969548s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.178173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.856548Z","time spent":"1.321604605s","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:00.178837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334156976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:00.178887Z","caller":"traceutil/trace.go:171","msg":"trace[1038210694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:264; }","duration":"1.334229348s","start":"2026-04-01T22:43:58.844645Z","end":"2026-04-01T22:44:00.178875Z","steps":["trace[1038210694] 'agreement among raft nodes before linearized reading' (duration: 1.334141185s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.178918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:58.844632Z","time spent":"1.334278899s","remote":"127.0.0.1:42498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T22:44:00.179002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.110374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:00.179044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.970769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T22:44:00.179075Z","caller":"traceutil/trace.go:171","msg":"trace[450082984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:264; }","duration":"351.214866ms","start":"2026-04-01T22:43:59.827832Z","end":"2026-04-01T22:44:00.179047Z","steps":["trace[450082984] 'agreement among raft nodes before linearized reading' (duration: 351.093493ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:00.179084Z","caller":"traceutil/trace.go:171","msg":"trace[1608628976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:264; }","duration":"705.03479ms","start":"2026-04-01T22:43:59.474035Z","end":"2026-04-01T22:44:00.17907Z","steps":["trace[1608628976] 'agreement among raft nodes before linearized reading' (duration: 704.962158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.179113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:59.827822Z","time spent":"351.285298ms","remote":"127.0.0.1:42708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-01T22:44:00.179151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.461806ms","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-01T22:44:00.17942Z","caller":"traceutil/trace.go:171","msg":"trace[1459507750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:264; }","duration":"851.754323ms","start":"2026-04-01T22:43:59.327653Z","end":"2026-04-01T22:44:00.179408Z","steps":["trace[1459507750] 'agreement among raft nodes before linearized reading' (duration: 851.361593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.179482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:59.327638Z","time spent":"851.809815ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T22:44:00.179227Z","caller":"traceutil/trace.go:171","msg":"trace[1160912327] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"352.049868ms","start":"2026-04-01T22:43:59.827165Z","end":"2026-04-01T22:44:00.179215Z","steps":["trace[1160912327] 'process raft request' (duration: 351.521654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.179573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:59.827147Z","time spent":"352.378097ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:00.179262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.422273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T22:44:00.179657Z","caller":"traceutil/trace.go:171","msg":"trace[1141567429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:264; }","duration":"353.865415ms","start":"2026-04-01T22:43:59.825785Z","end":"2026-04-01T22:44:00.179651Z","steps":["trace[1141567429] 'agreement among raft nodes before linearized reading' (duration: 353.429013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:00.179694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:59.82576Z","time spent":"353.927297ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T22:44:00.179114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:43:59.474017Z","time spent":"705.089182ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T22:44:26.308828Z","caller":"traceutil/trace.go:171","msg":"trace[2097059724] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"504.776229ms","start":"2026-04-01T22:44:25.804022Z","end":"2026-04-01T22:44:26.308798Z","steps":["trace[2097059724] 'process raft request' (duration: 504.576774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.308984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.804004Z","time spent":"504.909643ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:26.324034Z","caller":"traceutil/trace.go:171","msg":"trace[605319941] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"513.354326ms","start":"2026-04-01T22:44:25.810639Z","end":"2026-04-01T22:44:26.323993Z","steps":["trace[605319941] 'process raft request' (duration: 513.145059ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:26.324085Z","caller":"traceutil/trace.go:171","msg":"trace[1503355850] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"512.370878ms","start":"2026-04-01T22:44:25.811699Z","end":"2026-04-01T22:44:26.32407Z","steps":["trace[1503355850] 'process raft request' (duration: 512.225584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.32421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.811669Z","time spent":"512.474351ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:26.324244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.810531Z","time spent":"513.611441ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:26.324389Z","caller":"traceutil/trace.go:171","msg":"trace[359759328] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"501.645357ms","start":"2026-04-01T22:44:25.822735Z","end":"2026-04-01T22:44:26.32438Z","steps":["trace[359759328] 'process raft request' (duration: 501.324438ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:26.32437Z","caller":"traceutil/trace.go:171","msg":"trace[1771015235] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"503.868625ms","start":"2026-04-01T22:44:25.820467Z","end":"2026-04-01T22:44:26.324335Z","steps":["trace[1771015235] 'process raft request' (duration: 503.542217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.324424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.82269Z","time spent":"501.716348ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:26.324537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.820448Z","time spent":"503.999209ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:26.324581Z","caller":"traceutil/trace.go:171","msg":"trace[1443944990] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"500.454306ms","start":"2026-04-01T22:44:25.824102Z","end":"2026-04-01T22:44:26.324557Z","steps":["trace[1443944990] 'process raft request' (duration: 500.261141ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:26.324626Z","caller":"traceutil/trace.go:171","msg":"trace[622522261] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"499.46044ms","start":"2026-04-01T22:44:25.825136Z","end":"2026-04-01T22:44:26.324596Z","steps":["trace[622522261] 'process raft request' (duration: 499.292836ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:26.324678Z","caller":"traceutil/trace.go:171","msg":"trace[483247589] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"498.895755ms","start":"2026-04-01T22:44:25.825774Z","end":"2026-04-01T22:44:26.32467Z","steps":["trace[483247589] 'process raft request' (duration: 498.789302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.324688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.825117Z","time spent":"499.540893ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:26.324707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.824067Z","time spent":"500.574919ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:26.324715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.825726Z","time spent":"498.974907ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:26.324824Z","caller":"traceutil/trace.go:171","msg":"trace[1926684409] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"491.684366ms","start":"2026-04-01T22:44:25.833134Z","end":"2026-04-01T22:44:26.324819Z","steps":["trace[1926684409] 'process raft request' (duration: 491.500711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.324854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.833106Z","time spent":"491.736118ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:26.324932Z","caller":"traceutil/trace.go:171","msg":"trace[1828737375] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"481.827457ms","start":"2026-04-01T22:44:25.843093Z","end":"2026-04-01T22:44:26.32492Z","steps":["trace[1828737375] 'process raft request' (duration: 481.681303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.324985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.84308Z","time spent":"481.883988ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:26.325035Z","caller":"traceutil/trace.go:171","msg":"trace[103712090] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"440.682745ms","start":"2026-04-01T22:44:25.884345Z","end":"2026-04-01T22:44:26.325028Z","steps":["trace[103712090] 'process raft request' (duration: 440.49397ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:26.32502Z","caller":"traceutil/trace.go:171","msg":"trace[13117025] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:494; }","duration":"377.124157ms","start":"2026-04-01T22:44:25.947877Z","end":"2026-04-01T22:44:26.325001Z","steps":["trace[13117025] 'read index received' (duration: 360.840139ms)","trace[13117025] 'applied index is now lower than readState.Index' (duration: 16.283008ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:26.325057Z","caller":"traceutil/trace.go:171","msg":"trace[1173737787] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"339.699683ms","start":"2026-04-01T22:44:25.985348Z","end":"2026-04-01T22:44:26.325048Z","steps":["trace[1173737787] 'process raft request' (duration: 339.56865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.32507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.884308Z","time spent":"440.748167ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:26.325161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.985323Z","time spent":"339.749605ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:44:26.325171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.27593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T22:44:26.325218Z","caller":"traceutil/trace.go:171","msg":"trace[1356480405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"377.378453ms","start":"2026-04-01T22:44:25.94783Z","end":"2026-04-01T22:44:26.325208Z","steps":["trace[1356480405] 'agreement among raft nodes before linearized reading' (duration: 377.25921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.325252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:25.947808Z","time spent":"377.435425ms","remote":"127.0.0.1:42774","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-01T22:44:26.328874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.905116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T22:44:26.328959Z","caller":"traceutil/trace.go:171","msg":"trace[1109598867] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:487; }","duration":"322.04081ms","start":"2026-04-01T22:44:26.006904Z","end":"2026-04-01T22:44:26.328945Z","steps":["trace[1109598867] 'agreement among raft nodes before linearized reading' (duration: 321.898196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:26.329005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:26.006882Z","time spent":"322.114611ms","remote":"127.0.0.1:42518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-04-01T22:44:27.343275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898291924276823,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T22:44:27.943422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.963594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:44:27.943523Z","caller":"traceutil/trace.go:171","msg":"trace[1487342234] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.101449893s","start":"2026-04-01T22:44:26.842059Z","end":"2026-04-01T22:44:27.943509Z","steps":["trace[1487342234] 'process raft request' (duration: 644.314385ms)","trace[1487342234] 'compare' (duration: 456.8276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:27.943583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:26.842041Z","time spent":"1.101508005s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:44:27.944188Z","caller":"traceutil/trace.go:171","msg":"trace[1383364381] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.079131576s","start":"2026-04-01T22:44:26.865046Z","end":"2026-04-01T22:44:27.944178Z","steps":["trace[1383364381] 'process raft request' (duration: 1.078816498s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:27.944241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:26.865017Z","time spent":"1.079196897s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:44:27.944544Z","caller":"traceutil/trace.go:171","msg":"trace[1389204564] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"1.102118629s","start":"2026-04-01T22:44:26.842393Z","end":"2026-04-01T22:44:27.944512Z","steps":["trace[1389204564] 'read index received' (duration: 642.166057ms)","trace[1389204564] 'applied index is now lower than readState.Index' (duration: 459.951922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:27.944618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102221052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:27.94464Z","caller":"traceutil/trace.go:171","msg":"trace[715850630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"1.102262903s","start":"2026-04-01T22:44:26.842369Z","end":"2026-04-01T22:44:27.944632Z","steps":["trace[715850630] 'agreement among raft nodes before linearized reading' (duration: 1.102206042s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:27.944661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:26.842361Z","time spent":"1.102295464s","remote":"127.0.0.1:42498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T22:44:27.944867Z","caller":"traceutil/trace.go:171","msg":"trace[1850769945] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"915.801456ms","start":"2026-04-01T22:44:27.029056Z","end":"2026-04-01T22:44:27.944857Z","steps":["trace[1850769945] 'process raft request' (duration: 915.005755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:27.944945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:27.029017Z","time spent":"915.905549ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T22:44:30.450087Z","caller":"traceutil/trace.go:171","msg":"trace[610515599] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"160.639009ms","start":"2026-04-01T22:44:30.289331Z","end":"2026-04-01T22:44:30.44997Z","steps":["trace[610515599] 'process raft request' (duration: 157.842866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:31.630869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.700475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:44:31.631098Z","caller":"traceutil/trace.go:171","msg":"trace[1204832157] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"138.555038ms","start":"2026-04-01T22:44:31.492498Z","end":"2026-04-01T22:44:31.631053Z","steps":["trace[1204832157] 'process raft request' (duration: 29.575026ms)","trace[1204832157] 'compare' (duration: 108.535461ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:33.566463Z","caller":"traceutil/trace.go:171","msg":"trace[1447332694] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"389.737107ms","start":"2026-04-01T22:44:33.176702Z","end":"2026-04-01T22:44:33.566439Z","steps":["trace[1447332694] 'read index received' (duration: 389.025778ms)","trace[1447332694] 'applied index is now lower than readState.Index' (duration: 710.609µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:33.566508Z","caller":"traceutil/trace.go:171","msg":"trace[686969659] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"399.837833ms","start":"2026-04-01T22:44:33.166641Z","end":"2026-04-01T22:44:33.566479Z","steps":["trace[686969659] 'process raft request' (duration: 399.3342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.566649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.166612Z","time spent":"399.961245ms","remote":"127.0.0.1:42594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:44:33.566745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.895081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.566787Z","caller":"traceutil/trace.go:171","msg":"trace[2092140222] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:583; }","duration":"390.109307ms","start":"2026-04-01T22:44:33.176664Z","end":"2026-04-01T22:44:33.566773Z","steps":["trace[2092140222] 'agreement among raft nodes before linearized reading' (duration: 389.901451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.566906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.176654Z","time spent":"390.198359ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T22:44:33.566974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.135707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.566996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.898723ms","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-01T22:44:33.567029Z","caller":"traceutil/trace.go:171","msg":"trace[145728015] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:583; }","duration":"390.23253ms","start":"2026-04-01T22:44:33.176784Z","end":"2026-04-01T22:44:33.567016Z","steps":["trace[145728015] 'agreement among raft nodes before linearized reading' (duration: 390.144458ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.567047Z","caller":"traceutil/trace.go:171","msg":"trace[1440868841] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"185.949214ms","start":"2026-04-01T22:44:33.38108Z","end":"2026-04-01T22:44:33.567029Z","steps":["trace[1440868841] 'agreement among raft nodes before linearized reading' (duration: 185.851902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.56707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.176766Z","time spent":"390.294672ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T22:44:33.567162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.841634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.567183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.080611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.567211Z","caller":"traceutil/trace.go:171","msg":"trace[128434574] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:583; }","duration":"337.926167ms","start":"2026-04-01T22:44:33.229272Z","end":"2026-04-01T22:44:33.567199Z","steps":["trace[128434574] 'agreement among raft nodes before linearized reading' (duration: 337.856545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.229264Z","time spent":"337.975008ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-01T22:44:33.567264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.495633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.567283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.447172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.567361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.83204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.567397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.338884ms","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-01T22:44:33.567398Z","caller":"traceutil/trace.go:171","msg":"trace[1609098590] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:583; }","duration":"389.886111ms","start":"2026-04-01T22:44:33.177503Z","end":"2026-04-01T22:44:33.567389Z","steps":["trace[1609098590] 'agreement among raft nodes before linearized reading' (duration: 389.839039ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.5674Z","caller":"traceutil/trace.go:171","msg":"trace[1642324613] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:583; }","duration":"367.579025ms","start":"2026-04-01T22:44:33.19978Z","end":"2026-04-01T22:44:33.567359Z","steps":["trace[1642324613] 'agreement among raft nodes before linearized reading' (duration: 367.435051ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.567428Z","caller":"traceutil/trace.go:171","msg":"trace[1543481787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"186.382505ms","start":"2026-04-01T22:44:33.381039Z","end":"2026-04-01T22:44:33.567421Z","steps":["trace[1543481787] 'agreement among raft nodes before linearized reading' (duration: 186.336064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.177496Z","time spent":"389.932373ms","remote":"127.0.0.1:52532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-01T22:44:33.567454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.199769Z","time spent":"367.671187ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T22:44:33.5675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.973811ms","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-01T22:44:33.567523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.88778ms","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-01T22:44:33.567531Z","caller":"traceutil/trace.go:171","msg":"trace[1335094254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"120.013132ms","start":"2026-04-01T22:44:33.44751Z","end":"2026-04-01T22:44:33.567523Z","steps":["trace[1335094254] 'agreement among raft nodes before linearized reading' (duration: 119.970181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.273193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.567544Z","caller":"traceutil/trace.go:171","msg":"trace[2016969063] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"226.92928ms","start":"2026-04-01T22:44:33.340609Z","end":"2026-04-01T22:44:33.567538Z","steps":["trace[2016969063] 'agreement among raft nodes before linearized reading' (duration: 226.89616ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.567556Z","caller":"traceutil/trace.go:171","msg":"trace[1445912710] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:583; }","duration":"277.316454ms","start":"2026-04-01T22:44:33.290232Z","end":"2026-04-01T22:44:33.567548Z","steps":["trace[1445912710] 'agreement among raft nodes before linearized reading' (duration: 277.274883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.283235ms","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":"info","ts":"2026-04-01T22:44:33.567636Z","caller":"traceutil/trace.go:171","msg":"trace[1694047818] 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:583; }","duration":"140.309186ms","start":"2026-04-01T22:44:33.427321Z","end":"2026-04-01T22:44:33.56763Z","steps":["trace[1694047818] 'agreement among raft nodes before linearized reading' (duration: 140.274325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.365252ms","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-01T22:44:33.567748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.863472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.567786Z","caller":"traceutil/trace.go:171","msg":"trace[723868432] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"227.425694ms","start":"2026-04-01T22:44:33.340319Z","end":"2026-04-01T22:44:33.567744Z","steps":["trace[723868432] 'agreement among raft nodes before linearized reading' (duration: 227.375182ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.56779Z","caller":"traceutil/trace.go:171","msg":"trace[918617633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:583; }","duration":"338.929743ms","start":"2026-04-01T22:44:33.228849Z","end":"2026-04-01T22:44:33.567779Z","steps":["trace[918617633] 'agreement among raft nodes before linearized reading' (duration: 338.866521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.425277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.567845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.702133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.567859Z","caller":"traceutil/trace.go:171","msg":"trace[514358558] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:583; }","duration":"277.489819ms","start":"2026-04-01T22:44:33.290363Z","end":"2026-04-01T22:44:33.567853Z","steps":["trace[514358558] 'agreement among raft nodes before linearized reading' (duration: 277.448167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.15881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.567892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.766045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.567898Z","caller":"traceutil/trace.go:171","msg":"trace[1486640722] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:583; }","duration":"162.251152ms","start":"2026-04-01T22:44:33.405638Z","end":"2026-04-01T22:44:33.567889Z","steps":["trace[1486640722] 'agreement among raft nodes before linearized reading' (duration: 162.169329ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.567913Z","caller":"traceutil/trace.go:171","msg":"trace[1629232352] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:583; }","duration":"162.794096ms","start":"2026-04-01T22:44:33.405114Z","end":"2026-04-01T22:44:33.567908Z","steps":["trace[1629232352] 'agreement among raft nodes before linearized reading' (duration: 162.766655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.56773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.584102ms","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-01T22:44:33.567962Z","caller":"traceutil/trace.go:171","msg":"trace[690646334] 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:583; }","duration":"140.856959ms","start":"2026-04-01T22:44:33.427095Z","end":"2026-04-01T22:44:33.567952Z","steps":["trace[690646334] 'agreement among raft nodes before linearized reading' (duration: 140.619053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.838699ms","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":"info","ts":"2026-04-01T22:44:33.567998Z","caller":"traceutil/trace.go:171","msg":"trace[1580338011] 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:583; }","duration":"141.099036ms","start":"2026-04-01T22:44:33.426892Z","end":"2026-04-01T22:44:33.567991Z","steps":["trace[1580338011] 'agreement among raft nodes before linearized reading' (duration: 140.84985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.567934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.583875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:44:33.568053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.051796ms","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-01T22:44:33.568067Z","caller":"traceutil/trace.go:171","msg":"trace[1800725686] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:583; }","duration":"276.741708ms","start":"2026-04-01T22:44:33.291321Z","end":"2026-04-01T22:44:33.568062Z","steps":["trace[1800725686] 'agreement among raft nodes before linearized reading' (duration: 276.596714ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.568084Z","caller":"traceutil/trace.go:171","msg":"trace[43021979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"186.098267ms","start":"2026-04-01T22:44:33.381977Z","end":"2026-04-01T22:44:33.568075Z","steps":["trace[43021979] 'agreement among raft nodes before linearized reading' (duration: 186.047966ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.567313Z","caller":"traceutil/trace.go:171","msg":"trace[1567544831] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:583; }","duration":"367.569484ms","start":"2026-04-01T22:44:33.199734Z","end":"2026-04-01T22:44:33.567304Z","steps":["trace[1567544831] 'agreement among raft nodes before linearized reading' (duration: 367.500583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.568159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.199721Z","time spent":"368.426877ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T22:44:33.567853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.228835Z","time spent":"339.008445ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T22:44:33.567994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.434971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:33.567216Z","caller":"traceutil/trace.go:171","msg":"trace[1547969006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:583; }","duration":"338.133112ms","start":"2026-04-01T22:44:33.229072Z","end":"2026-04-01T22:44:33.567205Z","steps":["trace[1547969006] 'agreement among raft nodes before linearized reading' (duration: 338.089371ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.568257Z","caller":"traceutil/trace.go:171","msg":"trace[81464318] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:583; }","duration":"367.725079ms","start":"2026-04-01T22:44:33.200524Z","end":"2026-04-01T22:44:33.568249Z","steps":["trace[81464318] 'agreement among raft nodes before linearized reading' (duration: 367.447982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:33.56828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.200513Z","time spent":"367.760039ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-01T22:44:33.567459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.868919ms","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-01T22:44:33.568278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.229059Z","time spent":"339.20863ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-01T22:44:33.567868Z","caller":"traceutil/trace.go:171","msg":"trace[897613146] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:583; }","duration":"162.729535ms","start":"2026-04-01T22:44:33.405133Z","end":"2026-04-01T22:44:33.567862Z","steps":["trace[897613146] 'agreement among raft nodes before linearized reading' (duration: 162.701054ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:33.568355Z","caller":"traceutil/trace.go:171","msg":"trace[1957631215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"120.792083ms","start":"2026-04-01T22:44:33.447556Z","end":"2026-04-01T22:44:33.568348Z","steps":["trace[1957631215] 'agreement among raft nodes before linearized reading' (duration: 119.879779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:34.755613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.606509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:34.755727Z","caller":"traceutil/trace.go:171","msg":"trace[1390267224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"910.778923ms","start":"2026-04-01T22:44:33.844921Z","end":"2026-04-01T22:44:34.7557Z","steps":["trace[1390267224] 'range keys from in-memory index tree' (duration: 910.512397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:34.755783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:33.844905Z","time spent":"910.864606ms","remote":"127.0.0.1:42498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T22:44:34.756246Z","caller":"traceutil/trace.go:171","msg":"trace[133479660] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"699.366391ms","start":"2026-04-01T22:44:34.056858Z","end":"2026-04-01T22:44:34.756225Z","steps":["trace[133479660] 'process raft request' (duration: 629.488095ms)","trace[133479660] 'compare' (duration: 69.417813ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:34.756387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:34.056816Z","time spent":"699.488344ms","remote":"127.0.0.1:42594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:44:34.756454Z","caller":"traceutil/trace.go:171","msg":"trace[764283629] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"205.972061ms","start":"2026-04-01T22:44:34.550461Z","end":"2026-04-01T22:44:34.756433Z","steps":["trace[764283629] 'read index received' (duration: 135.570181ms)","trace[764283629] 'applied index is now lower than readState.Index' (duration: 70.40089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:34.756782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.31079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T22:44:34.756887Z","caller":"traceutil/trace.go:171","msg":"trace[2113208138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"206.388152ms","start":"2026-04-01T22:44:34.550426Z","end":"2026-04-01T22:44:34.756814Z","steps":["trace[2113208138] 'agreement among raft nodes before linearized reading' (duration: 206.259149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:34.757093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.466299ms","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-01T22:44:34.75713Z","caller":"traceutil/trace.go:171","msg":"trace[1079963407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"191.528801ms","start":"2026-04-01T22:44:34.565591Z","end":"2026-04-01T22:44:34.757119Z","steps":["trace[1079963407] 'agreement among raft nodes before linearized reading' (duration: 191.442109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:34.757488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.025365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T22:44:34.757562Z","caller":"traceutil/trace.go:171","msg":"trace[497439129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"189.124977ms","start":"2026-04-01T22:44:34.56842Z","end":"2026-04-01T22:44:34.757545Z","steps":["trace[497439129] 'agreement among raft nodes before linearized reading' (duration: 188.909341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:34.75799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.678082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T22:44:34.75803Z","caller":"traceutil/trace.go:171","msg":"trace[496653992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"189.757594ms","start":"2026-04-01T22:44:34.568262Z","end":"2026-04-01T22:44:34.758019Z","steps":["trace[496653992] 'agreement among raft nodes before linearized reading' (duration: 189.662592ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:35.457742Z","caller":"traceutil/trace.go:171","msg":"trace[1320910940] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"213.905599ms","start":"2026-04-01T22:44:35.243814Z","end":"2026-04-01T22:44:35.457719Z","steps":["trace[1320910940] 'process raft request' (duration: 213.392835ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:36.972199Z","caller":"traceutil/trace.go:171","msg":"trace[663574284] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"188.78668ms","start":"2026-04-01T22:44:36.783384Z","end":"2026-04-01T22:44:36.972171Z","steps":["trace[663574284] 'process raft request' (duration: 188.566183ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:36.972534Z","caller":"traceutil/trace.go:171","msg":"trace[1720666745] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"126.877043ms","start":"2026-04-01T22:44:36.845592Z","end":"2026-04-01T22:44:36.972469Z","steps":["trace[1720666745] 'read index received' (duration: 8.330219ms)","trace[1720666745] 'applied index is now lower than readState.Index' (duration: 118.545334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:36.972729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.119559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:36.972772Z","caller":"traceutil/trace.go:171","msg":"trace[1643938842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"127.213562ms","start":"2026-04-01T22:44:36.845543Z","end":"2026-04-01T22:44:36.972757Z","steps":["trace[1643938842] 'agreement among raft nodes before linearized reading' (duration: 127.13414ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:36.972867Z","caller":"traceutil/trace.go:171","msg":"trace[611931494] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"188.599905ms","start":"2026-04-01T22:44:36.784031Z","end":"2026-04-01T22:44:36.972631Z","steps":["trace[611931494] 'process raft request' (duration: 188.334008ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:36.972592Z","caller":"traceutil/trace.go:171","msg":"trace[1290642801] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"188.80211ms","start":"2026-04-01T22:44:36.783738Z","end":"2026-04-01T22:44:36.97254Z","steps":["trace[1290642801] 'process raft request' (duration: 188.385509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:37.179848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.283695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:44:37.179995Z","caller":"traceutil/trace.go:171","msg":"trace[1812897734] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"207.319646ms","start":"2026-04-01T22:44:36.972651Z","end":"2026-04-01T22:44:37.179971Z","steps":["trace[1812897734] 'read index received' (duration: 106.888688ms)","trace[1812897734] 'applied index is now lower than readState.Index' (duration: 100.429398ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:37.180114Z","caller":"traceutil/trace.go:171","msg":"trace[1935026565] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"219.234468ms","start":"2026-04-01T22:44:36.960865Z","end":"2026-04-01T22:44:37.1801Z","steps":["trace[1935026565] 'process raft request' (duration: 118.582834ms)","trace[1935026565] 'compare' (duration: 100.10566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:37.180427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.689434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:44:37.18059Z","caller":"traceutil/trace.go:171","msg":"trace[1458237538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"205.899009ms","start":"2026-04-01T22:44:36.974667Z","end":"2026-04-01T22:44:37.180566Z","steps":["trace[1458237538] 'agreement among raft nodes before linearized reading' (duration: 205.625532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:37.180888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.236302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4lq7j\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-04-01T22:44:37.181016Z","caller":"traceutil/trace.go:171","msg":"trace[1161757055] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4lq7j; range_end:; response_count:1; response_revision:612; }","duration":"221.430086ms","start":"2026-04-01T22:44:36.959563Z","end":"2026-04-01T22:44:37.180994Z","steps":["trace[1161757055] 'agreement among raft nodes before linearized reading' (duration: 220.933594ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:37.322707Z","caller":"traceutil/trace.go:171","msg":"trace[1062622173] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"124.991643ms","start":"2026-04-01T22:44:37.197692Z","end":"2026-04-01T22:44:37.322683Z","steps":["trace[1062622173] 'process raft request' (duration: 124.769718ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:48.923356Z","caller":"traceutil/trace.go:171","msg":"trace[243499234] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"230.572249ms","start":"2026-04-01T22:44:48.692757Z","end":"2026-04-01T22:44:48.923329Z","steps":["trace[243499234] 'process raft request' (duration: 230.155998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:51.307579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.427943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-01T22:44:51.307729Z","caller":"traceutil/trace.go:171","msg":"trace[1294306549] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"214.052199ms","start":"2026-04-01T22:44:51.093652Z","end":"2026-04-01T22:44:51.307705Z","steps":["trace[1294306549] 'read index received' (duration: 13.422191ms)","trace[1294306549] 'applied index is now lower than readState.Index' (duration: 200.628057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:44:51.308073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.375926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-01T22:44:51.308144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.200812ms","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-01T22:44:51.308162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.543204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T22:44:51.30821Z","caller":"traceutil/trace.go:171","msg":"trace[461672564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:739; }","duration":"214.328425ms","start":"2026-04-01T22:44:51.093867Z","end":"2026-04-01T22:44:51.308195Z","steps":["trace[461672564] 'agreement among raft nodes before linearized reading' (duration: 214.055748ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:51.308222Z","caller":"traceutil/trace.go:171","msg":"trace[167682411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"214.588323ms","start":"2026-04-01T22:44:51.0936Z","end":"2026-04-01T22:44:51.308188Z","steps":["trace[167682411] 'agreement among raft nodes before linearized reading' (duration: 214.198523ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:51.30825Z","caller":"traceutil/trace.go:171","msg":"trace[1706814433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:739; }","duration":"209.703248ms","start":"2026-04-01T22:44:51.098526Z","end":"2026-04-01T22:44:51.308229Z","steps":["trace[1706814433] 'agreement among raft nodes before linearized reading' (duration: 209.41431ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:51.415168Z","caller":"traceutil/trace.go:171","msg":"trace[1425458842] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"101.795396ms","start":"2026-04-01T22:44:51.313329Z","end":"2026-04-01T22:44:51.415125Z","steps":["trace[1425458842] 'process raft request' (duration: 101.501649ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:51.41551Z","caller":"traceutil/trace.go:171","msg":"trace[592787702] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"101.625152ms","start":"2026-04-01T22:44:51.313839Z","end":"2026-04-01T22:44:51.415465Z","steps":["trace[592787702] 'process raft request' (duration: 101.221752ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:51.808307Z","caller":"traceutil/trace.go:171","msg":"trace[1731540141] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"127.269835ms","start":"2026-04-01T22:44:51.681016Z","end":"2026-04-01T22:44:51.808286Z","steps":["trace[1731540141] 'process raft request' (duration: 127.072569ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:53.487915Z","caller":"traceutil/trace.go:171","msg":"trace[1383658379] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"428.668632ms","start":"2026-04-01T22:44:53.059222Z","end":"2026-04-01T22:44:53.48789Z","steps":["trace[1383658379] 'read index received' (duration: 428.424805ms)","trace[1383658379] 'applied index is now lower than readState.Index' (duration: 242.477µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:53.48798Z","caller":"traceutil/trace.go:171","msg":"trace[320569070] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"460.066188ms","start":"2026-04-01T22:44:53.027886Z","end":"2026-04-01T22:44:53.487952Z","steps":["trace[320569070] 'process raft request' (duration: 459.868223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:53.488137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:53.027863Z","time spent":"460.187432ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:44:53.488235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.988519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rj8z9\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-01T22:44:53.488309Z","caller":"traceutil/trace.go:171","msg":"trace[170590545] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rj8z9; range_end:; response_count:1; response_revision:756; }","duration":"429.135713ms","start":"2026-04-01T22:44:53.059161Z","end":"2026-04-01T22:44:53.488297Z","steps":["trace[170590545] 'agreement among raft nodes before linearized reading' (duration: 428.869566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:44:53.48838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.272509ms","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-01T22:44:53.488404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:44:53.059136Z","time spent":"429.256716ms","remote":"127.0.0.1:42680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rj8z9\" "} {"level":"info","ts":"2026-04-01T22:44:53.488431Z","caller":"traceutil/trace.go:171","msg":"trace[341933199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"106.375263ms","start":"2026-04-01T22:44:53.382043Z","end":"2026-04-01T22:44:53.488418Z","steps":["trace[341933199] 'agreement among raft nodes before linearized reading' (duration: 106.217069ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:53.666028Z","caller":"traceutil/trace.go:171","msg":"trace[2035877051] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"173.139699ms","start":"2026-04-01T22:44:53.492858Z","end":"2026-04-01T22:44:53.665998Z","steps":["trace[2035877051] 'process raft request' (duration: 138.291913ms)","trace[2035877051] 'compare' (duration: 34.656811ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:44:53.667615Z","caller":"traceutil/trace.go:171","msg":"trace[1425437914] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"169.156227ms","start":"2026-04-01T22:44:53.498428Z","end":"2026-04-01T22:44:53.667584Z","steps":["trace[1425437914] 'process raft request' (duration: 169.090815ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:53.667952Z","caller":"traceutil/trace.go:171","msg":"trace[419873363] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"171.879096ms","start":"2026-04-01T22:44:53.495958Z","end":"2026-04-01T22:44:53.667837Z","steps":["trace[419873363] 'process raft request' (duration: 171.488116ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:44:53.668046Z","caller":"traceutil/trace.go:171","msg":"trace[680875436] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"175.042747ms","start":"2026-04-01T22:44:53.492984Z","end":"2026-04-01T22:44:53.668027Z","steps":["trace[680875436] 'process raft request' (duration: 174.279578ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:05.72471Z","caller":"traceutil/trace.go:171","msg":"trace[397505256] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"168.82286ms","start":"2026-04-01T22:45:05.555868Z","end":"2026-04-01T22:45:05.724691Z","steps":["trace[397505256] 'read index received' (duration: 168.684806ms)","trace[397505256] 'applied index is now lower than readState.Index' (duration: 136.653µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:45:05.724834Z","caller":"traceutil/trace.go:171","msg":"trace[2111072453] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"218.116692ms","start":"2026-04-01T22:45:05.506688Z","end":"2026-04-01T22:45:05.724805Z","steps":["trace[2111072453] 'process raft request' (duration: 217.870616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:05.725511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.63127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T22:45:05.725629Z","caller":"traceutil/trace.go:171","msg":"trace[2122965644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"169.776074ms","start":"2026-04-01T22:45:05.555826Z","end":"2026-04-01T22:45:05.725602Z","steps":["trace[2122965644] 'agreement among raft nodes before linearized reading' (duration: 169.436945ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:05.839471Z","caller":"traceutil/trace.go:171","msg":"trace[822209384] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"110.512207ms","start":"2026-04-01T22:45:05.728928Z","end":"2026-04-01T22:45:05.83944Z","steps":["trace[822209384] 'process raft request' (duration: 31.211142ms)","trace[822209384] 'compare' (duration: 79.134751ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:45:05.839827Z","caller":"traceutil/trace.go:171","msg":"trace[1874605929] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"110.775915ms","start":"2026-04-01T22:45:05.72896Z","end":"2026-04-01T22:45:05.839736Z","steps":["trace[1874605929] 'process raft request' (duration: 110.442276ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:05.839957Z","caller":"traceutil/trace.go:171","msg":"trace[1542167370] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"110.333883ms","start":"2026-04-01T22:45:05.729374Z","end":"2026-04-01T22:45:05.839708Z","steps":["trace[1542167370] 'process raft request' (duration: 110.256561ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:06.026688Z","caller":"traceutil/trace.go:171","msg":"trace[259724911] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"146.651975ms","start":"2026-04-01T22:45:05.880015Z","end":"2026-04-01T22:45:06.026667Z","steps":["trace[259724911] 'process raft request' (duration: 146.45407ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:24.129267Z","caller":"traceutil/trace.go:171","msg":"trace[668806982] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"137.065732ms","start":"2026-04-01T22:45:23.992167Z","end":"2026-04-01T22:45:24.129233Z","steps":["trace[668806982] 'process raft request' (duration: 136.836637ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:28.809575Z","caller":"traceutil/trace.go:171","msg":"trace[544674616] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"399.128671ms","start":"2026-04-01T22:45:28.410402Z","end":"2026-04-01T22:45:28.809531Z","steps":["trace[544674616] 'process raft request' (duration: 398.966127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:28.809764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:28.410383Z","time spent":"399.286135ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:29.689376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.473235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:45:29.689465Z","caller":"traceutil/trace.go:171","msg":"trace[339477383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"842.590967ms","start":"2026-04-01T22:45:28.84686Z","end":"2026-04-01T22:45:29.689451Z","steps":["trace[339477383] 'range keys from in-memory index tree' (duration: 842.388023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:29.689493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:28.846845Z","time spent":"842.641659ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T22:45:29.689474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.362428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:45:29.689533Z","caller":"traceutil/trace.go:171","msg":"trace[302384643] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:972; }","duration":"403.481601ms","start":"2026-04-01T22:45:29.286035Z","end":"2026-04-01T22:45:29.689516Z","steps":["trace[302384643] 'count revisions from in-memory index tree' (duration: 403.269656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:29.689563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:29.286016Z","time spent":"403.539563ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-01T22:45:46.428085Z","caller":"traceutil/trace.go:171","msg":"trace[1155751329] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"103.685235ms","start":"2026-04-01T22:45:46.324382Z","end":"2026-04-01T22:45:46.428067Z","steps":["trace[1155751329] 'process raft request' (duration: 102.251139ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:46.451271Z","caller":"traceutil/trace.go:171","msg":"trace[964508237] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"123.18497ms","start":"2026-04-01T22:45:46.32806Z","end":"2026-04-01T22:45:46.451245Z","steps":["trace[964508237] 'process raft request' (duration: 123.052837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:51.345779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898291924277995,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T22:45:51.353913Z","caller":"traceutil/trace.go:171","msg":"trace[675639179] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"570.075052ms","start":"2026-04-01T22:45:50.78381Z","end":"2026-04-01T22:45:51.353885Z","steps":["trace[675639179] 'process raft request' (duration: 563.275158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:51.354032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:50.783784Z","time spent":"570.187035ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:45:51.354356Z","caller":"traceutil/trace.go:171","msg":"trace[539757368] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"452.358388ms","start":"2026-04-01T22:45:50.901968Z","end":"2026-04-01T22:45:51.354326Z","steps":["trace[539757368] 'process raft request' (duration: 452.205993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:51.354493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:50.901938Z","time spent":"452.47701ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:45:51.355956Z","caller":"traceutil/trace.go:171","msg":"trace[1089196710] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1088; }","duration":"510.894587ms","start":"2026-04-01T22:45:50.845037Z","end":"2026-04-01T22:45:51.355931Z","steps":["trace[1089196710] 'read index received' (duration: 502.06004ms)","trace[1089196710] 'applied index is now lower than readState.Index' (duration: 8.833257ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:45:51.356041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.008029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:45:51.356099Z","caller":"traceutil/trace.go:171","msg":"trace[93396137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"511.103952ms","start":"2026-04-01T22:45:50.844986Z","end":"2026-04-01T22:45:51.35609Z","steps":["trace[93396137] 'agreement among raft nodes before linearized reading' (duration: 511.02152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:51.356121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:50.844967Z","time spent":"511.149863ms","remote":"127.0.0.1:42498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T22:45:51.356128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.623971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T22:45:51.356128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.591312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:45:51.356166Z","caller":"traceutil/trace.go:171","msg":"trace[2136220349] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1045; }","duration":"439.674202ms","start":"2026-04-01T22:45:50.916482Z","end":"2026-04-01T22:45:51.356156Z","steps":["trace[2136220349] 'agreement among raft nodes before linearized reading' (duration: 439.600651ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:51.356172Z","caller":"traceutil/trace.go:171","msg":"trace[697779729] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1045; }","duration":"108.673074ms","start":"2026-04-01T22:45:51.247489Z","end":"2026-04-01T22:45:51.356162Z","steps":["trace[697779729] 'agreement among raft nodes before linearized reading' (duration: 108.599202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:51.356193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:50.916447Z","time spent":"439.739174ms","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-04-01T22:45:53.642206Z","caller":"traceutil/trace.go:171","msg":"trace[717016414] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"281.914751ms","start":"2026-04-01T22:45:53.360243Z","end":"2026-04-01T22:45:53.642158Z","steps":["trace[717016414] 'process raft request' (duration: 281.749277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:54.892159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.998281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:45:54.892316Z","caller":"traceutil/trace.go:171","msg":"trace[351805646] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"408.087213ms","start":"2026-04-01T22:45:54.484209Z","end":"2026-04-01T22:45:54.892296Z","steps":["trace[351805646] 'read index received' (duration: 72.824026ms)","trace[351805646] 'applied index is now lower than readState.Index' (duration: 335.261567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:45:54.892623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.402182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T22:45:54.892687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.078076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T22:45:54.892691Z","caller":"traceutil/trace.go:171","msg":"trace[119846829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"408.507875ms","start":"2026-04-01T22:45:54.484166Z","end":"2026-04-01T22:45:54.892674Z","steps":["trace[119846829] 'agreement among raft nodes before linearized reading' (duration: 408.237718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:54.892779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:54.484148Z","time spent":"408.619217ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-01T22:45:54.892903Z","caller":"traceutil/trace.go:171","msg":"trace[805910101] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"759.850035ms","start":"2026-04-01T22:45:54.133027Z","end":"2026-04-01T22:45:54.892877Z","steps":["trace[805910101] 'process raft request' (duration: 424.060513ms)","trace[805910101] 'compare' (duration: 334.798696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:45:54.893041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:54.133002Z","time spent":"759.968447ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:54.893164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.77119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T22:45:54.893467Z","caller":"traceutil/trace.go:171","msg":"trace[798641498] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1054; }","duration":"213.493738ms","start":"2026-04-01T22:45:54.679962Z","end":"2026-04-01T22:45:54.893456Z","steps":["trace[798641498] 'agreement among raft nodes before linearized reading' (duration: 212.726689ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:54.893134Z","caller":"traceutil/trace.go:171","msg":"trace[858957671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"371.165288ms","start":"2026-04-01T22:45:54.521567Z","end":"2026-04-01T22:45:54.892732Z","steps":["trace[858957671] 'agreement among raft nodes before linearized reading' (duration: 371.028995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:54.893629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:54.521553Z","time spent":"371.992639ms","remote":"127.0.0.1:42774","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-01T22:45:55.469948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.373611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:45:55.470632Z","caller":"traceutil/trace.go:171","msg":"trace[284759002] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"573.166292ms","start":"2026-04-01T22:45:54.897432Z","end":"2026-04-01T22:45:55.470598Z","steps":["trace[284759002] 'process raft request' (duration: 424.027902ms)","trace[284759002] 'compare' (duration: 148.136745ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:45:55.47076Z","caller":"traceutil/trace.go:171","msg":"trace[1573196700] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"573.258365ms","start":"2026-04-01T22:45:54.897477Z","end":"2026-04-01T22:45:55.470735Z","steps":["trace[1573196700] 'process raft request' (duration: 573.012639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:55.470883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:54.897454Z","time spent":"573.369408ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:55.470889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:54.897421Z","time spent":"573.280785ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:56.144643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.574227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:45:56.144749Z","caller":"traceutil/trace.go:171","msg":"trace[1838070240] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"397.692948ms","start":"2026-04-01T22:45:55.747038Z","end":"2026-04-01T22:45:56.144731Z","steps":["trace[1838070240] 'read index received' (duration: 115.004616ms)","trace[1838070240] 'applied index is now lower than readState.Index' (duration: 282.686811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:45:56.144863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.187034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:45:56.144894Z","caller":"traceutil/trace.go:171","msg":"trace[1056242813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"299.416189ms","start":"2026-04-01T22:45:55.845469Z","end":"2026-04-01T22:45:56.144886Z","steps":["trace[1056242813] 'agreement among raft nodes before linearized reading' (duration: 299.355408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:56.144889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.832871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:45:56.144971Z","caller":"traceutil/trace.go:171","msg":"trace[1997092437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1057; }","duration":"397.961884ms","start":"2026-04-01T22:45:55.746989Z","end":"2026-04-01T22:45:56.144951Z","steps":["trace[1997092437] 'agreement among raft nodes before linearized reading' (duration: 397.829091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:56.145026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:55.746966Z","time spent":"398.045546ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-01T22:45:56.145171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.908424ms","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-01T22:45:56.145209Z","caller":"traceutil/trace.go:171","msg":"trace[1249591800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1057; }","duration":"123.987406ms","start":"2026-04-01T22:45:56.021212Z","end":"2026-04-01T22:45:56.1452Z","steps":["trace[1249591800] 'agreement among raft nodes before linearized reading' (duration: 123.859483ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:56.145262Z","caller":"traceutil/trace.go:171","msg":"trace[299120616] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"496.950341ms","start":"2026-04-01T22:45:55.648296Z","end":"2026-04-01T22:45:56.145246Z","steps":["trace[299120616] 'process raft request' (duration: 213.679125ms)","trace[299120616] 'compare' (duration: 282.253139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:45:56.145357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:55.648268Z","time spent":"497.030172ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:56.145336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.944411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T22:45:56.145419Z","caller":"traceutil/trace.go:171","msg":"trace[360437471] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1057; }","duration":"134.077554ms","start":"2026-04-01T22:45:56.011322Z","end":"2026-04-01T22:45:56.1454Z","steps":["trace[360437471] 'agreement among raft nodes before linearized reading' (duration: 133.829028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:56.785333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.995708ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-01T22:45:56.785414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:56.146649Z","time spent":"638.755322ms","remote":"127.0.0.1:42518","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-01T22:45:56.876978Z","caller":"traceutil/trace.go:171","msg":"trace[824876600] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"727.892855ms","start":"2026-04-01T22:45:56.148975Z","end":"2026-04-01T22:45:56.876868Z","steps":["trace[824876600] 'process raft request' (duration: 727.69638ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:56.877111Z","caller":"traceutil/trace.go:171","msg":"trace[622616700] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"343.887769ms","start":"2026-04-01T22:45:56.533198Z","end":"2026-04-01T22:45:56.877086Z","steps":["trace[622616700] 'process raft request' (duration: 343.618832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:56.877253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:56.533168Z","time spent":"343.989012ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:56.877407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:56.148964Z","time spent":"728.386768ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:45:56.877995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.913445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T22:45:56.878052Z","caller":"traceutil/trace.go:171","msg":"trace[1098654972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"671.008138ms","start":"2026-04-01T22:45:56.20703Z","end":"2026-04-01T22:45:56.878039Z","steps":["trace[1098654972] 'agreement among raft nodes before linearized reading' (duration: 670.845694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:45:56.878096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:45:56.207011Z","time spent":"671.073199ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T22:45:56.877716Z","caller":"traceutil/trace.go:171","msg":"trace[851561480] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1103; }","duration":"670.611397ms","start":"2026-04-01T22:45:56.207083Z","end":"2026-04-01T22:45:56.877694Z","steps":["trace[851561480] 'read index received' (duration: 336.174521ms)","trace[851561480] 'applied index is now lower than readState.Index' (duration: 334.434956ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:45:56.940429Z","caller":"traceutil/trace.go:171","msg":"trace[840101929] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"154.141828ms","start":"2026-04-01T22:45:56.786229Z","end":"2026-04-01T22:45:56.940371Z","steps":["trace[840101929] 'process raft request' (duration: 109.761301ms)","trace[840101929] 'compare' (duration: 44.235194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:45:56.940569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.637003ms","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-01T22:45:56.940633Z","caller":"traceutil/trace.go:171","msg":"trace[527233744] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1061; }","duration":"117.762586ms","start":"2026-04-01T22:45:56.822856Z","end":"2026-04-01T22:45:56.940618Z","steps":["trace[527233744] 'agreement among raft nodes before linearized reading' (duration: 117.595582ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:45:58.276751Z","caller":"traceutil/trace.go:171","msg":"trace[637707349] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"125.291139ms","start":"2026-04-01T22:45:58.151432Z","end":"2026-04-01T22:45:58.276723Z","steps":["trace[637707349] 'process raft request' (duration: 125.113935ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:09.913675Z","caller":"traceutil/trace.go:171","msg":"trace[1808479680] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"317.978145ms","start":"2026-04-01T22:46:09.595662Z","end":"2026-04-01T22:46:09.91364Z","steps":["trace[1808479680] 'read index received' (duration: 317.684487ms)","trace[1808479680] 'applied index is now lower than readState.Index' (duration: 292.428µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:09.91408Z","caller":"traceutil/trace.go:171","msg":"trace[2020782620] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"353.593947ms","start":"2026-04-01T22:46:09.56047Z","end":"2026-04-01T22:46:09.914064Z","steps":["trace[2020782620] 'process raft request' (duration: 352.968511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:09.914285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:09.560459Z","time spent":"353.670219ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:46:09.91464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.974231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T22:46:09.9147Z","caller":"traceutil/trace.go:171","msg":"trace[715178042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1153; }","duration":"319.088144ms","start":"2026-04-01T22:46:09.595598Z","end":"2026-04-01T22:46:09.914687Z","steps":["trace[715178042] 'agreement among raft nodes before linearized reading' (duration: 318.904129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:09.91474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:09.595573Z","time spent":"319.159516ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T22:46:10.409326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.410043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:46:10.409415Z","caller":"traceutil/trace.go:171","msg":"trace[542123574] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"490.246477ms","start":"2026-04-01T22:46:09.919155Z","end":"2026-04-01T22:46:10.409401Z","steps":["trace[542123574] 'process raft request' (duration: 63.69117ms)","trace[542123574] 'compare' (duration: 426.3065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:10.409451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:09.919133Z","time spent":"490.303279ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:26.129086Z","caller":"traceutil/trace.go:171","msg":"trace[2029002442] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"112.873521ms","start":"2026-04-01T22:46:26.016182Z","end":"2026-04-01T22:46:26.129056Z","steps":["trace[2029002442] 'read index received' (duration: 112.622155ms)","trace[2029002442] 'applied index is now lower than readState.Index' (duration: 250.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:26.129479Z","caller":"traceutil/trace.go:171","msg":"trace[2051222835] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"136.595329ms","start":"2026-04-01T22:46:25.992618Z","end":"2026-04-01T22:46:26.129213Z","steps":["trace[2051222835] 'process raft request' (duration: 136.24832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:26.130175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.961899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-01T22:46:26.13025Z","caller":"traceutil/trace.go:171","msg":"trace[1824383643] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1219; }","duration":"114.087281ms","start":"2026-04-01T22:46:26.016148Z","end":"2026-04-01T22:46:26.130235Z","steps":["trace[1824383643] 'agreement among raft nodes before linearized reading' (duration: 113.822534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:26.131471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.855757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T22:46:26.131841Z","caller":"traceutil/trace.go:171","msg":"trace[1103839860] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1219; }","duration":"114.216486ms","start":"2026-04-01T22:46:26.017564Z","end":"2026-04-01T22:46:26.131781Z","steps":["trace[1103839860] 'agreement among raft nodes before linearized reading' (duration: 112.81342ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:27.374031Z","caller":"traceutil/trace.go:171","msg":"trace[689435062] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"260.048341ms","start":"2026-04-01T22:46:27.113955Z","end":"2026-04-01T22:46:27.374003Z","steps":["trace[689435062] 'read index received' (duration: 259.917348ms)","trace[689435062] 'applied index is now lower than readState.Index' (duration: 130.093µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:27.374411Z","caller":"traceutil/trace.go:171","msg":"trace[1264794097] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"356.452681ms","start":"2026-04-01T22:46:27.017942Z","end":"2026-04-01T22:46:27.374395Z","steps":["trace[1264794097] 'process raft request' (duration: 355.834255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:27.374537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:27.017929Z","time spent":"356.520833ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:46:27.37483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.880783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T22:46:27.374869Z","caller":"traceutil/trace.go:171","msg":"trace[1962450247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"260.960985ms","start":"2026-04-01T22:46:27.113897Z","end":"2026-04-01T22:46:27.374858Z","steps":["trace[1962450247] 'agreement among raft nodes before linearized reading' (duration: 260.824261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:27.375009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.370694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T22:46:27.37504Z","caller":"traceutil/trace.go:171","msg":"trace[62425489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"252.462457ms","start":"2026-04-01T22:46:27.122569Z","end":"2026-04-01T22:46:27.375032Z","steps":["trace[62425489] 'agreement among raft nodes before linearized reading' (duration: 252.383395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:27.779981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.685414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:46:27.780224Z","caller":"traceutil/trace.go:171","msg":"trace[306484916] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"399.718998ms","start":"2026-04-01T22:46:27.380465Z","end":"2026-04-01T22:46:27.780184Z","steps":["trace[306484916] 'process raft request' (duration: 144.687336ms)","trace[306484916] 'compare' (duration: 254.495179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:27.780493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:27.380443Z","time spent":"400.015075ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:27.8063Z","caller":"traceutil/trace.go:171","msg":"trace[759986634] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"303.323241ms","start":"2026-04-01T22:46:27.502957Z","end":"2026-04-01T22:46:27.806281Z","steps":["trace[759986634] 'read index received' (duration: 22.208129ms)","trace[759986634] 'applied index is now lower than readState.Index' (duration: 281.113842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:27.806512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.512065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:46:27.806557Z","caller":"traceutil/trace.go:171","msg":"trace[1258598468] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1231; }","duration":"303.604677ms","start":"2026-04-01T22:46:27.50294Z","end":"2026-04-01T22:46:27.806545Z","steps":["trace[1258598468] 'agreement among raft nodes before linearized reading' (duration: 303.423023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:27.806663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:27.50289Z","time spent":"303.759402ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-01T22:46:27.80709Z","caller":"traceutil/trace.go:171","msg":"trace[832079548] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"425.692594ms","start":"2026-04-01T22:46:27.381384Z","end":"2026-04-01T22:46:27.807077Z","steps":["trace[832079548] 'process raft request' (duration: 424.75825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:27.807172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:27.381373Z","time spent":"425.755946ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:27.844614Z","caller":"traceutil/trace.go:171","msg":"trace[322470996] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"123.637508ms","start":"2026-04-01T22:46:27.720959Z","end":"2026-04-01T22:46:27.844597Z","steps":["trace[322470996] 'process raft request' (duration: 123.550505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:28.49307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.439942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:46:28.493338Z","caller":"traceutil/trace.go:171","msg":"trace[451478060] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"126.259734ms","start":"2026-04-01T22:46:28.367056Z","end":"2026-04-01T22:46:28.493316Z","steps":["trace[451478060] 'process raft request' (duration: 126.186452ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:28.493513Z","caller":"traceutil/trace.go:171","msg":"trace[440519022] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"129.788295ms","start":"2026-04-01T22:46:28.363673Z","end":"2026-04-01T22:46:28.493462Z","steps":["trace[440519022] 'process raft request' (duration: 14.849771ms)","trace[440519022] 'compare' (duration: 113.97838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:28.99348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898291924278439,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T22:46:29.092774Z","caller":"traceutil/trace.go:171","msg":"trace[252261410] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"619.182861ms","start":"2026-04-01T22:46:28.473559Z","end":"2026-04-01T22:46:29.092742Z","steps":["trace[252261410] 'process raft request' (duration: 619.011776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:29.092972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:28.473538Z","time spent":"619.326935ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:29.093232Z","caller":"traceutil/trace.go:171","msg":"trace[1742618842] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"600.291587ms","start":"2026-04-01T22:46:28.492925Z","end":"2026-04-01T22:46:29.093217Z","steps":["trace[1742618842] 'read index received' (duration: 599.64512ms)","trace[1742618842] 'applied index is now lower than readState.Index' (duration: 644.957µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:29.093311Z","caller":"traceutil/trace.go:171","msg":"trace[780298382] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"593.145564ms","start":"2026-04-01T22:46:28.500157Z","end":"2026-04-01T22:46:29.093303Z","steps":["trace[780298382] 'process raft request' (duration: 592.98543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:29.093362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:28.500129Z","time spent":"593.205086ms","remote":"127.0.0.1:42570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T22:46:29.093678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.353699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-01T22:46:29.093798Z","caller":"traceutil/trace.go:171","msg":"trace[1877757988] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1240; }","duration":"595.533574ms","start":"2026-04-01T22:46:28.498225Z","end":"2026-04-01T22:46:29.093759Z","steps":["trace[1877757988] 'agreement among raft nodes before linearized reading' (duration: 595.230347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:29.093833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.903982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T22:46:29.093852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:28.498211Z","time spent":"595.631526ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-01T22:46:29.093881Z","caller":"traceutil/trace.go:171","msg":"trace[2019432347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"601.001094ms","start":"2026-04-01T22:46:28.492868Z","end":"2026-04-01T22:46:29.093869Z","steps":["trace[2019432347] 'agreement among raft nodes before linearized reading' (duration: 600.83341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:29.093919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:28.492848Z","time spent":"601.063556ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T22:46:29.09392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.92057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:46:29.094008Z","caller":"traceutil/trace.go:171","msg":"trace[1184628054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"248.070984ms","start":"2026-04-01T22:46:28.845917Z","end":"2026-04-01T22:46:29.093988Z","steps":["trace[1184628054] 'agreement among raft nodes before linearized reading' (duration: 247.90592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:29.563796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.209197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T22:46:29.564013Z","caller":"traceutil/trace.go:171","msg":"trace[1904140521] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1295; }","duration":"183.450169ms","start":"2026-04-01T22:46:29.380541Z","end":"2026-04-01T22:46:29.563991Z","steps":["trace[1904140521] 'read index received' (duration: 147.747644ms)","trace[1904140521] 'applied index is now lower than readState.Index' (duration: 35.701305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:29.56415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.608272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T22:46:29.564142Z","caller":"traceutil/trace.go:171","msg":"trace[649528980] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"463.865832ms","start":"2026-04-01T22:46:29.100251Z","end":"2026-04-01T22:46:29.564117Z","steps":["trace[649528980] 'process raft request' (duration: 128.143942ms)","trace[649528980] 'compare' (duration: 334.976921ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:29.564196Z","caller":"traceutil/trace.go:171","msg":"trace[1598722116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"183.671864ms","start":"2026-04-01T22:46:29.380509Z","end":"2026-04-01T22:46:29.564181Z","steps":["trace[1598722116] 'agreement among raft nodes before linearized reading' (duration: 183.543091ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:29.564199Z","caller":"traceutil/trace.go:171","msg":"trace[1965481391] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"463.308628ms","start":"2026-04-01T22:46:29.100835Z","end":"2026-04-01T22:46:29.564144Z","steps":["trace[1965481391] 'process raft request' (duration: 463.066181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:29.564269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:29.10022Z","time spent":"463.975464ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T22:46:29.564457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:29.100819Z","time spent":"463.495022ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:30.472489Z","caller":"traceutil/trace.go:171","msg":"trace[1891360883] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"143.398103ms","start":"2026-04-01T22:46:30.329066Z","end":"2026-04-01T22:46:30.472465Z","steps":["trace[1891360883] 'read index received' (duration: 143.26077ms)","trace[1891360883] 'applied index is now lower than readState.Index' (duration: 136.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:30.472593Z","caller":"traceutil/trace.go:171","msg":"trace[1234493685] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"241.383073ms","start":"2026-04-01T22:46:30.231175Z","end":"2026-04-01T22:46:30.472558Z","steps":["trace[1234493685] 'process raft request' (duration: 241.169907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:30.47271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.616909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T22:46:30.472775Z","caller":"traceutil/trace.go:171","msg":"trace[227028898] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1246; }","duration":"143.735013ms","start":"2026-04-01T22:46:30.329026Z","end":"2026-04-01T22:46:30.472761Z","steps":["trace[227028898] 'agreement among raft nodes before linearized reading' (duration: 143.542257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:31.2388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.838523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:46:31.238901Z","caller":"traceutil/trace.go:171","msg":"trace[69472192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"393.047998ms","start":"2026-04-01T22:46:30.84583Z","end":"2026-04-01T22:46:31.238878Z","steps":["trace[69472192] 'range keys from in-memory index tree' (duration: 392.757291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:31.238953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:30.845813Z","time spent":"393.126171ms","remote":"127.0.0.1:42498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T22:46:31.396875Z","caller":"traceutil/trace.go:171","msg":"trace[1463245203] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"439.256221ms","start":"2026-04-01T22:46:30.957595Z","end":"2026-04-01T22:46:31.396852Z","steps":["trace[1463245203] 'read index received' (duration: 438.999554ms)","trace[1463245203] 'applied index is now lower than readState.Index' (duration: 255.517µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:31.397042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.430895ms","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-01T22:46:31.397026Z","caller":"traceutil/trace.go:171","msg":"trace[1667685559] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"447.722668ms","start":"2026-04-01T22:46:30.949271Z","end":"2026-04-01T22:46:31.396994Z","steps":["trace[1667685559] 'process raft request' (duration: 447.373949ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:31.397075Z","caller":"traceutil/trace.go:171","msg":"trace[1007844291] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1247; }","duration":"439.523167ms","start":"2026-04-01T22:46:30.957542Z","end":"2026-04-01T22:46:31.397065Z","steps":["trace[1007844291] 'agreement among raft nodes before linearized reading' (duration: 439.415455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:31.397108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:30.957523Z","time spent":"439.578109ms","remote":"127.0.0.1:52570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"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-01T22:46:31.397171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:30.949252Z","time spent":"447.84697ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5963,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:31.672009Z","caller":"traceutil/trace.go:171","msg":"trace[772249031] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"571.926152ms","start":"2026-04-01T22:46:31.10006Z","end":"2026-04-01T22:46:31.671986Z","steps":["trace[772249031] 'process raft request' (duration: 507.409859ms)","trace[772249031] 'compare' (duration: 64.276937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:31.672142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:31.100037Z","time spent":"572.037754ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T22:46:31.759968Z","caller":"traceutil/trace.go:171","msg":"trace[1044268477] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"363.008749ms","start":"2026-04-01T22:46:31.396934Z","end":"2026-04-01T22:46:31.759943Z","steps":["trace[1044268477] 'read index received' (duration: 210.452612ms)","trace[1044268477] 'applied index is now lower than readState.Index' (duration: 152.554347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:31.76014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.095672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:46:31.760194Z","caller":"traceutil/trace.go:171","msg":"trace[415471660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"520.166244ms","start":"2026-04-01T22:46:31.240012Z","end":"2026-04-01T22:46:31.760178Z","steps":["trace[415471660] 'agreement among raft nodes before linearized reading' (duration: 520.058201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:31.760172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.899433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T22:46:31.760233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:31.239947Z","time spent":"520.277337ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T22:46:31.760269Z","caller":"traceutil/trace.go:171","msg":"trace[1053617074] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1248; }","duration":"375.050537ms","start":"2026-04-01T22:46:31.385195Z","end":"2026-04-01T22:46:31.760245Z","steps":["trace[1053617074] 'agreement among raft nodes before linearized reading' (duration: 374.866522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:31.760328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T22:46:31.385174Z","time spent":"375.137359ms","remote":"127.0.0.1:42612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-01T22:46:31.760457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.473634ms","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-01T22:46:31.760533Z","caller":"traceutil/trace.go:171","msg":"trace[1347977187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"175.582797ms","start":"2026-04-01T22:46:31.584932Z","end":"2026-04-01T22:46:31.760514Z","steps":["trace[1347977187] 'agreement among raft nodes before linearized reading' (duration: 175.31242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:31.760526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.636783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T22:46:31.760745Z","caller":"traceutil/trace.go:171","msg":"trace[7515484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"190.89452ms","start":"2026-04-01T22:46:31.569819Z","end":"2026-04-01T22:46:31.760713Z","steps":["trace[7515484] 'agreement among raft nodes before linearized reading' (duration: 190.410187ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:32.052745Z","caller":"traceutil/trace.go:171","msg":"trace[1980611483] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"180.267787ms","start":"2026-04-01T22:46:31.872452Z","end":"2026-04-01T22:46:32.052719Z","steps":["trace[1980611483] 'read index received' (duration: 83.846848ms)","trace[1980611483] 'applied index is now lower than readState.Index' (duration: 96.419459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:32.052987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.516144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T22:46:32.053024Z","caller":"traceutil/trace.go:171","msg":"trace[1301320287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"180.603357ms","start":"2026-04-01T22:46:31.87241Z","end":"2026-04-01T22:46:32.053013Z","steps":["trace[1301320287] 'agreement among raft nodes before linearized reading' (duration: 180.439413ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:32.052968Z","caller":"traceutil/trace.go:171","msg":"trace[1045702290] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"203.855652ms","start":"2026-04-01T22:46:31.849081Z","end":"2026-04-01T22:46:32.052937Z","steps":["trace[1045702290] 'process raft request' (duration: 107.289278ms)","trace[1045702290] 'compare' (duration: 96.009719ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:33.126023Z","caller":"traceutil/trace.go:171","msg":"trace[1957276440] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"154.724534ms","start":"2026-04-01T22:46:32.971271Z","end":"2026-04-01T22:46:33.125995Z","steps":["trace[1957276440] 'read index received' (duration: 130.525823ms)","trace[1957276440] 'applied index is now lower than readState.Index' (duration: 24.19753ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:46:33.126063Z","caller":"traceutil/trace.go:171","msg":"trace[466753311] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"184.217319ms","start":"2026-04-01T22:46:32.941822Z","end":"2026-04-01T22:46:33.12604Z","steps":["trace[466753311] 'process raft request' (duration: 159.985318ms)","trace[466753311] 'compare' (duration: 23.994175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T22:46:33.126217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.894569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:46:33.126541Z","caller":"traceutil/trace.go:171","msg":"trace[694962262] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1258; }","duration":"155.255188ms","start":"2026-04-01T22:46:32.971262Z","end":"2026-04-01T22:46:33.126517Z","steps":["trace[694962262] 'agreement among raft nodes before linearized reading' (duration: 154.817987ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:33.308255Z","caller":"traceutil/trace.go:171","msg":"trace[182348233] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"108.020968ms","start":"2026-04-01T22:46:33.200209Z","end":"2026-04-01T22:46:33.30823Z","steps":["trace[182348233] 'process raft request' (duration: 106.848587ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:33.309362Z","caller":"traceutil/trace.go:171","msg":"trace[201290258] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"105.715688ms","start":"2026-04-01T22:46:33.203617Z","end":"2026-04-01T22:46:33.309332Z","steps":["trace[201290258] 'process raft request' (duration: 105.550024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T22:46:43.142058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.018138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T22:46:43.14214Z","caller":"traceutil/trace.go:171","msg":"trace[2001814827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"297.146531ms","start":"2026-04-01T22:46:42.844974Z","end":"2026-04-01T22:46:43.142121Z","steps":["trace[2001814827] 'range keys from in-memory index tree' (duration: 296.922236ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:46:43.472775Z","caller":"traceutil/trace.go:171","msg":"trace[1454582812] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"143.244999ms","start":"2026-04-01T22:46:43.329504Z","end":"2026-04-01T22:46:43.472749Z","steps":["trace[1454582812] 'process raft request' (duration: 143.103605ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:47:24.35616Z","caller":"traceutil/trace.go:171","msg":"trace[999433159] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"127.243355ms","start":"2026-04-01T22:47:24.228887Z","end":"2026-04-01T22:47:24.356131Z","steps":["trace[999433159] 'process raft request' (duration: 127.135152ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T22:47:24.35617Z","caller":"traceutil/trace.go:171","msg":"trace[135087928] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"127.264196ms","start":"2026-04-01T22:47:24.228856Z","end":"2026-04-01T22:47:24.35612Z","steps":["trace[135087928] 'process raft request' (duration: 62.33725ms)","trace[135087928] 'compare' (duration: 64.67492ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:47:24.518114Z","caller":"traceutil/trace.go:171","msg":"trace[2080238265] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"154.471308ms","start":"2026-04-01T22:47:24.363601Z","end":"2026-04-01T22:47:24.518072Z","steps":["trace[2080238265] 'process raft request' (duration: 127.84431ms)","trace[2080238265] 'compare' (duration: 26.31057ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T22:48:12.628356Z","caller":"traceutil/trace.go:171","msg":"trace[439187403] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"171.30808ms","start":"2026-04-01T22:48:12.457011Z","end":"2026-04-01T22:48:12.628319Z","steps":["trace[439187403] 'process raft request' (duration: 170.914391ms)"],"step_count":1}