{"level":"warn","ts":"2026-10-04T05:48:42.937175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:48:42.937283Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-10-04T05:48:42.937476Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:48:42.937491Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-10-04T05:48:42.937569Z","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-10-04T05:48:42.938957Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-10-04T05:48:42.939031Z","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://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-10-04T05:48:42.945224Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.987442ms"} {"level":"info","ts":"2026-10-04T05:48:42.952438Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-10-04T05:48:42.952607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T05:48:42.952634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-10-04T05:48:42.952648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T05:48:42.95266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-10-04T05:48:42.95271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-10-04T05:48:42.964985Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T05:48:42.969694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T05:48:42.971138Z","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-10-04T05:48:42.974059Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T05:48:42.974357Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T05:48:42.974807Z","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-10-04T05:48:42.976689Z","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-10-04T05:48:42.976762Z","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-10-04T05:48:42.97719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-10-04T05:48:42.977317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-10-04T05:48:42.978081Z","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-10-04T05:48:42.978124Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-10-04T05:48:42.978184Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-10-04T05:48:42.97836Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T05:48:42.978386Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T05:48:43.953951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T05:48:43.954049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T05:48:43.954122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-10-04T05:48:43.954185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-10-04T05:48:43.954203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-10-04T05:48:43.954222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-10-04T05:48:43.954244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-10-04T05:48:43.955545Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:48:43.955535Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T05:48:43.955523Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:48:43.955747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:48:43.956055Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T05:48:43.956119Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T05:48:43.959473Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:48:43.959528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T05:48:43.959615Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:48:43.959663Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:48:43.959715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-10-04T05:48:47.065743Z","caller":"traceutil/trace.go:171","msg":"trace[690916432] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"253.386ms","start":"2026-10-04T05:48:46.812326Z","end":"2026-10-04T05:48:47.065712Z","steps":["trace[690916432] 'process raft request' (duration: 247.301116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:48.813184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058278627s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:48:48.813372Z","caller":"traceutil/trace.go:171","msg":"trace[1365316716] linearizableReadLoop","detail":"{readStateIndex:175; appliedIndex:174; }","duration":"1.185645826s","start":"2026-10-04T05:48:47.627636Z","end":"2026-10-04T05:48:48.813282Z","steps":["trace[1365316716] 'read index received' (duration: 126.670854ms)","trace[1365316716] 'applied index is now lower than readState.Index' (duration: 1.058973802s)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:48:48.813451Z","caller":"traceutil/trace.go:171","msg":"trace[635064714] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"1.486634777s","start":"2026-10-04T05:48:47.326806Z","end":"2026-10-04T05:48:48.813441Z","steps":["trace[635064714] 'process raft request' (duration: 427.566203ms)","trace[635064714] 'compare' (duration: 1.058101743s)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:48:48.813508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:47.326789Z","time spent":"1.486693289s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:48:48.813585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183854786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-10-04T05:48:48.813632Z","caller":"traceutil/trace.go:171","msg":"trace[1397863600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:171; }","duration":"1.183938169s","start":"2026-10-04T05:48:47.62968Z","end":"2026-10-04T05:48:48.813618Z","steps":["trace[1397863600] 'agreement among raft nodes before linearized reading' (duration: 1.183787865s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:48.813662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:47.62967Z","time spent":"1.183985569s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T05:48:48.813734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.813243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:48:48.813826Z","caller":"traceutil/trace.go:171","msg":"trace[304562589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:171; }","duration":"493.961348ms","start":"2026-10-04T05:48:48.319841Z","end":"2026-10-04T05:48:48.813803Z","steps":["trace[304562589] 'agreement among raft nodes before linearized reading' (duration: 493.774213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:48.813847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18619289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-04T05:48:48.813796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16905941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:48:48.813888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.319819Z","time spent":"494.060189ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T05:48:48.813897Z","caller":"traceutil/trace.go:171","msg":"trace[689582599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:171; }","duration":"1.186317342s","start":"2026-10-04T05:48:47.627561Z","end":"2026-10-04T05:48:48.813879Z","steps":["trace[689582599] 'agreement among raft nodes before linearized reading' (duration: 1.186183488s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:48.813931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:47.627528Z","time spent":"1.186396184s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-04T05:48:48.813973Z","caller":"traceutil/trace.go:171","msg":"trace[1575320628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:171; }","duration":"1.169198814s","start":"2026-10-04T05:48:47.64469Z","end":"2026-10-04T05:48:48.813889Z","steps":["trace[1575320628] 'agreement among raft nodes before linearized reading' (duration: 1.16902513s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:48.814028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:47.64468Z","time spent":"1.169337897s","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T05:48:49.199226Z","caller":"traceutil/trace.go:171","msg":"trace[42638960] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"380.234639ms","start":"2026-10-04T05:48:48.818967Z","end":"2026-10-04T05:48:49.199202Z","steps":["trace[42638960] 'process raft request' (duration: 379.997253ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:49.199649Z","caller":"traceutil/trace.go:171","msg":"trace[62062818] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:175; }","duration":"354.896079ms","start":"2026-10-04T05:48:48.844683Z","end":"2026-10-04T05:48:49.199579Z","steps":["trace[62062818] 'read index received' (duration: 48.713934ms)","trace[62062818] 'applied index is now lower than readState.Index' (duration: 306.180895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:48:49.199702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.81895Z","time spent":"380.356952ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:48:49.199733Z","caller":"traceutil/trace.go:171","msg":"trace[395994290] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"378.650493ms","start":"2026-10-04T05:48:48.821052Z","end":"2026-10-04T05:48:49.199703Z","steps":["trace[395994290] 'process raft request' (duration: 378.427538ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:48:49.199753Z","caller":"traceutil/trace.go:171","msg":"trace[1723715161] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"379.802599ms","start":"2026-10-04T05:48:48.819927Z","end":"2026-10-04T05:48:49.19973Z","steps":["trace[1723715161] 'process raft request' (duration: 379.194826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:49.199813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.82104Z","time spent":"378.731035ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:48:49.199838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.144803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:48:49.199877Z","caller":"traceutil/trace.go:171","msg":"trace[1631602048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"355.266647ms","start":"2026-10-04T05:48:48.844601Z","end":"2026-10-04T05:48:49.199868Z","steps":["trace[1631602048] 'agreement among raft nodes before linearized reading' (duration: 355.147414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:49.199901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.819906Z","time spent":"379.89916ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:48:49.199967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.844567Z","time spent":"355.332359ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:48:49.200592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.898407Z","time spent":"302.181866ms","remote":"127.0.0.1:44666","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-04T05:48:49.200598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.467785ms","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-10-04T05:48:49.200654Z","caller":"traceutil/trace.go:171","msg":"trace[1049323217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:175; }","duration":"303.546206ms","start":"2026-10-04T05:48:48.897093Z","end":"2026-10-04T05:48:49.20064Z","steps":["trace[1049323217] 'agreement among raft nodes before linearized reading' (duration: 303.455924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:48:49.200691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:48:48.897081Z","time spent":"303.600248ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T05:49:07.5194Z","caller":"traceutil/trace.go:171","msg":"trace[1651548203] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"121.641104ms","start":"2026-10-04T05:49:07.397727Z","end":"2026-10-04T05:49:07.519368Z","steps":["trace[1651548203] 'process raft request' (duration: 78.859951ms)","trace[1651548203] 'compare' (duration: 42.670091ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:22.66555Z","caller":"traceutil/trace.go:171","msg":"trace[730733873] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"495.725486ms","start":"2026-10-04T05:49:22.169801Z","end":"2026-10-04T05:49:22.665527Z","steps":["trace[730733873] 'process raft request' (duration: 495.589903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.665709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.169778Z","time spent":"495.850918ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:22.682019Z","caller":"traceutil/trace.go:171","msg":"trace[692871945] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"351.228208ms","start":"2026-10-04T05:49:22.33074Z","end":"2026-10-04T05:49:22.681968Z","steps":["trace[692871945] 'process raft request' (duration: 350.90262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.682216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.330718Z","time spent":"351.397782ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:22.682367Z","caller":"traceutil/trace.go:171","msg":"trace[1928217453] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"323.762463ms","start":"2026-10-04T05:49:22.358555Z","end":"2026-10-04T05:49:22.682318Z","steps":["trace[1928217453] 'process raft request' (duration: 323.360144ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:22.682478Z","caller":"traceutil/trace.go:171","msg":"trace[78581893] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"347.727781ms","start":"2026-10-04T05:49:22.334685Z","end":"2026-10-04T05:49:22.682413Z","steps":["trace[78581893] 'process raft request' (duration: 347.154348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.682568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.35853Z","time spent":"323.953496ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:22.682535Z","caller":"traceutil/trace.go:171","msg":"trace[1814646113] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"323.827524ms","start":"2026-10-04T05:49:22.358658Z","end":"2026-10-04T05:49:22.682486Z","steps":["trace[1814646113] 'process raft request' (duration: 323.321743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.682622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.334659Z","time spent":"347.904225ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:49:22.682703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.358651Z","time spent":"323.985797ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:22.682732Z","caller":"traceutil/trace.go:171","msg":"trace[1734052475] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"314.213411ms","start":"2026-10-04T05:49:22.368502Z","end":"2026-10-04T05:49:22.682716Z","steps":["trace[1734052475] 'process raft request' (duration: 313.71717ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:22.682747Z","caller":"traceutil/trace.go:171","msg":"trace[284808229] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"290.385445ms","start":"2026-10-04T05:49:22.392344Z","end":"2026-10-04T05:49:22.68273Z","steps":["trace[284808229] 'process raft request' (duration: 289.962006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.682794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.368488Z","time spent":"314.275363ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:22.682827Z","caller":"traceutil/trace.go:171","msg":"trace[1853663474] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"347.890424ms","start":"2026-10-04T05:49:22.334925Z","end":"2026-10-04T05:49:22.682815Z","steps":["trace[1853663474] 'process raft request' (duration: 346.953384ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:22.682875Z","caller":"traceutil/trace.go:171","msg":"trace[741430535] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"288.726169ms","start":"2026-10-04T05:49:22.394134Z","end":"2026-10-04T05:49:22.68286Z","steps":["trace[741430535] 'process raft request' (duration: 288.329091ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:22.682894Z","caller":"traceutil/trace.go:171","msg":"trace[1198435120] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"323.446826ms","start":"2026-10-04T05:49:22.359435Z","end":"2026-10-04T05:49:22.682881Z","steps":["trace[1198435120] 'process raft request' (duration: 322.634718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.682925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.334914Z","time spent":"347.983806ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:49:22.682942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.35941Z","time spent":"323.507467ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:22.682978Z","caller":"traceutil/trace.go:171","msg":"trace[2053255681] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"261.692383ms","start":"2026-10-04T05:49:22.421274Z","end":"2026-10-04T05:49:22.682966Z","steps":["trace[2053255681] 'process raft request' (duration: 261.290565ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:22.682964Z","caller":"traceutil/trace.go:171","msg":"trace[833391486] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"130.159271ms","start":"2026-10-04T05:49:22.552753Z","end":"2026-10-04T05:49:22.682912Z","steps":["trace[833391486] 'process raft request' (duration: 129.996628ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:22.682401Z","caller":"traceutil/trace.go:171","msg":"trace[1714056905] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"323.534507ms","start":"2026-10-04T05:49:22.358815Z","end":"2026-10-04T05:49:22.682349Z","steps":["trace[1714056905] 'process raft request' (duration: 323.21ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:22.683217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.358806Z","time spent":"324.352105ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:49:23.210647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.213425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:23.210763Z","caller":"traceutil/trace.go:171","msg":"trace[1607880750] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"308.22452ms","start":"2026-10-04T05:49:22.902519Z","end":"2026-10-04T05:49:23.210743Z","steps":["trace[1607880750] 'process raft request' (duration: 64.818571ms)","trace[1607880750] 'compare' (duration: 243.036521ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:23.210822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.902499Z","time spent":"308.296021ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:24.25378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.809141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:24.253959Z","caller":"traceutil/trace.go:171","msg":"trace[471723424] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.302125586s","start":"2026-10-04T05:49:22.951817Z","end":"2026-10-04T05:49:24.253942Z","steps":["trace[471723424] 'process raft request' (duration: 1.302067005s)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:24.253965Z","caller":"traceutil/trace.go:171","msg":"trace[1619469032] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.345628676s","start":"2026-10-04T05:49:22.908313Z","end":"2026-10-04T05:49:24.253942Z","steps":["trace[1619469032] 'process raft request' (duration: 586.583941ms)","trace[1619469032] 'compare' (duration: 758.638576ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:24.254013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.951802Z","time spent":"1.302187098s","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:49:24.254051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:22.908299Z","time spent":"1.345705558s","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:24.313208Z","caller":"traceutil/trace.go:171","msg":"trace[736169525] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"918.182186ms","start":"2026-10-04T05:49:23.395004Z","end":"2026-10-04T05:49:24.313186Z","steps":["trace[736169525] 'read index received' (duration: 99.984194ms)","trace[736169525] 'applied index is now lower than readState.Index' (duration: 818.196802ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:24.313237Z","caller":"traceutil/trace.go:171","msg":"trace[397942432] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.066431367s","start":"2026-10-04T05:49:23.246784Z","end":"2026-10-04T05:49:24.313215Z","steps":["trace[397942432] 'process raft request' (duration: 1.066221653s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:24.31338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:23.246731Z","time spent":"1.0665624s","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:24.313413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.380741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-04T05:49:24.313483Z","caller":"traceutil/trace.go:171","msg":"trace[498766222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"918.503163ms","start":"2026-10-04T05:49:23.394964Z","end":"2026-10-04T05:49:24.313467Z","steps":["trace[498766222] 'agreement among raft nodes before linearized reading' (duration: 918.311869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:24.31352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:23.39495Z","time spent":"918.562655ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T05:49:24.881526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.77786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:24.881661Z","caller":"traceutil/trace.go:171","msg":"trace[393675168] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"568.361828ms","start":"2026-10-04T05:49:24.313275Z","end":"2026-10-04T05:49:24.881637Z","steps":["trace[393675168] 'read index received' (duration: 86.412046ms)","trace[393675168] 'applied index is now lower than readState.Index' (duration: 481.948252ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:24.881705Z","caller":"traceutil/trace.go:171","msg":"trace[342225038] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"613.422223ms","start":"2026-10-04T05:49:24.268241Z","end":"2026-10-04T05:49:24.881664Z","steps":["trace[342225038] 'process raft request' (duration: 131.377688ms)","trace[342225038] 'compare' (duration: 481.589954ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:24.881782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41244288s","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-10-04T05:49:24.881815Z","caller":"traceutil/trace.go:171","msg":"trace[1110908194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.412494232s","start":"2026-10-04T05:49:23.469308Z","end":"2026-10-04T05:49:24.881802Z","steps":["trace[1110908194] 'agreement among raft nodes before linearized reading' (duration: 1.41241857s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:24.881834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138720641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-04T05:49:24.88185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:23.469298Z","time spent":"1.412544053s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T05:49:24.881892Z","caller":"traceutil/trace.go:171","msg":"trace[2124657586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"1.138824323s","start":"2026-10-04T05:49:23.743052Z","end":"2026-10-04T05:49:24.881877Z","steps":["trace[2124657586] 'agreement among raft nodes before linearized reading' (duration: 1.13866781s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:24.881893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.268215Z","time spent":"613.572455ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:24.881894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.07765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:49:24.881931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:23.743035Z","time spent":"1.138889214s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T05:49:24.881942Z","caller":"traceutil/trace.go:171","msg":"trace[833793000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"834.168922ms","start":"2026-10-04T05:49:24.04776Z","end":"2026-10-04T05:49:24.881929Z","steps":["trace[833793000] 'agreement among raft nodes before linearized reading' (duration: 834.083751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:24.881976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.047739Z","time spent":"834.230894ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T05:49:25.260314Z","caller":"traceutil/trace.go:171","msg":"trace[1930398918] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"942.125727ms","start":"2026-10-04T05:49:24.318161Z","end":"2026-10-04T05:49:25.260286Z","steps":["trace[1930398918] 'process raft request' (duration: 912.423359ms)","trace[1930398918] 'compare' (duration: 29.481624ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:25.260443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.318124Z","time spent":"942.25274ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:25.260543Z","caller":"traceutil/trace.go:171","msg":"trace[1564777415] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"907.753839ms","start":"2026-10-04T05:49:24.352747Z","end":"2026-10-04T05:49:25.2605Z","steps":["trace[1564777415] 'process raft request' (duration: 907.485593ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:25.260551Z","caller":"traceutil/trace.go:171","msg":"trace[1812088572] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"883.426672ms","start":"2026-10-04T05:49:24.37709Z","end":"2026-10-04T05:49:25.260516Z","steps":["trace[1812088572] 'process raft request' (duration: 883.288989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.260679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.377065Z","time spent":"883.552206ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:49:25.260945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.35273Z","time spent":"907.883411ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:25.264131Z","caller":"traceutil/trace.go:171","msg":"trace[757901926] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"376.49175ms","start":"2026-10-04T05:49:24.887624Z","end":"2026-10-04T05:49:25.264116Z","steps":["trace[757901926] 'process raft request' (duration: 376.149232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.264235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.887594Z","time spent":"376.599792ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:25.264496Z","caller":"traceutil/trace.go:171","msg":"trace[693755202] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"382.797219ms","start":"2026-10-04T05:49:24.881688Z","end":"2026-10-04T05:49:25.264485Z","steps":["trace[693755202] 'read index received' (duration: 349.209593ms)","trace[693755202] 'applied index is now lower than readState.Index' (duration: 33.586616ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:25.26456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.966044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-04T05:49:25.264596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.822206ms","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":"info","ts":"2026-10-04T05:49:25.264609Z","caller":"traceutil/trace.go:171","msg":"trace[360222811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"592.058046ms","start":"2026-10-04T05:49:24.672536Z","end":"2026-10-04T05:49:25.264594Z","steps":["trace[360222811] 'agreement among raft nodes before linearized reading' (duration: 591.968044ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:25.26463Z","caller":"traceutil/trace.go:171","msg":"trace[1610423753] 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:587; }","duration":"817.860686ms","start":"2026-10-04T05:49:24.446757Z","end":"2026-10-04T05:49:25.264617Z","steps":["trace[1610423753] 'agreement among raft nodes before linearized reading' (duration: 817.802015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.264641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.67251Z","time spent":"592.124847ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T05:49:25.264636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.181087ms","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-10-04T05:49:25.26466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.446722Z","time spent":"817.930398ms","remote":"127.0.0.1:40412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-10-04T05:49:25.264678Z","caller":"traceutil/trace.go:171","msg":"trace[323184059] 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:587; }","duration":"878.242528ms","start":"2026-10-04T05:49:24.386423Z","end":"2026-10-04T05:49:25.264666Z","steps":["trace[323184059] 'agreement among raft nodes before linearized reading' (duration: 878.175347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.264709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.386418Z","time spent":"878.282999ms","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-04T05:49:25.264731Z","caller":"traceutil/trace.go:171","msg":"trace[765322062] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"376.243874ms","start":"2026-10-04T05:49:24.888476Z","end":"2026-10-04T05:49:25.26472Z","steps":["trace[765322062] 'process raft request' (duration: 375.557159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.264785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.888463Z","time spent":"376.286484ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:25.26486Z","caller":"traceutil/trace.go:171","msg":"trace[1628724114] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"357.729694ms","start":"2026-10-04T05:49:24.907122Z","end":"2026-10-04T05:49:25.264852Z","steps":["trace[1628724114] 'process raft request' (duration: 357.270915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.26486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.573645ms","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-10-04T05:49:25.264869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.47555ms","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-10-04T05:49:25.264894Z","caller":"traceutil/trace.go:171","msg":"trace[1276992140] 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:587; }","duration":"878.620006ms","start":"2026-10-04T05:49:24.386261Z","end":"2026-10-04T05:49:25.264881Z","steps":["trace[1276992140] 'agreement among raft nodes before linearized reading' (duration: 878.572305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.264919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.907096Z","time spent":"357.780356ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:25.264931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.386252Z","time spent":"878.670308ms","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"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-10-04T05:49:25.264978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.537959ms","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":"info","ts":"2026-10-04T05:49:25.265001Z","caller":"traceutil/trace.go:171","msg":"trace[1171639384] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:587; }","duration":"846.58025ms","start":"2026-10-04T05:49:24.418414Z","end":"2026-10-04T05:49:25.264994Z","steps":["trace[1171639384] 'agreement among raft nodes before linearized reading' (duration: 846.54408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.418403Z","time spent":"846.615471ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-10-04T05:49:25.265066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.285852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:49:25.265067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.197248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.265094Z","caller":"traceutil/trace.go:171","msg":"trace[2123045763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:587; }","duration":"903.468895ms","start":"2026-10-04T05:49:24.361617Z","end":"2026-10-04T05:49:25.265086Z","steps":["trace[2123045763] 'agreement among raft nodes before linearized reading' (duration: 903.424824ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:25.265099Z","caller":"traceutil/trace.go:171","msg":"trace[483983122] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"529.237479ms","start":"2026-10-04T05:49:24.735851Z","end":"2026-10-04T05:49:25.265088Z","steps":["trace[483983122] 'agreement among raft nodes before linearized reading' (duration: 529.188078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.361577Z","time spent":"903.535687ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-10-04T05:49:25.265125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.735847Z","time spent":"529.27043ms","remote":"127.0.0.1:40438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:49:25.265183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.192003ms","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-10-04T05:49:25.265209Z","caller":"traceutil/trace.go:171","msg":"trace[1514771534] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:587; }","duration":"847.280185ms","start":"2026-10-04T05:49:24.41792Z","end":"2026-10-04T05:49:25.2652Z","steps":["trace[1514771534] 'agreement among raft nodes before linearized reading' (duration: 847.240414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.417894Z","time spent":"847.331446ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:49:25.265293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.458674ms","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-10-04T05:49:25.265306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.928882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.265318Z","caller":"traceutil/trace.go:171","msg":"trace[1569553853] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"529.484924ms","start":"2026-10-04T05:49:24.735825Z","end":"2026-10-04T05:49:25.26531Z","steps":["trace[1569553853] 'agreement among raft nodes before linearized reading' (duration: 529.443303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.606784ms","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":"warn","ts":"2026-10-04T05:49:25.265345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.73582Z","time spent":"529.517125ms","remote":"127.0.0.1:40438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-10-04T05:49:25.265365Z","caller":"traceutil/trace.go:171","msg":"trace[246078112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:587; }","duration":"847.651294ms","start":"2026-10-04T05:49:24.417706Z","end":"2026-10-04T05:49:25.265357Z","steps":["trace[246078112] 'agreement among raft nodes before linearized reading' (duration: 847.613043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.417693Z","time spent":"847.690525ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-10-04T05:49:25.26547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.829233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.265494Z","caller":"traceutil/trace.go:171","msg":"trace[988160941] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"529.892794ms","start":"2026-10-04T05:49:24.735593Z","end":"2026-10-04T05:49:25.265486Z","steps":["trace[988160941] 'agreement among raft nodes before linearized reading' (duration: 529.837593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.494515ms","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-10-04T05:49:25.265556Z","caller":"traceutil/trace.go:171","msg":"trace[15862405] 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:587; }","duration":"878.534675ms","start":"2026-10-04T05:49:24.387013Z","end":"2026-10-04T05:49:25.265547Z","steps":["trace[15862405] 'agreement among raft nodes before linearized reading' (duration: 878.490234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.26552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.735571Z","time spent":"529.940676ms","remote":"127.0.0.1:40430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-10-04T05:49:25.26559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.387006Z","time spent":"878.575957ms","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"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-10-04T05:49:25.265714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.607604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.265739Z","caller":"traceutil/trace.go:171","msg":"trace[815670653] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"561.698795ms","start":"2026-10-04T05:49:24.704032Z","end":"2026-10-04T05:49:25.265731Z","steps":["trace[815670653] 'agreement among raft nodes before linearized reading' (duration: 561.655494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.70401Z","time spent":"561.746676ms","remote":"127.0.0.1:40430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-10-04T05:49:25.265871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.848388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.265894Z","caller":"traceutil/trace.go:171","msg":"trace[245615600] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"561.90238ms","start":"2026-10-04T05:49:24.703983Z","end":"2026-10-04T05:49:25.265886Z","steps":["trace[245615600] 'agreement among raft nodes before linearized reading' (duration: 561.864899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.703968Z","time spent":"561.9457ms","remote":"127.0.0.1:40430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-10-04T05:49:25.265931Z","caller":"traceutil/trace.go:171","msg":"trace[973006274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:587; }","duration":"906.981323ms","start":"2026-10-04T05:49:24.358346Z","end":"2026-10-04T05:49:25.265327Z","steps":["trace[973006274] 'agreement among raft nodes before linearized reading' (duration: 906.931902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.265982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.358338Z","time spent":"907.629286ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:49:25.266025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.589442ms","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-10-04T05:49:25.266065Z","caller":"traceutil/trace.go:171","msg":"trace[82021068] 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:587; }","duration":"585.679444ms","start":"2026-10-04T05:49:24.680376Z","end":"2026-10-04T05:49:25.266056Z","steps":["trace[82021068] 'agreement among raft nodes before linearized reading' (duration: 585.626003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.26609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.680352Z","time spent":"585.730145ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-10-04T05:49:25.266122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.140707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.266169Z","caller":"traceutil/trace.go:171","msg":"trace[1318272900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:587; }","duration":"908.222899ms","start":"2026-10-04T05:49:24.357936Z","end":"2026-10-04T05:49:25.266159Z","steps":["trace[1318272900] 'agreement among raft nodes before linearized reading' (duration: 908.158468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.266202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.357914Z","time spent":"908.276421ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-10-04T05:49:25.266243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.820449ms","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-10-04T05:49:25.266269Z","caller":"traceutil/trace.go:171","msg":"trace[131190991] 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:587; }","duration":"586.867491ms","start":"2026-10-04T05:49:24.679392Z","end":"2026-10-04T05:49:25.26626Z","steps":["trace[131190991] 'agreement among raft nodes before linearized reading' (duration: 586.82621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.266293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.679386Z","time spent":"586.899791ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:49:25.266323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.936072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:25.266348Z","caller":"traceutil/trace.go:171","msg":"trace[2076648128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"383.009333ms","start":"2026-10-04T05:49:24.883331Z","end":"2026-10-04T05:49:25.26634Z","steps":["trace[2076648128] 'agreement among raft nodes before linearized reading' (duration: 382.970153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.26637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.883301Z","time spent":"383.064155ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:49:25.266403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.030315ms","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-10-04T05:49:25.266428Z","caller":"traceutil/trace.go:171","msg":"trace[1739143610] 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:587; }","duration":"587.055835ms","start":"2026-10-04T05:49:24.679363Z","end":"2026-10-04T05:49:25.266419Z","steps":["trace[1739143610] 'agreement among raft nodes before linearized reading' (duration: 587.013604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.2665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.679273Z","time spent":"587.173838ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-10-04T05:49:25.264898Z","caller":"traceutil/trace.go:171","msg":"trace[1237619910] 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:587; }","duration":"818.543651ms","start":"2026-10-04T05:49:24.446345Z","end":"2026-10-04T05:49:25.264888Z","steps":["trace[1237619910] 'agreement among raft nodes before linearized reading' (duration: 818.49934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.26818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.446331Z","time spent":"821.804414ms","remote":"127.0.0.1:40412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-10-04T05:49:25.264932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.612083ms","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-10-04T05:49:25.268343Z","caller":"traceutil/trace.go:171","msg":"trace[962688180] 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:587; }","duration":"822.09154ms","start":"2026-10-04T05:49:24.446232Z","end":"2026-10-04T05:49:25.268324Z","steps":["trace[962688180] 'agreement among raft nodes before linearized reading' (duration: 818.681224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:25.268382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:24.446213Z","time spent":"822.154742ms","remote":"127.0.0.1:40412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-10-04T05:49:29.319372Z","caller":"traceutil/trace.go:171","msg":"trace[1029645845] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"221.045034ms","start":"2026-10-04T05:49:29.098304Z","end":"2026-10-04T05:49:29.319349Z","steps":["trace[1029645845] 'process raft request' (duration: 220.8593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:29.947638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026488175676725,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T05:49:30.224878Z","caller":"traceutil/trace.go:171","msg":"trace[682080895] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"900.502104ms","start":"2026-10-04T05:49:29.324337Z","end":"2026-10-04T05:49:30.224839Z","steps":["trace[682080895] 'process raft request' (duration: 900.377992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:30.225258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:29.324322Z","time spent":"900.767891ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:31.282905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.813715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:31.283076Z","caller":"traceutil/trace.go:171","msg":"trace[1797461716] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.958387881s","start":"2026-10-04T05:49:29.324666Z","end":"2026-10-04T05:49:31.283054Z","steps":["trace[1797461716] 'process raft request' (duration: 1.056350541s)","trace[1797461716] 'compare' (duration: 901.650431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:31.283167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:29.324655Z","time spent":"1.958458793s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:31.284277Z","caller":"traceutil/trace.go:171","msg":"trace[165080744] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.959505817s","start":"2026-10-04T05:49:29.324747Z","end":"2026-10-04T05:49:31.284253Z","steps":["trace[165080744] 'process raft request' (duration: 1.958251889s)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:31.284415Z","caller":"traceutil/trace.go:171","msg":"trace[836956311] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"1.837307596s","start":"2026-10-04T05:49:29.44709Z","end":"2026-10-04T05:49:31.284397Z","steps":["trace[836956311] 'read index received' (duration: 777.62652ms)","trace[836956311] 'applied index is now lower than readState.Index' (duration: 1.059680136s)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:31.284513Z","caller":"traceutil/trace.go:171","msg":"trace[897379367] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.958254308s","start":"2026-10-04T05:49:29.326247Z","end":"2026-10-04T05:49:31.284502Z","steps":["trace[897379367] 'process raft request' (duration: 1.957933371s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:31.28455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235432565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:49:31.284578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:29.326203Z","time spent":"1.95833431s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:31.284657Z","caller":"traceutil/trace.go:171","msg":"trace[853729239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.235513467s","start":"2026-10-04T05:49:30.049063Z","end":"2026-10-04T05:49:31.284576Z","steps":["trace[853729239] 'agreement among raft nodes before linearized reading' (duration: 1.235453396s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:31.284656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:29.324737Z","time spent":"1.959624999s","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:31.284695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:30.049044Z","time spent":"1.235644769s","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:49:31.284716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837608392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T05:49:31.284842Z","caller":"traceutil/trace.go:171","msg":"trace[931888746] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:627; }","duration":"1.837743705s","start":"2026-10-04T05:49:29.447032Z","end":"2026-10-04T05:49:31.284776Z","steps":["trace[931888746] 'agreement among raft nodes before linearized reading' (duration: 1.837575152s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:31.284891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:29.447013Z","time spent":"1.837869948s","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-10-04T05:49:31.786662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026488175676731,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T05:49:31.92335Z","caller":"traceutil/trace.go:171","msg":"trace[928033612] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"637.111822ms","start":"2026-10-04T05:49:31.286217Z","end":"2026-10-04T05:49:31.923329Z","steps":["trace[928033612] 'read index received' (duration: 633.772739ms)","trace[928033612] 'applied index is now lower than readState.Index' (duration: 3.336543ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:31.923466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.238355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:31.923493Z","caller":"traceutil/trace.go:171","msg":"trace[494690323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"637.305727ms","start":"2026-10-04T05:49:31.286178Z","end":"2026-10-04T05:49:31.923484Z","steps":["trace[494690323] 'agreement among raft nodes before linearized reading' (duration: 637.227295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:31.923521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:31.286158Z","time spent":"637.355847ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:49:31.923673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:31.285686Z","time spent":"637.985851ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-04T05:49:31.923838Z","caller":"traceutil/trace.go:171","msg":"trace[323994368] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"626.536477ms","start":"2026-10-04T05:49:31.297292Z","end":"2026-10-04T05:49:31.923829Z","steps":["trace[323994368] 'process raft request' (duration: 625.931434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:31.923922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:31.297274Z","time spent":"626.600839ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:33.565396Z","caller":"traceutil/trace.go:171","msg":"trace[271509821] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"271.701857ms","start":"2026-10-04T05:49:33.293665Z","end":"2026-10-04T05:49:33.565367Z","steps":["trace[271509821] 'process raft request' (duration: 271.519793ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:33.577956Z","caller":"traceutil/trace.go:171","msg":"trace[1461822511] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"280.550403ms","start":"2026-10-04T05:49:33.297385Z","end":"2026-10-04T05:49:33.577935Z","steps":["trace[1461822511] 'process raft request' (duration: 279.879828ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:33.579444Z","caller":"traceutil/trace.go:171","msg":"trace[891645451] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"281.441173ms","start":"2026-10-04T05:49:33.297976Z","end":"2026-10-04T05:49:33.579417Z","steps":["trace[891645451] 'process raft request' (duration: 279.464699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:35.398103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.077476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:35.398589Z","caller":"traceutil/trace.go:171","msg":"trace[61412384] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"216.81392ms","start":"2026-10-04T05:49:35.181722Z","end":"2026-10-04T05:49:35.398536Z","steps":["trace[61412384] 'process raft request' (duration: 216.725988ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:35.39848Z","caller":"traceutil/trace.go:171","msg":"trace[752088530] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"349.754979ms","start":"2026-10-04T05:49:35.04869Z","end":"2026-10-04T05:49:35.398445Z","steps":["trace[752088530] 'process raft request' (duration: 210.181153ms)","trace[752088530] 'compare' (duration: 138.818989ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:35.39875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:35.048671Z","time spent":"349.992274ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:49:35.398668Z","caller":"traceutil/trace.go:171","msg":"trace[1298053192] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"277.049195ms","start":"2026-10-04T05:49:35.121592Z","end":"2026-10-04T05:49:35.398642Z","steps":["trace[1298053192] 'process raft request' (duration: 276.730758ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:35.398694Z","caller":"traceutil/trace.go:171","msg":"trace[52438372] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"283.304413ms","start":"2026-10-04T05:49:35.115353Z","end":"2026-10-04T05:49:35.398657Z","steps":["trace[52438372] 'read index received' (duration: 143.455591ms)","trace[52438372] 'applied index is now lower than readState.Index' (duration: 139.845912ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:35.399111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.501036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-68clr\" ","response":"range_response_count:1 size:17790"} {"level":"warn","ts":"2026-10-04T05:49:35.399108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.027216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:49:35.399129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.752763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:35.399177Z","caller":"traceutil/trace.go:171","msg":"trace[2015268923] range","detail":"{range_begin:/registry/pods/kube-system/cilium-68clr; range_end:; response_count:1; response_revision:694; }","duration":"278.637201ms","start":"2026-10-04T05:49:35.120524Z","end":"2026-10-04T05:49:35.399161Z","steps":["trace[2015268923] 'agreement among raft nodes before linearized reading' (duration: 278.384305ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:35.399212Z","caller":"traceutil/trace.go:171","msg":"trace[37735276] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:694; }","duration":"159.185631ms","start":"2026-10-04T05:49:35.240004Z","end":"2026-10-04T05:49:35.399189Z","steps":["trace[37735276] 'agreement among raft nodes before linearized reading' (duration: 159.002206ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:35.399243Z","caller":"traceutil/trace.go:171","msg":"trace[667040278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"283.907437ms","start":"2026-10-04T05:49:35.11531Z","end":"2026-10-04T05:49:35.399217Z","steps":["trace[667040278] 'agreement among raft nodes before linearized reading' (duration: 283.466627ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:35.796186Z","caller":"traceutil/trace.go:171","msg":"trace[1365182070] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"124.468741ms","start":"2026-10-04T05:49:35.671693Z","end":"2026-10-04T05:49:35.796161Z","steps":["trace[1365182070] 'process raft request' (duration: 124.285757ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:36.699912Z","caller":"traceutil/trace.go:171","msg":"trace[1459136883] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"260.252322ms","start":"2026-10-04T05:49:36.439629Z","end":"2026-10-04T05:49:36.699881Z","steps":["trace[1459136883] 'read index received' (duration: 230.035872ms)","trace[1459136883] 'applied index is now lower than readState.Index' (duration: 30.21436ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:36.700022Z","caller":"traceutil/trace.go:171","msg":"trace[2077332179] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"419.911594ms","start":"2026-10-04T05:49:36.28007Z","end":"2026-10-04T05:49:36.699981Z","steps":["trace[2077332179] 'process raft request' (duration: 389.430808ms)","trace[2077332179] 'compare' (duration: 30.184159ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:36.700239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:36.280049Z","time spent":"420.062018ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:36.700244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.591371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-10-04T05:49:36.700501Z","caller":"traceutil/trace.go:171","msg":"trace[848581861] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:710; }","duration":"260.867737ms","start":"2026-10-04T05:49:36.43956Z","end":"2026-10-04T05:49:36.700428Z","steps":["trace[848581861] 'agreement among raft nodes before linearized reading' (duration: 260.473758ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:36.775478Z","caller":"traceutil/trace.go:171","msg":"trace[1821271118] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"321.166913ms","start":"2026-10-04T05:49:36.454282Z","end":"2026-10-04T05:49:36.775449Z","steps":["trace[1821271118] 'process raft request' (duration: 320.575011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:36.77568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:36.45424Z","time spent":"321.320938ms","remote":"127.0.0.1:44674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:49:36.77598Z","caller":"traceutil/trace.go:171","msg":"trace[1658018531] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"129.707808ms","start":"2026-10-04T05:49:36.646225Z","end":"2026-10-04T05:49:36.775933Z","steps":["trace[1658018531] 'process raft request' (duration: 129.525514ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:36.776016Z","caller":"traceutil/trace.go:171","msg":"trace[246688965] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"143.637467ms","start":"2026-10-04T05:49:36.632342Z","end":"2026-10-04T05:49:36.775979Z","steps":["trace[246688965] 'process raft request' (duration: 143.050713ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:36.953513Z","caller":"traceutil/trace.go:171","msg":"trace[360679993] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"149.088198ms","start":"2026-10-04T05:49:36.804407Z","end":"2026-10-04T05:49:36.953495Z","steps":["trace[360679993] 'process raft request' (duration: 148.669938ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:36.95379Z","caller":"traceutil/trace.go:171","msg":"trace[1080308800] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"109.185372ms","start":"2026-10-04T05:49:36.84423Z","end":"2026-10-04T05:49:36.953415Z","steps":["trace[1080308800] 'read index received' (duration: 108.828303ms)","trace[1080308800] 'applied index is now lower than readState.Index' (duration: 356.109µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:36.953855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.519479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-10-04T05:49:36.953942Z","caller":"traceutil/trace.go:171","msg":"trace[360926289] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"109.728154ms","start":"2026-10-04T05:49:36.844193Z","end":"2026-10-04T05:49:36.953921Z","steps":["trace[360926289] 'agreement among raft nodes before linearized reading' (duration: 109.438638ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:36.999077Z","caller":"traceutil/trace.go:171","msg":"trace[1040275585] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"143.223436ms","start":"2026-10-04T05:49:36.855821Z","end":"2026-10-04T05:49:36.999045Z","steps":["trace[1040275585] 'process raft request' (duration: 143.042173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:47.681852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.81809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:49:47.682025Z","caller":"traceutil/trace.go:171","msg":"trace[1349288860] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"811.548952ms","start":"2026-10-04T05:49:46.870439Z","end":"2026-10-04T05:49:47.681988Z","steps":["trace[1349288860] 'process raft request' (duration: 44.494187ms)","trace[1349288860] 'compare' (duration: 766.651886ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:47.682115Z","caller":"traceutil/trace.go:171","msg":"trace[226417542] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"632.927749ms","start":"2026-10-04T05:49:47.049164Z","end":"2026-10-04T05:49:47.682092Z","steps":["trace[226417542] 'read index received' (duration: 33.4µs)","trace[226417542] 'applied index is now lower than readState.Index' (duration: 632.892719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:47.682187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:46.870422Z","time spent":"811.663264ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:49:47.883196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.00899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:49:47.883277Z","caller":"traceutil/trace.go:171","msg":"trace[863904576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"834.154174ms","start":"2026-10-04T05:49:47.049104Z","end":"2026-10-04T05:49:47.883258Z","steps":["trace[863904576] 'agreement among raft nodes before linearized reading' (duration: 633.125544ms)","trace[863904576] 'range keys from in-memory index tree' (duration: 200.895177ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:47.883316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:47.049084Z","time spent":"834.223925ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:49:47.883207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.232745ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T05:49:47.883463Z","caller":"traceutil/trace.go:171","msg":"trace[1998601875] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"201.248924ms","start":"2026-10-04T05:49:47.682196Z","end":"2026-10-04T05:49:47.883445Z","steps":["trace[1998601875] 'read index received' (duration: 61.721µs)","trace[1998601875] 'applied index is now lower than readState.Index' (duration: 201.185313ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:47.883674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.180928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T05:49:47.883721Z","caller":"traceutil/trace.go:171","msg":"trace[791936387] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:805; }","duration":"194.28387ms","start":"2026-10-04T05:49:47.689426Z","end":"2026-10-04T05:49:47.88371Z","steps":["trace[791936387] 'agreement among raft nodes before linearized reading' (duration: 194.201968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:47.883678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.248982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jg7vg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-04T05:49:47.884188Z","caller":"traceutil/trace.go:171","msg":"trace[285440569] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jg7vg; range_end:; response_count:1; response_revision:805; }","duration":"695.755553ms","start":"2026-10-04T05:49:47.188378Z","end":"2026-10-04T05:49:47.884133Z","steps":["trace[285440569] 'agreement among raft nodes before linearized reading' (duration: 695.1747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:47.884275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:47.188365Z","time spent":"695.882086ms","remote":"127.0.0.1:44748","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-jg7vg\" "} {"level":"warn","ts":"2026-10-04T05:49:47.884344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.014664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-04T05:49:47.884396Z","caller":"traceutil/trace.go:171","msg":"trace[428205644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"162.070135ms","start":"2026-10-04T05:49:47.722312Z","end":"2026-10-04T05:49:47.884382Z","steps":["trace[428205644] 'agreement among raft nodes before linearized reading' (duration: 161.736937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:59.85868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.895566ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-04T05:49:59.858836Z","caller":"traceutil/trace.go:171","msg":"trace[1651663466] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"275.01947ms","start":"2026-10-04T05:49:59.583794Z","end":"2026-10-04T05:49:59.858813Z","steps":["trace[1651663466] 'read index received' (duration: 877.379µs)","trace[1651663466] 'applied index is now lower than readState.Index' (duration: 274.139881ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:49:59.858931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:59.487358Z","time spent":"371.568052ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-04T05:49:59.85903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.229455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T05:49:59.859195Z","caller":"traceutil/trace.go:171","msg":"trace[1175133223] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:929; }","duration":"275.355337ms","start":"2026-10-04T05:49:59.583749Z","end":"2026-10-04T05:49:59.859104Z","steps":["trace[1175133223] 'agreement among raft nodes before linearized reading' (duration: 275.131072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:00.229062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.465411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:50:00.229195Z","caller":"traceutil/trace.go:171","msg":"trace[903702529] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"258.247648ms","start":"2026-10-04T05:49:59.97093Z","end":"2026-10-04T05:50:00.229178Z","steps":["trace[903702529] 'read index received' (duration: 133.381378ms)","trace[903702529] 'applied index is now lower than readState.Index' (duration: 124.86487ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:00.22928Z","caller":"traceutil/trace.go:171","msg":"trace[1000086731] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"368.819133ms","start":"2026-10-04T05:49:59.860395Z","end":"2026-10-04T05:50:00.229215Z","steps":["trace[1000086731] 'process raft request' (duration: 244.044854ms)","trace[1000086731] 'compare' (duration: 124.327688ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:00.229404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.471963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-04T05:50:00.229427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:49:59.860375Z","time spent":"368.971425ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:50:00.229415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.479436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:50:00.229438Z","caller":"traceutil/trace.go:171","msg":"trace[262647813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"258.552786ms","start":"2026-10-04T05:49:59.970877Z","end":"2026-10-04T05:50:00.229429Z","steps":["trace[262647813] 'agreement among raft nodes before linearized reading' (duration: 258.371002ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:00.229486Z","caller":"traceutil/trace.go:171","msg":"trace[705932003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"181.586348ms","start":"2026-10-04T05:50:00.047882Z","end":"2026-10-04T05:50:00.229469Z","steps":["trace[705932003] 'agreement among raft nodes before linearized reading' (duration: 181.463915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:00.229489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.84228ms","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-10-04T05:50:00.229577Z","caller":"traceutil/trace.go:171","msg":"trace[1911379199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"179.951583ms","start":"2026-10-04T05:50:00.049588Z","end":"2026-10-04T05:50:00.22954Z","steps":["trace[1911379199] 'agreement among raft nodes before linearized reading' (duration: 179.759898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:00.229573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.030402ms","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-10-04T05:50:00.229596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.137731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-04T05:50:00.229638Z","caller":"traceutil/trace.go:171","msg":"trace[1076975192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"179.130273ms","start":"2026-10-04T05:50:00.050491Z","end":"2026-10-04T05:50:00.229621Z","steps":["trace[1076975192] 'agreement among raft nodes before linearized reading' (duration: 178.862038ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:00.229651Z","caller":"traceutil/trace.go:171","msg":"trace[209252595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"178.234904ms","start":"2026-10-04T05:50:00.051403Z","end":"2026-10-04T05:50:00.229638Z","steps":["trace[209252595] 'agreement among raft nodes before linearized reading' (duration: 178.07452ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:00.415684Z","caller":"traceutil/trace.go:171","msg":"trace[1973726983] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:961; }","duration":"127.973479ms","start":"2026-10-04T05:50:00.287691Z","end":"2026-10-04T05:50:00.415665Z","steps":["trace[1973726983] 'read index received' (duration: 42.521574ms)","trace[1973726983] 'applied index is now lower than readState.Index' (duration: 85.451175ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:00.41581Z","caller":"traceutil/trace.go:171","msg":"trace[216302590] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"181.249841ms","start":"2026-10-04T05:50:00.234533Z","end":"2026-10-04T05:50:00.415783Z","steps":["trace[216302590] 'process raft request' (duration: 95.725104ms)","trace[216302590] 'compare' (duration: 85.22084ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:00.416031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.320757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T05:50:00.416071Z","caller":"traceutil/trace.go:171","msg":"trace[1855777232] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:934; }","duration":"128.397679ms","start":"2026-10-04T05:50:00.287658Z","end":"2026-10-04T05:50:00.416055Z","steps":["trace[1855777232] 'agreement among raft nodes before linearized reading' (duration: 128.102262ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:00.41613Z","caller":"traceutil/trace.go:171","msg":"trace[1522829333] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"178.441358ms","start":"2026-10-04T05:50:00.237673Z","end":"2026-10-04T05:50:00.416114Z","steps":["trace[1522829333] 'process raft request' (duration: 177.959607ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:00.416141Z","caller":"traceutil/trace.go:171","msg":"trace[1988161070] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"180.886162ms","start":"2026-10-04T05:50:00.235227Z","end":"2026-10-04T05:50:00.416113Z","steps":["trace[1988161070] 'process raft request' (duration: 180.35398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:30.607799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062928094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-04T05:50:30.608069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158865264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T05:50:30.608131Z","caller":"traceutil/trace.go:171","msg":"trace[1276108760] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1092; }","duration":"1.063316334s","start":"2026-10-04T05:50:29.544797Z","end":"2026-10-04T05:50:30.608114Z","steps":["trace[1276108760] 'count revisions from in-memory index tree' (duration: 1.062860553s)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:30.608183Z","caller":"traceutil/trace.go:171","msg":"trace[1495895371] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:1092; }","duration":"1.158998037s","start":"2026-10-04T05:50:29.449137Z","end":"2026-10-04T05:50:30.608135Z","steps":["trace[1495895371] 'range keys from in-memory index tree' (duration: 1.158683929s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:30.608204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:29.544779Z","time spent":"1.063407885s","remote":"127.0.0.1:44950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:50:30.608194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.23647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-10-04T05:50:30.608256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:29.449124Z","time spent":"1.15911986s","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"info","ts":"2026-10-04T05:50:30.60829Z","caller":"traceutil/trace.go:171","msg":"trace[2099463049] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"681.697022ms","start":"2026-10-04T05:50:29.926574Z","end":"2026-10-04T05:50:30.608271Z","steps":["trace[2099463049] 'read index received' (duration: 60.314139ms)","trace[2099463049] 'applied index is now lower than readState.Index' (duration: 621.381453ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:30.608326Z","caller":"traceutil/trace.go:171","msg":"trace[1802064350] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"979.203576ms","start":"2026-10-04T05:50:29.62911Z","end":"2026-10-04T05:50:30.608314Z","steps":["trace[1802064350] 'process raft request' (duration: 357.747271ms)","trace[1802064350] 'compare' (duration: 620.892322ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:30.608392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:29.62909Z","time spent":"979.257058ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:50:30.608427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.843045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-04T05:50:30.608458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.233734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:50:30.608479Z","caller":"traceutil/trace.go:171","msg":"trace[116829722] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1093; }","duration":"681.949207ms","start":"2026-10-04T05:50:29.926516Z","end":"2026-10-04T05:50:30.608465Z","steps":["trace[116829722] 'agreement among raft nodes before linearized reading' (duration: 681.835815ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:30.608505Z","caller":"traceutil/trace.go:171","msg":"trace[1229386535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"559.316406ms","start":"2026-10-04T05:50:30.049173Z","end":"2026-10-04T05:50:30.608489Z","steps":["trace[1229386535] 'agreement among raft nodes before linearized reading' (duration: 559.226414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:30.608513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:29.926493Z","time spent":"682.011218ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:50:30.608535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:30.049131Z","time spent":"559.396388ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:50:46.249337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.177076ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T05:50:46.249428Z","caller":"traceutil/trace.go:171","msg":"trace[1346977050] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1193; }","duration":"551.923791ms","start":"2026-10-04T05:50:45.697488Z","end":"2026-10-04T05:50:46.249412Z","steps":["trace[1346977050] 'read index received' (duration: 163.611482ms)","trace[1346977050] 'applied index is now lower than readState.Index' (duration: 388.311079ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:46.249706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.194117ms","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":"warn","ts":"2026-10-04T05:50:46.249736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.751407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T05:50:46.249789Z","caller":"traceutil/trace.go:171","msg":"trace[326749413] 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:1153; }","duration":"552.32251ms","start":"2026-10-04T05:50:45.697444Z","end":"2026-10-04T05:50:46.249766Z","steps":["trace[326749413] 'agreement among raft nodes before linearized reading' (duration: 552.186957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:46.249779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.454836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:50:46.249836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:45.697427Z","time spent":"552.400171ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-10-04T05:50:46.249849Z","caller":"traceutil/trace.go:171","msg":"trace[221911355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"199.557119ms","start":"2026-10-04T05:50:46.050273Z","end":"2026-10-04T05:50:46.24983Z","steps":["trace[221911355] 'agreement among raft nodes before linearized reading' (duration: 199.435046ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:46.249919Z","caller":"traceutil/trace.go:171","msg":"trace[2033820951] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1153; }","duration":"278.87663ms","start":"2026-10-04T05:50:45.970917Z","end":"2026-10-04T05:50:46.249794Z","steps":["trace[2033820951] 'agreement among raft nodes before linearized reading' (duration: 278.750107ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:47.817526Z","caller":"traceutil/trace.go:171","msg":"trace[2084523729] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"922.325043ms","start":"2026-10-04T05:50:46.895142Z","end":"2026-10-04T05:50:47.817467Z","steps":["trace[2084523729] 'process raft request' (duration: 880.366792ms)","trace[2084523729] 'compare' (duration: 41.727726ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:47.81766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:46.895124Z","time spent":"922.465867ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:50:47.860577Z","caller":"traceutil/trace.go:171","msg":"trace[348236085] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1195; }","duration":"488.796981ms","start":"2026-10-04T05:50:47.371743Z","end":"2026-10-04T05:50:47.86054Z","steps":["trace[348236085] 'read index received' (duration: 403.781224ms)","trace[348236085] 'applied index is now lower than readState.Index' (duration: 85.014527ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:47.86086Z","caller":"traceutil/trace.go:171","msg":"trace[1562887205] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"965.633235ms","start":"2026-10-04T05:50:46.895213Z","end":"2026-10-04T05:50:47.860846Z","steps":["trace[1562887205] 'process raft request' (duration: 965.099632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:47.860953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:46.895195Z","time spent":"965.692026ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:50:47.861113Z","caller":"traceutil/trace.go:171","msg":"trace[1957650150] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"963.505627ms","start":"2026-10-04T05:50:46.897594Z","end":"2026-10-04T05:50:47.8611Z","steps":["trace[1957650150] 'process raft request' (duration: 962.854532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:47.861196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:46.89758Z","time spent":"963.556918ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:50:47.861364Z","caller":"traceutil/trace.go:171","msg":"trace[765400798] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"963.371995ms","start":"2026-10-04T05:50:46.897979Z","end":"2026-10-04T05:50:47.861351Z","steps":["trace[765400798] 'process raft request' (duration: 962.519596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:47.861429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:46.897969Z","time spent":"963.420405ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:50:47.861679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.936745ms","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-10-04T05:50:47.86172Z","caller":"traceutil/trace.go:171","msg":"trace[1511043797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1158; }","duration":"490.012187ms","start":"2026-10-04T05:50:47.371694Z","end":"2026-10-04T05:50:47.861706Z","steps":["trace[1511043797] 'agreement among raft nodes before linearized reading' (duration: 489.881855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:47.861759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:47.371674Z","time spent":"490.076448ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-04T05:50:47.861948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.269386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:50:47.861985Z","caller":"traceutil/trace.go:171","msg":"trace[1836429842] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1158; }","duration":"109.344678ms","start":"2026-10-04T05:50:47.752627Z","end":"2026-10-04T05:50:47.861972Z","steps":["trace[1836429842] 'agreement among raft nodes before linearized reading' (duration: 109.280447ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:49.207981Z","caller":"traceutil/trace.go:171","msg":"trace[478229656] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"160.066193ms","start":"2026-10-04T05:50:49.047865Z","end":"2026-10-04T05:50:49.207931Z","steps":["trace[478229656] 'read index received' (duration: 159.988971ms)","trace[478229656] 'applied index is now lower than readState.Index' (duration: 76.142µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:49.2081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.218496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:50:49.208134Z","caller":"traceutil/trace.go:171","msg":"trace[2112547788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"160.304178ms","start":"2026-10-04T05:50:49.047818Z","end":"2026-10-04T05:50:49.208122Z","steps":["trace[2112547788] 'agreement among raft nodes before linearized reading' (duration: 160.229906ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:49.208253Z","caller":"traceutil/trace.go:171","msg":"trace[566020206] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"310.697787ms","start":"2026-10-04T05:50:48.897522Z","end":"2026-10-04T05:50:49.20822Z","steps":["trace[566020206] 'process raft request' (duration: 310.259027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:49.208414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:48.897481Z","time spent":"310.85037ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:50:49.710554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026488175677879,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T05:50:49.754491Z","caller":"traceutil/trace.go:171","msg":"trace[662021372] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"544.688711ms","start":"2026-10-04T05:50:49.209778Z","end":"2026-10-04T05:50:49.754467Z","steps":["trace[662021372] 'read index received' (duration: 544.479376ms)","trace[662021372] 'applied index is now lower than readState.Index' (duration: 208.655µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:49.754543Z","caller":"traceutil/trace.go:171","msg":"trace[1595662265] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"608.543748ms","start":"2026-10-04T05:50:49.145964Z","end":"2026-10-04T05:50:49.754508Z","steps":["trace[1595662265] 'process raft request' (duration: 608.358894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:49.75466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.856004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:50:49.754714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.221243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T05:50:49.754735Z","caller":"traceutil/trace.go:171","msg":"trace[1785578797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"544.980277ms","start":"2026-10-04T05:50:49.209734Z","end":"2026-10-04T05:50:49.754715Z","steps":["trace[1785578797] 'agreement among raft nodes before linearized reading' (duration: 544.851134ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:49.754746Z","caller":"traceutil/trace.go:171","msg":"trace[1325604232] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:1161; }","duration":"304.274324ms","start":"2026-10-04T05:50:49.450462Z","end":"2026-10-04T05:50:49.754736Z","steps":["trace[1325604232] 'agreement among raft nodes before linearized reading' (duration: 304.12473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:49.754744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:49.145936Z","time spent":"608.686981ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:50:49.754789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:49.450419Z","time spent":"304.362026ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-10-04T05:50:49.754788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:49.209714Z","time spent":"545.065219ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T05:50:49.90544Z","caller":"traceutil/trace.go:171","msg":"trace[357766406] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"105.659635ms","start":"2026-10-04T05:50:49.799755Z","end":"2026-10-04T05:50:49.905415Z","steps":["trace[357766406] 'process raft request' (duration: 56.065464ms)","trace[357766406] 'compare' (duration: 49.417107ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:50.094228Z","caller":"traceutil/trace.go:171","msg":"trace[1299268720] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"183.968214ms","start":"2026-10-04T05:50:49.910171Z","end":"2026-10-04T05:50:50.094139Z","steps":["trace[1299268720] 'process raft request' (duration: 108.966049ms)","trace[1299268720] 'compare' (duration: 74.821341ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:50.094431Z","caller":"traceutil/trace.go:171","msg":"trace[14198004] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"184.192579ms","start":"2026-10-04T05:50:49.910218Z","end":"2026-10-04T05:50:50.09441Z","steps":["trace[14198004] 'process raft request' (duration: 183.872872ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:50.094659Z","caller":"traceutil/trace.go:171","msg":"trace[1506973045] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"184.27893ms","start":"2026-10-04T05:50:49.91036Z","end":"2026-10-04T05:50:50.094639Z","steps":["trace[1506973045] 'process raft request' (duration: 183.967813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:50.094768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.971065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-04T05:50:50.094804Z","caller":"traceutil/trace.go:171","msg":"trace[1007623618] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1167; }","duration":"121.050406ms","start":"2026-10-04T05:50:49.973748Z","end":"2026-10-04T05:50:50.094798Z","steps":["trace[1007623618] 'agreement among raft nodes before linearized reading' (duration: 120.952164ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:50.094663Z","caller":"traceutil/trace.go:171","msg":"trace[1448864259] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1205; }","duration":"120.864733ms","start":"2026-10-04T05:50:49.973779Z","end":"2026-10-04T05:50:50.094644Z","steps":["trace[1448864259] 'read index received' (duration: 45.368217ms)","trace[1448864259] 'applied index is now lower than readState.Index' (duration: 75.495646ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:50.094936Z","caller":"traceutil/trace.go:171","msg":"trace[1872444266] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"184.523736ms","start":"2026-10-04T05:50:49.910387Z","end":"2026-10-04T05:50:50.094911Z","steps":["trace[1872444266] 'process raft request' (duration: 184.170838ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:50.552668Z","caller":"traceutil/trace.go:171","msg":"trace[1171981520] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"156.059443ms","start":"2026-10-04T05:50:50.396581Z","end":"2026-10-04T05:50:50.55264Z","steps":["trace[1171981520] 'process raft request' (duration: 155.90059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:51.278306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.810273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:50:51.278727Z","caller":"traceutil/trace.go:171","msg":"trace[632896089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"230.953427ms","start":"2026-10-04T05:50:51.047438Z","end":"2026-10-04T05:50:51.278391Z","steps":["trace[632896089] 'range keys from in-memory index tree' (duration: 230.735442ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:51.337396Z","caller":"traceutil/trace.go:171","msg":"trace[1551679826] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"124.867622ms","start":"2026-10-04T05:50:51.212504Z","end":"2026-10-04T05:50:51.337371Z","steps":["trace[1551679826] 'process raft request' (duration: 124.466272ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:02.076125Z","caller":"traceutil/trace.go:171","msg":"trace[995509267] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"217.980188ms","start":"2026-10-04T05:51:01.858097Z","end":"2026-10-04T05:51:02.076077Z","steps":["trace[995509267] 'process raft request' (duration: 217.715713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:02.695929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026488175678065,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T05:51:02.848764Z","caller":"traceutil/trace.go:171","msg":"trace[1540329890] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"653.339451ms","start":"2026-10-04T05:51:02.195376Z","end":"2026-10-04T05:51:02.848716Z","steps":["trace[1540329890] 'read index received' (duration: 650.642661ms)","trace[1540329890] 'applied index is now lower than readState.Index' (duration: 2.69548ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:51:02.849042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.923087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-10-04T05:51:02.849042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.535947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-04T05:51:02.849091Z","caller":"traceutil/trace.go:171","msg":"trace[842040303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"641.008929ms","start":"2026-10-04T05:51:02.20807Z","end":"2026-10-04T05:51:02.849079Z","steps":["trace[842040303] 'agreement among raft nodes before linearized reading' (duration: 640.845824ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:02.849107Z","caller":"traceutil/trace.go:171","msg":"trace[1463842633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"648.652599ms","start":"2026-10-04T05:51:02.200445Z","end":"2026-10-04T05:51:02.849098Z","steps":["trace[1463842633] 'agreement among raft nodes before linearized reading' (duration: 648.436784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:02.849123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:02.208055Z","time spent":"641.06ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T05:51:02.849134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:02.200424Z","time spent":"648.7029ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T05:51:02.849062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.135742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-04T05:51:02.849289Z","caller":"traceutil/trace.go:171","msg":"trace[966323344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"641.396857ms","start":"2026-10-04T05:51:02.207873Z","end":"2026-10-04T05:51:02.84927Z","steps":["trace[966323344] 'agreement among raft nodes before linearized reading' (duration: 641.064169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:02.849329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.056281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-04T05:51:02.849382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.004307ms","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-10-04T05:51:02.849431Z","caller":"traceutil/trace.go:171","msg":"trace[1097318857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"654.081618ms","start":"2026-10-04T05:51:02.195337Z","end":"2026-10-04T05:51:02.849419Z","steps":["trace[1097318857] 'agreement among raft nodes before linearized reading' (duration: 653.531545ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:02.84946Z","caller":"traceutil/trace.go:171","msg":"trace[339624870] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1259; }","duration":"273.133833ms","start":"2026-10-04T05:51:02.57623Z","end":"2026-10-04T05:51:02.849363Z","steps":["trace[339624870] 'agreement among raft nodes before linearized reading' (duration: 273.038521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:02.849464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:02.195321Z","time spent":"654.13595ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-04T05:51:02.849413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:02.207861Z","time spent":"641.536681ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-04T05:51:02.849843Z","caller":"traceutil/trace.go:171","msg":"trace[438997594] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"768.098219ms","start":"2026-10-04T05:51:02.080794Z","end":"2026-10-04T05:51:02.848892Z","steps":["trace[438997594] 'process raft request' (duration: 765.242446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:02.850003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:02.080768Z","time spent":"769.175723ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:51:03.065491Z","caller":"traceutil/trace.go:171","msg":"trace[978179391] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"210.958293ms","start":"2026-10-04T05:51:02.85451Z","end":"2026-10-04T05:51:03.065468Z","steps":["trace[978179391] 'process raft request' (duration: 208.289543ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.065793Z","caller":"traceutil/trace.go:171","msg":"trace[1829067356] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"211.188508ms","start":"2026-10-04T05:51:02.854553Z","end":"2026-10-04T05:51:03.065742Z","steps":["trace[1829067356] 'process raft request' (duration: 210.81851ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.065816Z","caller":"traceutil/trace.go:171","msg":"trace[2060099029] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"210.605494ms","start":"2026-10-04T05:51:02.855176Z","end":"2026-10-04T05:51:03.065782Z","steps":["trace[2060099029] 'process raft request' (duration: 210.270567ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.065879Z","caller":"traceutil/trace.go:171","msg":"trace[1739713785] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"210.741157ms","start":"2026-10-04T05:51:02.855103Z","end":"2026-10-04T05:51:03.065844Z","steps":["trace[1739713785] 'process raft request' (duration: 210.305987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:31.550437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.319773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:51:31.550589Z","caller":"traceutil/trace.go:171","msg":"trace[1840645706] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"320.844612ms","start":"2026-10-04T05:51:31.229712Z","end":"2026-10-04T05:51:31.550557Z","steps":["trace[1840645706] 'process raft request' (duration: 190.311134ms)","trace[1840645706] 'compare' (duration: 130.20124ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:51:31.550644Z","caller":"traceutil/trace.go:171","msg":"trace[586158674] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"236.647723ms","start":"2026-10-04T05:51:31.313979Z","end":"2026-10-04T05:51:31.550627Z","steps":["trace[586158674] 'process raft request' (duration: 236.574301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:31.550738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:31.229701Z","time spent":"320.953165ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:51:31.550745Z","caller":"traceutil/trace.go:171","msg":"trace[1453887048] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1443; }","duration":"240.694093ms","start":"2026-10-04T05:51:31.310022Z","end":"2026-10-04T05:51:31.550716Z","steps":["trace[1453887048] 'read index received' (duration: 110.093483ms)","trace[1453887048] 'applied index is now lower than readState.Index' (duration: 130.59766ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:51:31.550929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.710933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-04T05:51:31.55093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.427243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-04T05:51:31.550963Z","caller":"traceutil/trace.go:171","msg":"trace[1486551932] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1394; }","duration":"117.820645ms","start":"2026-10-04T05:51:31.433131Z","end":"2026-10-04T05:51:31.550952Z","steps":["trace[1486551932] 'agreement among raft nodes before linearized reading' (duration: 117.738583ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:31.550975Z","caller":"traceutil/trace.go:171","msg":"trace[1031126325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1394; }","duration":"320.503264ms","start":"2026-10-04T05:51:31.230458Z","end":"2026-10-04T05:51:31.550961Z","steps":["trace[1031126325] 'agreement among raft nodes before linearized reading' (duration: 320.3291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:31.551033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:51:31.230444Z","time spent":"320.556995ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-04T05:51:41.878312Z","caller":"traceutil/trace.go:171","msg":"trace[1968928550] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"128.155052ms","start":"2026-10-04T05:51:41.75013Z","end":"2026-10-04T05:51:41.878285Z","steps":["trace[1968928550] 'process raft request' (duration: 127.950617ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:55:31.018174Z","caller":"traceutil/trace.go:171","msg":"trace[665688016] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"136.234982ms","start":"2026-10-04T05:55:30.881916Z","end":"2026-10-04T05:55:31.018151Z","steps":["trace[665688016] 'process raft request' (duration: 135.763392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:55:39.799964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.221523ms","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-10-04T05:55:39.800035Z","caller":"traceutil/trace.go:171","msg":"trace[737021488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2554; }","duration":"515.350896ms","start":"2026-10-04T05:55:39.284667Z","end":"2026-10-04T05:55:39.800018Z","steps":["trace[737021488] 'range keys from in-memory index tree' (duration: 515.102871ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:55:39.800048Z","caller":"traceutil/trace.go:171","msg":"trace[1659346363] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"454.312033ms","start":"2026-10-04T05:55:39.345714Z","end":"2026-10-04T05:55:39.800026Z","steps":["trace[1659346363] 'process raft request' (duration: 418.453074ms)","trace[1659346363] 'compare' (duration: 35.380149ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:55:39.800076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:55:39.284646Z","time spent":"515.417248ms","remote":"127.0.0.1:44856","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-10-04T05:55:39.799984Z","caller":"traceutil/trace.go:171","msg":"trace[1066029050] linearizableReadLoop","detail":"{readStateIndex:2656; appliedIndex:2655; }","duration":"422.064943ms","start":"2026-10-04T05:55:39.377851Z","end":"2026-10-04T05:55:39.799916Z","steps":["trace[1066029050] 'read index received' (duration: 386.259675ms)","trace[1066029050] 'applied index is now lower than readState.Index' (duration: 35.804218ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:55:39.800154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:55:39.345695Z","time spent":"454.389855ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:55:39.799928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.265145ms","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-10-04T05:55:39.800284Z","caller":"traceutil/trace.go:171","msg":"trace[1963750979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2554; }","duration":"515.676864ms","start":"2026-10-04T05:55:39.284598Z","end":"2026-10-04T05:55:39.800275Z","steps":["trace[1963750979] 'range keys from in-memory index tree' (duration: 515.116432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:55:39.80032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:55:39.284578Z","time spent":"515.732575ms","remote":"127.0.0.1:44856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T05:55:39.800414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.536643ms","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":"info","ts":"2026-10-04T05:55:39.800483Z","caller":"traceutil/trace.go:171","msg":"trace[453288254] 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:2555; }","duration":"422.649687ms","start":"2026-10-04T05:55:39.377815Z","end":"2026-10-04T05:55:39.800465Z","steps":["trace[453288254] 'agreement among raft nodes before linearized reading' (duration: 422.525483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:55:39.800534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:55:39.377804Z","time spent":"422.716358ms","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"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-10-04T05:55:39.800547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.352784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T05:55:39.800646Z","caller":"traceutil/trace.go:171","msg":"trace[1438122889] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:2555; }","duration":"333.507227ms","start":"2026-10-04T05:55:39.467127Z","end":"2026-10-04T05:55:39.800634Z","steps":["trace[1438122889] 'agreement among raft nodes before linearized reading' (duration: 333.24033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:55:39.800722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:55:39.467113Z","time spent":"333.598819ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "}