{"level":"warn","ts":"2026-02-06T00:35:17.677217Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T00:35:17.678203Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-02-06T00:35:17.679291Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T00:35:17.679329Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-06T00:35:17.679376Z","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-02-06T00:35:17.679935Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-06T00:35:17.683329Z","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":2,"max-cpu-available":2,"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.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-02-06T00:35:17.829401Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"144.599254ms"} {"level":"info","ts":"2026-02-06T00:35:18.510514Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-06T00:35:18.510656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:35:18.51068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-06T00:35:18.510691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:35:18.510698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-06T00:35:18.510755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-06T00:35:18.86243Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:35:19.100271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:35:19.147518Z","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-02-06T00:35:19.16726Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:35:19.168627Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:35:19.16876Z","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-02-06T00:35:19.168794Z","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-02-06T00:35:19.168814Z","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-02-06T00:35:19.169003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-06T00:35:19.169081Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-06T00:35:19.17099Z","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-02-06T00:35:19.171125Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-06T00:35:19.171221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-06T00:35:19.172493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:35:19.172445Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:35:19.812914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:35:19.812968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:35:19.813003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-06T00:35:19.813024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:35:19.813036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-06T00:35:19.813053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-06T00:35:19.813067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-06T00:35:19.813926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:35:19.814117Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:35:19.814157Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:35:19.814873Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:35:19.815001Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:35:19.815044Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:35:19.815071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:35:19.81652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-06T00:35:19.817709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T00:35:19.83047Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:35:19.830489Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-06T00:35:21.6853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.518694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-06T00:35:21.685914Z","caller":"traceutil/trace.go:171","msg":"trace[787812568] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"521.287355ms","start":"2026-02-06T00:35:21.164597Z","end":"2026-02-06T00:35:21.685884Z","steps":["trace[787812568] 'process raft request' (duration: 96.558221ms)","trace[787812568] 'compare' (duration: 423.362683ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:21.686237Z","caller":"traceutil/trace.go:171","msg":"trace[508612456] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:5; }","duration":"468.579572ms","start":"2026-02-06T00:35:21.217369Z","end":"2026-02-06T00:35:21.685949Z","steps":["trace[508612456] 'read index received' (duration: 43.855358ms)","trace[508612456] 'applied index is now lower than readState.Index' (duration: 424.722784ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:21.686811Z","caller":"traceutil/trace.go:171","msg":"trace[509421533] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"505.119184ms","start":"2026-02-06T00:35:21.181679Z","end":"2026-02-06T00:35:21.686798Z","steps":["trace[509421533] 'process raft request' (duration: 503.745534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.687427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.164573Z","time spent":"521.413726ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:21.68748Z","caller":"traceutil/trace.go:171","msg":"trace[797528507] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"504.383129ms","start":"2026-02-06T00:35:21.183088Z","end":"2026-02-06T00:35:21.687471Z","steps":["trace[797528507] 'process raft request' (duration: 502.408455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.687334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.181665Z","time spent":"505.482327ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:35:21.688019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.183039Z","time spent":"504.461509ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:35:21.688167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.390113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:21.688208Z","caller":"traceutil/trace.go:171","msg":"trace[917356640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:8; }","duration":"471.435563ms","start":"2026-02-06T00:35:21.216759Z","end":"2026-02-06T00:35:21.688195Z","steps":["trace[917356640] 'agreement among raft nodes before linearized reading' (duration: 471.302271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.688239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.216649Z","time spent":"471.583304ms","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-06T00:35:21.688292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.431502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:21.688371Z","caller":"traceutil/trace.go:171","msg":"trace[1826961792] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:8; }","duration":"523.467222ms","start":"2026-02-06T00:35:21.164842Z","end":"2026-02-06T00:35:21.688309Z","steps":["trace[1826961792] 'agreement among raft nodes before linearized reading' (duration: 523.417251ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:21.688397Z","caller":"traceutil/trace.go:171","msg":"trace[68044020] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"504.611701ms","start":"2026-02-06T00:35:21.183778Z","end":"2026-02-06T00:35:21.68839Z","steps":["trace[68044020] 'process raft request' (duration: 502.045532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.688425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.16484Z","time spent":"523.576143ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-06T00:35:21.688453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.183762Z","time spent":"504.657032ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:21.688572Z","caller":"traceutil/trace.go:171","msg":"trace[411949687] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"504.54215ms","start":"2026-02-06T00:35:21.184016Z","end":"2026-02-06T00:35:21.688559Z","steps":["trace[411949687] 'process raft request' (duration: 501.86993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.688616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.184006Z","time spent":"504.587301ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:35:21.688614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.768694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:21.688697Z","caller":"traceutil/trace.go:171","msg":"trace[1007775531] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:8; }","duration":"523.871905ms","start":"2026-02-06T00:35:21.164815Z","end":"2026-02-06T00:35:21.688687Z","steps":["trace[1007775531] 'agreement among raft nodes before linearized reading' (duration: 523.769014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.688726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.164813Z","time spent":"523.902295ms","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-06T00:35:21.688879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.064686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:21.688927Z","caller":"traceutil/trace.go:171","msg":"trace[709012353] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:0; response_revision:8; }","duration":"524.098276ms","start":"2026-02-06T00:35:21.164797Z","end":"2026-02-06T00:35:21.688895Z","steps":["trace[709012353] 'agreement among raft nodes before linearized reading' (duration: 524.062436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.68895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.164793Z","time spent":"524.151176ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-02-06T00:35:21.689009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.062047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:21.689047Z","caller":"traceutil/trace.go:171","msg":"trace[1124527650] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:8; }","duration":"536.121687ms","start":"2026-02-06T00:35:21.152909Z","end":"2026-02-06T00:35:21.68903Z","steps":["trace[1124527650] 'agreement among raft nodes before linearized reading' (duration: 536.074597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.689073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.152903Z","time spent":"536.164587ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-06T00:35:21.688369Z","caller":"traceutil/trace.go:171","msg":"trace[144634168] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"505.007244ms","start":"2026-02-06T00:35:21.183352Z","end":"2026-02-06T00:35:21.688359Z","steps":["trace[144634168] 'process raft request' (duration: 502.443955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:21.689134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.183341Z","time spent":"505.77163ms","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:35:22.186607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149252222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:35:22.505049Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.243862016s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-06T00:35:22.990988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.467166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-06T00:35:22.991446Z","caller":"traceutil/trace.go:171","msg":"trace[1667123105] linearizableReadLoop","detail":"{readStateIndex:20; appliedIndex:11; }","duration":"1.304861771s","start":"2026-02-06T00:35:21.686571Z","end":"2026-02-06T00:35:22.991433Z","steps":["trace[1667123105] 'read index received' (duration: 818.865621ms)","trace[1667123105] 'applied index is now lower than readState.Index' (duration: 485.99539ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:22.99164Z","caller":"traceutil/trace.go:171","msg":"trace[1790691217] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.754741573s","start":"2026-02-06T00:35:21.236889Z","end":"2026-02-06T00:35:22.99163Z","steps":["trace[1790691217] 'process raft request' (duration: 1.268534071s)","trace[1790691217] 'compare' (duration: 485.290443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:22.99186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.236873Z","time spent":"1.754803293s","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2847,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.992066Z","caller":"traceutil/trace.go:171","msg":"trace[2105559015] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.293096693s","start":"2026-02-06T00:35:21.698947Z","end":"2026-02-06T00:35:22.992044Z","steps":["trace[2105559015] 'process raft request' (duration: 1.292431088s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.99222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.698944Z","time spent":"1.293228244s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.992483Z","caller":"traceutil/trace.go:171","msg":"trace[1364281515] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.293192954s","start":"2026-02-06T00:35:21.699281Z","end":"2026-02-06T00:35:22.992474Z","steps":["trace[1364281515] 'process raft request' (duration: 1.292113476s)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:22.992582Z","caller":"traceutil/trace.go:171","msg":"trace[25039663] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.296906381s","start":"2026-02-06T00:35:21.695591Z","end":"2026-02-06T00:35:22.992497Z","steps":["trace[25039663] 'process raft request' (duration: 1.29547764s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.992769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.695582Z","time spent":"1.297165863s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.992974Z","caller":"traceutil/trace.go:171","msg":"trace[78989912] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.295322639s","start":"2026-02-06T00:35:21.697643Z","end":"2026-02-06T00:35:22.992966Z","steps":["trace[78989912] 'process raft request' (duration: 1.293599046s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.993086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.697635Z","time spent":"1.2954311s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.993252Z","caller":"traceutil/trace.go:171","msg":"trace[1334797432] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"1.295564491s","start":"2026-02-06T00:35:21.697679Z","end":"2026-02-06T00:35:22.993244Z","steps":["trace[1334797432] 'process raft request' (duration: 1.293600016s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.993359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.697676Z","time spent":"1.295664402s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.993569Z","caller":"traceutil/trace.go:171","msg":"trace[710657639] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.295600933s","start":"2026-02-06T00:35:21.697961Z","end":"2026-02-06T00:35:22.993562Z","steps":["trace[710657639] 'process raft request' (duration: 1.293335584s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.993674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.697937Z","time spent":"1.295717623s","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.993809Z","caller":"traceutil/trace.go:171","msg":"trace[1308593203] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.294922188s","start":"2026-02-06T00:35:21.69888Z","end":"2026-02-06T00:35:22.993802Z","steps":["trace[1308593203] 'process raft request' (duration: 1.292458468s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.993909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.698873Z","time spent":"1.295015269s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:35:22.993681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.699272Z","time spent":"1.293237884s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.994124Z","caller":"traceutil/trace.go:171","msg":"trace[167531954] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.29519099s","start":"2026-02-06T00:35:21.698924Z","end":"2026-02-06T00:35:22.994115Z","steps":["trace[167531954] 'process raft request' (duration: 1.292433227s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.994223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.698921Z","time spent":"1.29528165s","remote":"127.0.0.1:35340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.994469Z","caller":"traceutil/trace.go:171","msg":"trace[1740545581] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"755.204832ms","start":"2026-02-06T00:35:22.239255Z","end":"2026-02-06T00:35:22.99446Z","steps":["trace[1740545581] 'process raft request' (duration: 752.155398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.995036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775122557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-06T00:35:22.995125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:22.239236Z","time spent":"755.258023ms","remote":"127.0.0.1:35078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:35:22.995145Z","caller":"traceutil/trace.go:171","msg":"trace[1628612172] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:17; }","duration":"1.775275348s","start":"2026-02-06T00:35:21.219861Z","end":"2026-02-06T00:35:22.995136Z","steps":["trace[1628612172] 'agreement among raft nodes before linearized reading' (duration: 1.775127997s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.996269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.21985Z","time spent":"1.776342226s","remote":"127.0.0.1:34858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697248405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-06T00:35:22.996537Z","caller":"traceutil/trace.go:171","msg":"trace[185813117] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"1.698461735s","start":"2026-02-06T00:35:21.298024Z","end":"2026-02-06T00:35:22.996486Z","steps":["trace[185813117] 'agreement among raft nodes before linearized reading' (duration: 1.697238675s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.996607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.298019Z","time spent":"1.698543396s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70593836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:22.996824Z","caller":"traceutil/trace.go:171","msg":"trace[667320732] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:17; }","duration":"1.707478063s","start":"2026-02-06T00:35:21.289337Z","end":"2026-02-06T00:35:22.996815Z","steps":["trace[667320732] 'agreement among raft nodes before linearized reading' (duration: 1.70596267s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.997376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.289326Z","time spent":"1.707919436s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708422498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-06T00:35:22.998312Z","caller":"traceutil/trace.go:171","msg":"trace[1681546805] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"1.711040209s","start":"2026-02-06T00:35:21.286872Z","end":"2026-02-06T00:35:22.997913Z","steps":["trace[1681546805] 'agreement among raft nodes before linearized reading' (duration: 1.708450728s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.998457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.286866Z","time spent":"1.711477893s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708637029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-06T00:35:22.998737Z","caller":"traceutil/trace.go:171","msg":"trace[1604593627] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"1.712034677s","start":"2026-02-06T00:35:21.286692Z","end":"2026-02-06T00:35:22.998727Z","steps":["trace[1604593627] 'agreement among raft nodes before linearized reading' (duration: 1.708652059s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:22.999529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.28668Z","time spent":"1.712481121s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299993826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:23.000129Z","caller":"traceutil/trace.go:171","msg":"trace[1659200316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"1.304673924s","start":"2026-02-06T00:35:21.695446Z","end":"2026-02-06T00:35:23.00012Z","steps":["trace[1659200316] 'agreement among raft nodes before linearized reading' (duration: 1.300007656s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:23.00126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.695435Z","time spent":"1.305632962s","remote":"127.0.0.1:34794","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-02-06T00:35:22.995487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.778758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:23.002432Z","caller":"traceutil/trace.go:171","msg":"trace[886966279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"704.756195ms","start":"2026-02-06T00:35:22.297665Z","end":"2026-02-06T00:35:23.002422Z","steps":["trace[886966279] 'agreement among raft nodes before linearized reading' (duration: 697.812358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:23.002467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:22.297646Z","time spent":"704.812365ms","remote":"127.0.0.1:34788","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-02-06T00:35:22.99551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.646711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:23.002809Z","caller":"traceutil/trace.go:171","msg":"trace[721075270] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:17; }","duration":"935.982001ms","start":"2026-02-06T00:35:22.066816Z","end":"2026-02-06T00:35:23.002798Z","steps":["trace[721075270] 'agreement among raft nodes before linearized reading' (duration: 928.681772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:23.003095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:22.066796Z","time spent":"936.286983ms","remote":"127.0.0.1:35166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.746402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:23.003923Z","caller":"traceutil/trace.go:171","msg":"trace[230669762] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:17; }","duration":"937.235631ms","start":"2026-02-06T00:35:22.066677Z","end":"2026-02-06T00:35:23.003913Z","steps":["trace[230669762] 'agreement among raft nodes before linearized reading' (duration: 928.841333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:23.003982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:22.066656Z","time spent":"937.315621ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-06T00:35:22.99555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23898206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-06T00:35:23.004133Z","caller":"traceutil/trace.go:171","msg":"trace[1230552207] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"1.247546229s","start":"2026-02-06T00:35:21.756563Z","end":"2026-02-06T00:35:23.004109Z","steps":["trace[1230552207] 'agreement among raft nodes before linearized reading' (duration: 1.2389738s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:23.004617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.756509Z","time spent":"1.247767821s","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-06T00:35:22.995569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288571741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:35:23.005322Z","caller":"traceutil/trace.go:171","msg":"trace[70912749] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:17; }","duration":"1.298349291s","start":"2026-02-06T00:35:21.70696Z","end":"2026-02-06T00:35:23.005309Z","steps":["trace[70912749] 'agreement among raft nodes before linearized reading' (duration: 1.288599511s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:23.005384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:21.70695Z","time spent":"1.298427491s","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-06T00:35:23.477653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.139762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-06T00:35:23.478074Z","caller":"traceutil/trace.go:171","msg":"trace[1117094238] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"139.008352ms","start":"2026-02-06T00:35:23.339045Z","end":"2026-02-06T00:35:23.478053Z","steps":["trace[1117094238] 'process raft request' (duration: 28.259985ms)","trace[1117094238] 'compare' (duration: 110.057701ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:23.478248Z","caller":"traceutil/trace.go:171","msg":"trace[1107714538] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"138.534818ms","start":"2026-02-06T00:35:23.339666Z","end":"2026-02-06T00:35:23.478201Z","steps":["trace[1107714538] 'process raft request' (duration: 138.428996ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:27.676151Z","caller":"traceutil/trace.go:171","msg":"trace[1342858678] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"194.383019ms","start":"2026-02-06T00:35:27.481735Z","end":"2026-02-06T00:35:27.676118Z","steps":["trace[1342858678] 'process raft request' (duration: 194.175997ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:27.676295Z","caller":"traceutil/trace.go:171","msg":"trace[1446315642] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:320; }","duration":"189.74832ms","start":"2026-02-06T00:35:27.486488Z","end":"2026-02-06T00:35:27.676236Z","steps":["trace[1446315642] 'read index received' (duration: 189.73793ms)","trace[1446315642] 'applied index is now lower than readState.Index' (duration: 8.14µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:27.676641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.108333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-06T00:35:27.676679Z","caller":"traceutil/trace.go:171","msg":"trace[816531506] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:312; }","duration":"190.204744ms","start":"2026-02-06T00:35:27.486461Z","end":"2026-02-06T00:35:27.676666Z","steps":["trace[816531506] 'agreement among raft nodes before linearized reading' (duration: 189.955242ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:27.831645Z","caller":"traceutil/trace.go:171","msg":"trace[375115881] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"155.232893ms","start":"2026-02-06T00:35:27.676394Z","end":"2026-02-06T00:35:27.831626Z","steps":["trace[375115881] 'read index received' (duration: 150.063307ms)","trace[375115881] 'applied index is now lower than readState.Index' (duration: 5.168796ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:27.831716Z","caller":"traceutil/trace.go:171","msg":"trace[705922601] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"218.126888ms","start":"2026-02-06T00:35:27.613567Z","end":"2026-02-06T00:35:27.831694Z","steps":["trace[705922601] 'process raft request' (duration: 212.87416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:27.831747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.30445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3446"} {"level":"info","ts":"2026-02-06T00:35:27.831776Z","caller":"traceutil/trace.go:171","msg":"trace[1279724466] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:312; }","duration":"218.398481ms","start":"2026-02-06T00:35:27.613369Z","end":"2026-02-06T00:35:27.831768Z","steps":["trace[1279724466] 'agreement among raft nodes before linearized reading' (duration: 218.32669ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:27.838851Z","caller":"traceutil/trace.go:171","msg":"trace[1481218597] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"157.673988ms","start":"2026-02-06T00:35:27.681166Z","end":"2026-02-06T00:35:27.83884Z","steps":["trace[1481218597] 'process raft request' (duration: 157.450466ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:28.026515Z","caller":"traceutil/trace.go:171","msg":"trace[756738321] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"126.774353ms","start":"2026-02-06T00:35:27.899723Z","end":"2026-02-06T00:35:28.026497Z","steps":["trace[756738321] 'read index received' (duration: 118.288526ms)","trace[756738321] 'applied index is now lower than readState.Index' (duration: 8.484957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:28.026628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.886515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-06T00:35:28.026647Z","caller":"traceutil/trace.go:171","msg":"trace[1138873500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:315; }","duration":"126.947485ms","start":"2026-02-06T00:35:27.899694Z","end":"2026-02-06T00:35:28.026641Z","steps":["trace[1138873500] 'agreement among raft nodes before linearized reading' (duration: 126.869024ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:28.026826Z","caller":"traceutil/trace.go:171","msg":"trace[1195758296] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"174.660915ms","start":"2026-02-06T00:35:27.852142Z","end":"2026-02-06T00:35:28.026803Z","steps":["trace[1195758296] 'process raft request' (duration: 165.800244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:28.26983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.54336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:28.270224Z","caller":"traceutil/trace.go:171","msg":"trace[170486580] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"133.748521ms","start":"2026-02-06T00:35:28.13645Z","end":"2026-02-06T00:35:28.270198Z","steps":["trace[170486580] 'read index received' (duration: 5.721525ms)","trace[170486580] 'applied index is now lower than readState.Index' (duration: 128.025726ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:28.270335Z","caller":"traceutil/trace.go:171","msg":"trace[761512100] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"154.005762ms","start":"2026-02-06T00:35:28.116316Z","end":"2026-02-06T00:35:28.270322Z","steps":["trace[761512100] 'process raft request' (duration: 25.881486ms)","trace[761512100] 'compare' (duration: 127.415308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:28.270432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.972963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:28.271235Z","caller":"traceutil/trace.go:171","msg":"trace[1119336443] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:324; }","duration":"134.764812ms","start":"2026-02-06T00:35:28.136417Z","end":"2026-02-06T00:35:28.271182Z","steps":["trace[1119336443] 'agreement among raft nodes before linearized reading' (duration: 133.949143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:28.618259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.691095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:28.61836Z","caller":"traceutil/trace.go:171","msg":"trace[1265604601] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"231.148325ms","start":"2026-02-06T00:35:28.387193Z","end":"2026-02-06T00:35:28.618341Z","steps":["trace[1265604601] 'process raft request' (duration: 101.304309ms)","trace[1265604601] 'compare' (duration: 129.459892ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:28.79248Z","caller":"traceutil/trace.go:171","msg":"trace[80056215] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"105.093433ms","start":"2026-02-06T00:35:28.687366Z","end":"2026-02-06T00:35:28.792459Z","steps":["trace[80056215] 'process raft request' (duration: 76.029219ms)","trace[80056215] 'compare' (duration: 28.985163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:30.89652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.00046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:30.896604Z","caller":"traceutil/trace.go:171","msg":"trace[1992719541] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"309.364908ms","start":"2026-02-06T00:35:30.587209Z","end":"2026-02-06T00:35:30.896574Z","steps":["trace[1992719541] 'read index received' (duration: 29.217896ms)","trace[1992719541] 'applied index is now lower than readState.Index' (duration: 280.146222ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:30.896912Z","caller":"traceutil/trace.go:171","msg":"trace[1677440545] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"352.830031ms","start":"2026-02-06T00:35:30.544055Z","end":"2026-02-06T00:35:30.896885Z","steps":["trace[1677440545] 'process raft request' (duration: 72.422466ms)","trace[1677440545] 'compare' (duration: 279.674246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:30.897028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:30.544049Z","time spent":"352.896071ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:35:30.897455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.241459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-06T00:35:30.897485Z","caller":"traceutil/trace.go:171","msg":"trace[373089905] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:353; }","duration":"310.274179ms","start":"2026-02-06T00:35:30.587202Z","end":"2026-02-06T00:35:30.897476Z","steps":["trace[373089905] 'agreement among raft nodes before linearized reading' (duration: 309.938055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:30.897736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:30.587142Z","time spent":"310.514002ms","remote":"127.0.0.1:35024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-06T00:35:30.89822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.664778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-06T00:35:30.898248Z","caller":"traceutil/trace.go:171","msg":"trace[474389275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:353; }","duration":"260.934361ms","start":"2026-02-06T00:35:30.637307Z","end":"2026-02-06T00:35:30.898241Z","steps":["trace[474389275] 'agreement among raft nodes before linearized reading' (duration: 260.680258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:30.899276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.602795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-06T00:35:30.899351Z","caller":"traceutil/trace.go:171","msg":"trace[1984824222] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"112.04613ms","start":"2026-02-06T00:35:30.787297Z","end":"2026-02-06T00:35:30.899343Z","steps":["trace[1984824222] 'agreement among raft nodes before linearized reading' (duration: 111.618695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:30.899631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.042828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-06T00:35:30.899658Z","caller":"traceutil/trace.go:171","msg":"trace[692383281] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:353; }","duration":"163.104858ms","start":"2026-02-06T00:35:30.736546Z","end":"2026-02-06T00:35:30.89965Z","steps":["trace[692383281] 'agreement among raft nodes before linearized reading' (duration: 162.950626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:30.899871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.858989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-06T00:35:30.899956Z","caller":"traceutil/trace.go:171","msg":"trace[1428271512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:353; }","duration":"211.99301ms","start":"2026-02-06T00:35:30.687955Z","end":"2026-02-06T00:35:30.899948Z","steps":["trace[1428271512] 'agreement among raft nodes before linearized reading' (duration: 211.876638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:30.900089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.951604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-06T00:35:30.900131Z","caller":"traceutil/trace.go:171","msg":"trace[463357690] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:353; }","duration":"238.011665ms","start":"2026-02-06T00:35:30.662113Z","end":"2026-02-06T00:35:30.900125Z","steps":["trace[463357690] 'agreement among raft nodes before linearized reading' (duration: 237.953154ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.10635Z","caller":"traceutil/trace.go:171","msg":"trace[1330060662] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"117.600482ms","start":"2026-02-06T00:35:30.988723Z","end":"2026-02-06T00:35:31.106324Z","steps":["trace[1330060662] 'read index received' (duration: 53.834742ms)","trace[1330060662] 'applied index is now lower than readState.Index' (duration: 63.7647ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:31.106931Z","caller":"traceutil/trace.go:171","msg":"trace[2057768424] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"152.548009ms","start":"2026-02-06T00:35:30.954366Z","end":"2026-02-06T00:35:31.106914Z","steps":["trace[2057768424] 'process raft request' (duration: 88.263833ms)","trace[2057768424] 'compare' (duration: 63.414204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:31.107278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.537434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-06T00:35:31.111477Z","caller":"traceutil/trace.go:171","msg":"trace[1603220858] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:361; }","duration":"122.732498ms","start":"2026-02-06T00:35:30.988685Z","end":"2026-02-06T00:35:31.111417Z","steps":["trace[1603220858] 'agreement among raft nodes before linearized reading' (duration: 118.552763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:31.297084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.152589ms","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-02-06T00:35:31.297353Z","caller":"traceutil/trace.go:171","msg":"trace[223021910] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"115.94049ms","start":"2026-02-06T00:35:31.181388Z","end":"2026-02-06T00:35:31.297329Z","steps":["trace[223021910] 'process raft request' (duration: 44.50016ms)","trace[223021910] 'compare' (duration: 71.041905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:31.297595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.869244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-06T00:35:31.29767Z","caller":"traceutil/trace.go:171","msg":"trace[34104265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:366; }","duration":"110.990935ms","start":"2026-02-06T00:35:31.186669Z","end":"2026-02-06T00:35:31.29766Z","steps":["trace[34104265] 'agreement among raft nodes before linearized reading' (duration: 110.871574ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.297204Z","caller":"traceutil/trace.go:171","msg":"trace[1519563820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:365; }","duration":"165.319182ms","start":"2026-02-06T00:35:31.131859Z","end":"2026-02-06T00:35:31.297178Z","steps":["trace[1519563820] 'agreement among raft nodes before linearized reading' (duration: 75.812427ms)","trace[1519563820] 'range keys from in-memory index tree' (duration: 89.309233ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:31.332809Z","caller":"traceutil/trace.go:171","msg":"trace[532436357] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"117.255385ms","start":"2026-02-06T00:35:31.215542Z","end":"2026-02-06T00:35:31.332798Z","steps":["trace[532436357] 'process raft request' (duration: 117.232965ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.333044Z","caller":"traceutil/trace.go:171","msg":"trace[125445126] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"122.087748ms","start":"2026-02-06T00:35:31.210952Z","end":"2026-02-06T00:35:31.333039Z","steps":["trace[125445126] 'process raft request' (duration: 121.716543ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.333103Z","caller":"traceutil/trace.go:171","msg":"trace[533058457] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"118.607644ms","start":"2026-02-06T00:35:31.214491Z","end":"2026-02-06T00:35:31.333099Z","steps":["trace[533058457] 'process raft request' (duration: 118.251719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:31.333102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.611034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:31.333182Z","caller":"traceutil/trace.go:171","msg":"trace[1374607166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"118.734296ms","start":"2026-02-06T00:35:31.214431Z","end":"2026-02-06T00:35:31.333165Z","steps":["trace[1374607166] 'agreement among raft nodes before linearized reading' (duration: 118.579453ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.333198Z","caller":"traceutil/trace.go:171","msg":"trace[1704578153] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"117.749534ms","start":"2026-02-06T00:35:31.215441Z","end":"2026-02-06T00:35:31.33319Z","steps":["trace[1704578153] 'process raft request' (duration: 117.321018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:31.690034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.396435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:31.690185Z","caller":"traceutil/trace.go:171","msg":"trace[2103486584] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"237.031646ms","start":"2026-02-06T00:35:31.453138Z","end":"2026-02-06T00:35:31.69017Z","steps":["trace[2103486584] 'process raft request' (duration: 130.426688ms)","trace[2103486584] 'compare' (duration: 106.319254ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:31.69542Z","caller":"traceutil/trace.go:171","msg":"trace[1779945819] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"242.116913ms","start":"2026-02-06T00:35:31.453286Z","end":"2026-02-06T00:35:31.695403Z","steps":["trace[1779945819] 'process raft request' (duration: 241.970791ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.695768Z","caller":"traceutil/trace.go:171","msg":"trace[277892008] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"237.890866ms","start":"2026-02-06T00:35:31.457871Z","end":"2026-02-06T00:35:31.695762Z","steps":["trace[277892008] 'process raft request' (duration: 237.857586ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.696119Z","caller":"traceutil/trace.go:171","msg":"trace[1578214757] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"242.69237ms","start":"2026-02-06T00:35:31.45333Z","end":"2026-02-06T00:35:31.696022Z","steps":["trace[1578214757] 'process raft request' (duration: 242.011511ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:31.696097Z","caller":"traceutil/trace.go:171","msg":"trace[442030799] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"242.70639ms","start":"2026-02-06T00:35:31.453386Z","end":"2026-02-06T00:35:31.696092Z","steps":["trace[442030799] 'process raft request' (duration: 241.97823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:33.859392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149252968,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:35:33.894889Z","caller":"traceutil/trace.go:171","msg":"trace[1750850095] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"587.571228ms","start":"2026-02-06T00:35:33.307294Z","end":"2026-02-06T00:35:33.894866Z","steps":["trace[1750850095] 'process raft request' (duration: 587.335465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:33.895012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:33.307276Z","time spent":"587.665179ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:35:35.96642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644494131s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:35.966695Z","caller":"traceutil/trace.go:171","msg":"trace[30286963] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:414; }","duration":"2.60760071s","start":"2026-02-06T00:35:33.359077Z","end":"2026-02-06T00:35:35.966678Z","steps":["trace[30286963] 'read index received' (duration: 535.630359ms)","trace[30286963] 'applied index is now lower than readState.Index' (duration: 2.071968361s)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:35.967026Z","caller":"traceutil/trace.go:171","msg":"trace[482316672] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"2.559236413s","start":"2026-02-06T00:35:33.407775Z","end":"2026-02-06T00:35:35.967011Z","steps":["trace[482316672] 'process raft request' (duration: 914.03476ms)","trace[482316672] 'compare' (duration: 1.64305223s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:35.968559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:33.407749Z","time spent":"2.560742614s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:35:35.969041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241230569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-06T00:35:35.969229Z","caller":"traceutil/trace.go:171","msg":"trace[175645260] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:406; }","duration":"1.241443912s","start":"2026-02-06T00:35:34.727767Z","end":"2026-02-06T00:35:35.969211Z","steps":["trace[175645260] 'agreement among raft nodes before linearized reading' (duration: 1.241235309s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:35.969269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:34.727751Z","time spent":"1.241505723s","remote":"127.0.0.1:35010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4284,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-06T00:35:35.96856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.216920794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-06T00:35:35.969565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.56359728s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:35.96972Z","caller":"traceutil/trace.go:171","msg":"trace[1192813571] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:406; }","duration":"1.563752782s","start":"2026-02-06T00:35:34.405955Z","end":"2026-02-06T00:35:35.969708Z","steps":["trace[1192813571] 'agreement among raft nodes before linearized reading' (duration: 1.56358254s)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:35.969697Z","caller":"traceutil/trace.go:171","msg":"trace[1816546947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"2.218108703s","start":"2026-02-06T00:35:33.751572Z","end":"2026-02-06T00:35:35.969681Z","steps":["trace[1816546947] 'agreement among raft nodes before linearized reading' (duration: 2.216888414s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:35.970138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.651954182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:35:35.970246Z","caller":"traceutil/trace.go:171","msg":"trace[1988912371] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:406; }","duration":"1.652093424s","start":"2026-02-06T00:35:34.318141Z","end":"2026-02-06T00:35:35.970235Z","steps":["trace[1988912371] 'agreement among raft nodes before linearized reading' (duration: 1.651955582s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:35.97028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:34.318126Z","time spent":"1.652144505s","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-02-06T00:35:35.970473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670343028s","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-02-06T00:35:35.970503Z","caller":"traceutil/trace.go:171","msg":"trace[1593410133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"1.67039817s","start":"2026-02-06T00:35:34.300096Z","end":"2026-02-06T00:35:35.970494Z","steps":["trace[1593410133] 'agreement among raft nodes before linearized reading' (duration: 1.670345569s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:35.970528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:34.300079Z","time spent":"1.67044397s","remote":"127.0.0.1:35088","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-02-06T00:35:35.971789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:33.751552Z","time spent":"2.218427147s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:35:35.968701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.609639939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-06T00:35:35.96898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.250975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:35.974281Z","caller":"traceutil/trace.go:171","msg":"trace[318403161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"844.595175ms","start":"2026-02-06T00:35:35.129676Z","end":"2026-02-06T00:35:35.974271Z","steps":["trace[318403161] 'agreement among raft nodes before linearized reading' (duration: 839.281625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:35.974322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.129656Z","time spent":"844.658146ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T00:35:35.974463Z","caller":"traceutil/trace.go:171","msg":"trace[1745042517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"2.615477177s","start":"2026-02-06T00:35:33.358981Z","end":"2026-02-06T00:35:35.974458Z","steps":["trace[1745042517] 'agreement among raft nodes before linearized reading' (duration: 2.609624189s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:35.974586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:33.358938Z","time spent":"2.61564177s","remote":"127.0.0.1:35088","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-02-06T00:35:36.699762Z","caller":"traceutil/trace.go:171","msg":"trace[1636274924] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:416; }","duration":"569.170446ms","start":"2026-02-06T00:35:36.130576Z","end":"2026-02-06T00:35:36.699746Z","steps":["trace[1636274924] 'read index received' (duration: 480.595936ms)","trace[1636274924] 'applied index is now lower than readState.Index' (duration: 88.57395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:36.699869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.287527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:36.699888Z","caller":"traceutil/trace.go:171","msg":"trace[1828725651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"569.318508ms","start":"2026-02-06T00:35:36.130564Z","end":"2026-02-06T00:35:36.699882Z","steps":["trace[1828725651] 'agreement among raft nodes before linearized reading' (duration: 569.241617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:36.699907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:36.130519Z","time spent":"569.383138ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:35:36.700028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.979979Z","time spent":"720.048238ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T00:35:36.70016Z","caller":"traceutil/trace.go:171","msg":"trace[1710127350] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"719.162194ms","start":"2026-02-06T00:35:35.980991Z","end":"2026-02-06T00:35:36.700153Z","steps":["trace[1710127350] 'process raft request' (duration: 718.575425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:36.700216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.980983Z","time spent":"719.192834ms","remote":"127.0.0.1:35010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:35:36.700346Z","caller":"traceutil/trace.go:171","msg":"trace[136496290] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"718.678917ms","start":"2026-02-06T00:35:35.981661Z","end":"2026-02-06T00:35:36.70034Z","steps":["trace[136496290] 'process raft request' (duration: 717.976646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:36.700379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.981655Z","time spent":"718.705317ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:35:36.700446Z","caller":"traceutil/trace.go:171","msg":"trace[672837579] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"718.695577ms","start":"2026-02-06T00:35:35.981744Z","end":"2026-02-06T00:35:36.700439Z","steps":["trace[672837579] 'process raft request' (duration: 717.921556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:36.700475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.98174Z","time spent":"718.719388ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:35:36.700541Z","caller":"traceutil/trace.go:171","msg":"trace[1190571690] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"717.919057ms","start":"2026-02-06T00:35:35.982617Z","end":"2026-02-06T00:35:36.700536Z","steps":["trace[1190571690] 'process raft request' (duration: 717.066923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:36.700571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.982599Z","time spent":"717.955027ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:35:36.700634Z","caller":"traceutil/trace.go:171","msg":"trace[462484920] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"717.437219ms","start":"2026-02-06T00:35:35.983192Z","end":"2026-02-06T00:35:36.700629Z","steps":["trace[462484920] 'process raft request' (duration: 716.509184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:36.700661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:35.983185Z","time spent":"717.46127ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:35:55.231217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.01439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:55.23128Z","caller":"traceutil/trace.go:171","msg":"trace[1135887897] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"428.599118ms","start":"2026-02-06T00:35:54.802672Z","end":"2026-02-06T00:35:55.231271Z","steps":["trace[1135887897] 'read index received' (duration: 82.769465ms)","trace[1135887897] 'applied index is now lower than readState.Index' (duration: 345.829073ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:55.231393Z","caller":"traceutil/trace.go:171","msg":"trace[666454459] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"434.661846ms","start":"2026-02-06T00:35:54.796726Z","end":"2026-02-06T00:35:55.231388Z","steps":["trace[666454459] 'process raft request' (duration: 177.426881ms)","trace[666454459] 'compare' (duration: 256.796165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:55.231425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:54.796718Z","time spent":"434.689717ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:35:55.231557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.882635ms","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-02-06T00:35:55.231572Z","caller":"traceutil/trace.go:171","msg":"trace[1215646785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"428.910086ms","start":"2026-02-06T00:35:54.802657Z","end":"2026-02-06T00:35:55.231567Z","steps":["trace[1215646785] 'agreement among raft nodes before linearized reading' (duration: 428.852613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:55.231586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:54.80265Z","time spent":"428.933496ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:35:55.231657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.952036ms","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-02-06T00:35:55.231668Z","caller":"traceutil/trace.go:171","msg":"trace[1964471106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"428.977317ms","start":"2026-02-06T00:35:54.802688Z","end":"2026-02-06T00:35:55.231665Z","steps":["trace[1964471106] 'agreement among raft nodes before linearized reading' (duration: 428.953786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:55.231682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:54.802685Z","time spent":"428.994087ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:35:55.231769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.447478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:55.231782Z","caller":"traceutil/trace.go:171","msg":"trace[998250418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"101.492109ms","start":"2026-02-06T00:35:55.130285Z","end":"2026-02-06T00:35:55.231777Z","steps":["trace[998250418] 'agreement among raft nodes before linearized reading' (duration: 101.472449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:55.712277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.045063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:55.712718Z","caller":"traceutil/trace.go:171","msg":"trace[2041352881] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"473.602496ms","start":"2026-02-06T00:35:55.239078Z","end":"2026-02-06T00:35:55.712681Z","steps":["trace[2041352881] 'process raft request' (duration: 182.086722ms)","trace[2041352881] 'compare' (duration: 290.753907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:55.712786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:55.239061Z","time spent":"473.689878ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:35:55.712898Z","caller":"traceutil/trace.go:171","msg":"trace[892295107] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"471.448495ms","start":"2026-02-06T00:35:55.241441Z","end":"2026-02-06T00:35:55.712889Z","steps":["trace[892295107] 'process raft request' (duration: 470.916583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:55.712946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:55.241428Z","time spent":"471.490397ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:35:55.96864Z","caller":"traceutil/trace.go:171","msg":"trace[567449486] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"247.991113ms","start":"2026-02-06T00:35:55.720627Z","end":"2026-02-06T00:35:55.968618Z","steps":["trace[567449486] 'process raft request' (duration: 219.105622ms)","trace[567449486] 'compare' (duration: 28.805959ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:56.386344Z","caller":"traceutil/trace.go:171","msg":"trace[126722537] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"152.823685ms","start":"2026-02-06T00:35:56.233503Z","end":"2026-02-06T00:35:56.386327Z","steps":["trace[126722537] 'process raft request' (duration: 152.729073ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:35:57.41054Z","caller":"traceutil/trace.go:171","msg":"trace[1261919124] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"518.944898ms","start":"2026-02-06T00:35:56.891573Z","end":"2026-02-06T00:35:57.410518Z","steps":["trace[1261919124] 'process raft request' (duration: 518.785074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:57.410711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:56.891558Z","time spent":"519.052851ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:35:58.339419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.006072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:35:58.339515Z","caller":"traceutil/trace.go:171","msg":"trace[1539285358] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"1.211577834s","start":"2026-02-06T00:35:57.127918Z","end":"2026-02-06T00:35:58.339496Z","steps":["trace[1539285358] 'read index received' (duration: 283.239559ms)","trace[1539285358] 'applied index is now lower than readState.Index' (duration: 928.336855ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:35:58.339727Z","caller":"traceutil/trace.go:171","msg":"trace[1709363067] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.324060225s","start":"2026-02-06T00:35:57.015653Z","end":"2026-02-06T00:35:58.339713Z","steps":["trace[1709363067] 'process raft request' (duration: 409.691394ms)","trace[1709363067] 'compare' (duration: 913.47972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:35:58.33979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:57.01563Z","time spent":"1.324126967s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:35:58.340759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102495093s","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-02-06T00:35:58.340805Z","caller":"traceutil/trace.go:171","msg":"trace[168569853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"1.102539184s","start":"2026-02-06T00:35:57.23825Z","end":"2026-02-06T00:35:58.340789Z","steps":["trace[168569853] 'agreement among raft nodes before linearized reading' (duration: 1.102456472s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:58.340845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:57.238195Z","time spent":"1.102641267s","remote":"127.0.0.1:35088","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-02-06T00:35:58.341051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213133091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:35:58.341084Z","caller":"traceutil/trace.go:171","msg":"trace[1438675977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"1.213213304s","start":"2026-02-06T00:35:57.12786Z","end":"2026-02-06T00:35:58.341074Z","steps":["trace[1438675977] 'agreement among raft nodes before linearized reading' (duration: 1.212075745s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:58.341111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:57.127839Z","time spent":"1.213266554s","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:35:58.342756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.336056ms","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-02-06T00:35:58.342814Z","caller":"traceutil/trace.go:171","msg":"trace[1705499179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"625.394257ms","start":"2026-02-06T00:35:57.717412Z","end":"2026-02-06T00:35:58.342806Z","steps":["trace[1705499179] 'agreement among raft nodes before linearized reading' (duration: 625.316755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:58.34285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:57.717375Z","time spent":"625.468869ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:35:58.343202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.909376ms","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-02-06T00:35:58.344309Z","caller":"traceutil/trace.go:171","msg":"trace[875466471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"626.027924ms","start":"2026-02-06T00:35:57.718273Z","end":"2026-02-06T00:35:58.344301Z","steps":["trace[875466471] 'agreement among raft nodes before linearized reading' (duration: 622.697002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:35:58.344494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:35:57.718268Z","time spent":"626.193188ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T00:36:01.820562Z","caller":"traceutil/trace.go:171","msg":"trace[1915380997] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"138.997349ms","start":"2026-02-06T00:36:01.681549Z","end":"2026-02-06T00:36:01.820547Z","steps":["trace[1915380997] 'process raft request' (duration: 138.909497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.35548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117697609s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:03.35556Z","caller":"traceutil/trace.go:171","msg":"trace[116341737] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.470113689s","start":"2026-02-06T00:36:01.885432Z","end":"2026-02-06T00:36:03.355545Z","steps":["trace[116341737] 'process raft request' (duration: 352.283476ms)","trace[116341737] 'compare' (duration: 1.117587146s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:03.355601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:01.885415Z","time spent":"1.470166041s","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:03.397509Z","caller":"traceutil/trace.go:171","msg":"trace[1218573898] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"1.266842445s","start":"2026-02-06T00:36:02.130653Z","end":"2026-02-06T00:36:03.397495Z","steps":["trace[1218573898] 'read index received' (duration: 107.14013ms)","trace[1218573898] 'applied index is now lower than readState.Index' (duration: 1.159701514s)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:03.397619Z","caller":"traceutil/trace.go:171","msg":"trace[713621903] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.398607271s","start":"2026-02-06T00:36:01.998991Z","end":"2026-02-06T00:36:03.397598Z","steps":["trace[713621903] 'process raft request' (duration: 1.398387005s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.397667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:01.998976Z","time spent":"1.398655772s","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:36:03.397725Z","caller":"traceutil/trace.go:171","msg":"trace[1810494418] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.199951715s","start":"2026-02-06T00:36:02.197769Z","end":"2026-02-06T00:36:03.397721Z","steps":["trace[1810494418] 'process raft request' (duration: 1.199706218s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.397745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.197737Z","time spent":"1.199996657s","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:36:03.397799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267145133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:03.397812Z","caller":"traceutil/trace.go:171","msg":"trace[196589600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.267181634s","start":"2026-02-06T00:36:02.130627Z","end":"2026-02-06T00:36:03.397809Z","steps":["trace[196589600] 'agreement among raft nodes before linearized reading' (duration: 1.267145543s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.397824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.130614Z","time spent":"1.267207695s","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:36:03.398047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.329346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:03.398064Z","caller":"traceutil/trace.go:171","msg":"trace[701559406] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:591; }","duration":"801.392407ms","start":"2026-02-06T00:36:02.596665Z","end":"2026-02-06T00:36:03.398058Z","steps":["trace[701559406] 'agreement among raft nodes before linearized reading' (duration: 801.363976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.39808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.596632Z","time spent":"801.443948ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:03.398071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.352869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:36:03.398113Z","caller":"traceutil/trace.go:171","msg":"trace[422281818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:591; }","duration":"753.41724ms","start":"2026-02-06T00:36:02.644684Z","end":"2026-02-06T00:36:03.398101Z","steps":["trace[422281818] 'agreement among raft nodes before linearized reading' (duration: 753.315077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.39813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001758879s","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-02-06T00:36:03.398143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.644671Z","time spent":"753.464552ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T00:36:03.398157Z","caller":"traceutil/trace.go:171","msg":"trace[901008312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:591; }","duration":"1.00179064s","start":"2026-02-06T00:36:02.396347Z","end":"2026-02-06T00:36:03.398138Z","steps":["trace[901008312] 'agreement among raft nodes before linearized reading' (duration: 1.00177382s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.39817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.396336Z","time spent":"1.001830391s","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:03.398237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017805199s","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-02-06T00:36:03.398251Z","caller":"traceutil/trace.go:171","msg":"trace[2051990925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.01782778s","start":"2026-02-06T00:36:02.380417Z","end":"2026-02-06T00:36:03.398244Z","steps":["trace[2051990925] 'agreement among raft nodes before linearized reading' (duration: 1.017789379s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.398262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.380399Z","time spent":"1.017861171s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:03.39834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019063101s","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-02-06T00:36:03.398353Z","caller":"traceutil/trace.go:171","msg":"trace[1776575075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"1.019103422s","start":"2026-02-06T00:36:02.379245Z","end":"2026-02-06T00:36:03.398348Z","steps":["trace[1776575075] 'agreement among raft nodes before linearized reading' (duration: 1.019067581s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.398364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.37922Z","time spent":"1.019141403s","remote":"127.0.0.1:35088","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-02-06T00:36:03.39836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.76934ms","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-02-06T00:36:03.39843Z","caller":"traceutil/trace.go:171","msg":"trace[592948512] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:591; }","duration":"201.846332ms","start":"2026-02-06T00:36:03.196541Z","end":"2026-02-06T00:36:03.398387Z","steps":["trace[592948512] 'agreement among raft nodes before linearized reading' (duration: 201.75436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.39847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026010768s","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-02-06T00:36:03.398483Z","caller":"traceutil/trace.go:171","msg":"trace[1385464653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.02606085s","start":"2026-02-06T00:36:02.372418Z","end":"2026-02-06T00:36:03.398479Z","steps":["trace[1385464653] 'agreement among raft nodes before linearized reading' (duration: 1.026022469s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.398494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.3724Z","time spent":"1.0260917s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:03.398569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.475769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-06T00:36:03.398599Z","caller":"traceutil/trace.go:171","msg":"trace[700442769] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:591; }","duration":"400.552141ms","start":"2026-02-06T00:36:02.998039Z","end":"2026-02-06T00:36:03.398591Z","steps":["trace[700442769] 'agreement among raft nodes before linearized reading' (duration: 400.477799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.398623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.998018Z","time spent":"400.598532ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:03.398756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.302308ms","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-02-06T00:36:03.39877Z","caller":"traceutil/trace.go:171","msg":"trace[272700178] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:591; }","duration":"602.331759ms","start":"2026-02-06T00:36:02.796434Z","end":"2026-02-06T00:36:03.398766Z","steps":["trace[272700178] 'agreement among raft nodes before linearized reading' (duration: 602.290388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.398782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:02.796424Z","time spent":"602.355749ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:03.840877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.875016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:03.840953Z","caller":"traceutil/trace.go:171","msg":"trace[603256611] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"417.585715ms","start":"2026-02-06T00:36:03.423357Z","end":"2026-02-06T00:36:03.840942Z","steps":["trace[603256611] 'read index received' (duration: 68.601505ms)","trace[603256611] 'applied index is now lower than readState.Index' (duration: 348.98315ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:03.841069Z","caller":"traceutil/trace.go:171","msg":"trace[895285203] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"424.017472ms","start":"2026-02-06T00:36:03.417044Z","end":"2026-02-06T00:36:03.841061Z","steps":["trace[895285203] 'process raft request' (duration: 74.906489ms)","trace[895285203] 'compare' (duration: 348.495127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:03.841107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.417028Z","time spent":"424.058892ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:03.841236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.878052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:03.841255Z","caller":"traceutil/trace.go:171","msg":"trace[763840911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"417.917152ms","start":"2026-02-06T00:36:03.423331Z","end":"2026-02-06T00:36:03.841248Z","steps":["trace[763840911] 'agreement among raft nodes before linearized reading' (duration: 417.886832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:03.841272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.423316Z","time spent":"417.952694ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:36:04.341887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253344,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:36:04.427034Z","caller":"traceutil/trace.go:171","msg":"trace[1707287429] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"992.343023ms","start":"2026-02-06T00:36:03.434678Z","end":"2026-02-06T00:36:04.427021Z","steps":["trace[1707287429] 'process raft request' (duration: 992.319682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.427245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.434662Z","time spent":"992.414004ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:04.427247Z","caller":"traceutil/trace.go:171","msg":"trace[1046845615] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.003815389s","start":"2026-02-06T00:36:03.423406Z","end":"2026-02-06T00:36:04.427221Z","steps":["trace[1046845615] 'process raft request' (duration: 972.081189ms)","trace[1046845615] 'compare' (duration: 31.269328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:04.427469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.423398Z","time spent":"1.003992634s","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:04.427575Z","caller":"traceutil/trace.go:171","msg":"trace[1895133788] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.003887621s","start":"2026-02-06T00:36:03.423681Z","end":"2026-02-06T00:36:04.427568Z","steps":["trace[1895133788] 'process raft request' (duration: 1.003271025s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.427622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.423674Z","time spent":"1.003929883s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:04.427745Z","caller":"traceutil/trace.go:171","msg":"trace[177377424] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"999.286752ms","start":"2026-02-06T00:36:03.428452Z","end":"2026-02-06T00:36:04.427738Z","steps":["trace[177377424] 'process raft request' (duration: 998.527673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.427777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.428436Z","time spent":"999.325083ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:04.785541Z","caller":"traceutil/trace.go:171","msg":"trace[1446939886] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:611; }","duration":"944.550436ms","start":"2026-02-06T00:36:03.840969Z","end":"2026-02-06T00:36:04.78552Z","steps":["trace[1446939886] 'read index received' (duration: 554.552916ms)","trace[1446939886] 'applied index is now lower than readState.Index' (duration: 389.99659ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:04.785622Z","caller":"traceutil/trace.go:171","msg":"trace[1074426876] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"558.682156ms","start":"2026-02-06T00:36:04.226917Z","end":"2026-02-06T00:36:04.785599Z","steps":["trace[1074426876] 'process raft request' (duration: 558.45125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.785722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.226899Z","time spent":"558.777118ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:36:04.78579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.677713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-06T00:36:04.785826Z","caller":"traceutil/trace.go:171","msg":"trace[903396318] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"989.758025ms","start":"2026-02-06T00:36:03.796057Z","end":"2026-02-06T00:36:04.785815Z","steps":["trace[903396318] 'agreement among raft nodes before linearized reading' (duration: 989.58326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.78589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.796044Z","time spent":"989.836277ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:04.786058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18626667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-06T00:36:04.786085Z","caller":"traceutil/trace.go:171","msg":"trace[1440156083] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"1.186319191s","start":"2026-02-06T00:36:03.599757Z","end":"2026-02-06T00:36:04.786076Z","steps":["trace[1440156083] 'agreement among raft nodes before linearized reading' (duration: 1.186237749s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.786114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.599741Z","time spent":"1.186364312s","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:04.786157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304100398s","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-02-06T00:36:04.786176Z","caller":"traceutil/trace.go:171","msg":"trace[2052249098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.304124709s","start":"2026-02-06T00:36:03.482046Z","end":"2026-02-06T00:36:04.786171Z","steps":["trace[2052249098] 'agreement among raft nodes before linearized reading' (duration: 1.304033126s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.786191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.48201Z","time spent":"1.3041777s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-06T00:36:04.808822Z","caller":"traceutil/trace.go:171","msg":"trace[1973545733] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"327.36379ms","start":"2026-02-06T00:36:04.481444Z","end":"2026-02-06T00:36:04.808808Z","steps":["trace[1973545733] 'process raft request' (duration: 327.204026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.809125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.481432Z","time spent":"327.533684ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:36:04.809365Z","caller":"traceutil/trace.go:171","msg":"trace[2062792360] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"362.662924ms","start":"2026-02-06T00:36:04.446696Z","end":"2026-02-06T00:36:04.809359Z","steps":["trace[2062792360] 'process raft request' (duration: 361.838762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.809443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.446679Z","time spent":"362.739145ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:04.809592Z","caller":"traceutil/trace.go:171","msg":"trace[457906413] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"334.658971ms","start":"2026-02-06T00:36:04.474928Z","end":"2026-02-06T00:36:04.809587Z","steps":["trace[457906413] 'process raft request' (duration: 333.672005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.811329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.474912Z","time spent":"336.397687ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:04.810529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.863019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-06T00:36:04.810559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.818149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-06T00:36:04.810635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.553681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-06T00:36:04.81672Z","caller":"traceutil/trace.go:171","msg":"trace[1895753331] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:600; }","duration":"220.087391ms","start":"2026-02-06T00:36:04.596624Z","end":"2026-02-06T00:36:04.816711Z","steps":["trace[1895753331] 'agreement among raft nodes before linearized reading' (duration: 213.880219ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:04.818889Z","caller":"traceutil/trace.go:171","msg":"trace[1510704689] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:600; }","duration":"499.171537ms","start":"2026-02-06T00:36:04.319711Z","end":"2026-02-06T00:36:04.818882Z","steps":["trace[1510704689] 'agreement among raft nodes before linearized reading' (duration: 490.832229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.819158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.319697Z","time spent":"499.451924ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"info","ts":"2026-02-06T00:36:04.819055Z","caller":"traceutil/trace.go:171","msg":"trace[473762270] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:600; }","duration":"823.002782ms","start":"2026-02-06T00:36:03.996046Z","end":"2026-02-06T00:36:04.819048Z","steps":["trace[473762270] 'agreement among raft nodes before linearized reading' (duration: 814.52141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:04.820292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:03.996005Z","time spent":"824.279065ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:05.245642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.880447ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-06T00:36:05.245828Z","caller":"traceutil/trace.go:171","msg":"trace[2027564959] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"385.636678ms","start":"2026-02-06T00:36:04.860156Z","end":"2026-02-06T00:36:05.245793Z","steps":["trace[2027564959] 'process raft request' (duration: 385.545376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:05.245887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.860139Z","time spent":"385.708499ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:05.24594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.842962Z","time spent":"402.977503ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T00:36:05.330876Z","caller":"traceutil/trace.go:171","msg":"trace[515831181] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:620; }","duration":"334.480725ms","start":"2026-02-06T00:36:04.99638Z","end":"2026-02-06T00:36:05.33086Z","steps":["trace[515831181] 'read index received' (duration: 96.449972ms)","trace[515831181] 'applied index is now lower than readState.Index' (duration: 238.030253ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:05.33128Z","caller":"traceutil/trace.go:171","msg":"trace[1906502803] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"429.713449ms","start":"2026-02-06T00:36:04.901561Z","end":"2026-02-06T00:36:05.331274Z","steps":["trace[1906502803] 'process raft request' (duration: 429.082252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:05.331352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.901544Z","time spent":"429.75542ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:05.331479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.097981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-06T00:36:05.3315Z","caller":"traceutil/trace.go:171","msg":"trace[1849224096] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"335.155513ms","start":"2026-02-06T00:36:04.996339Z","end":"2026-02-06T00:36:05.331494Z","steps":["trace[1849224096] 'agreement among raft nodes before linearized reading' (duration: 335.111242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:05.331518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:04.99632Z","time spent":"335.194244ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:05.645192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.625605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:05.645249Z","caller":"traceutil/trace.go:171","msg":"trace[1121483778] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"314.323123ms","start":"2026-02-06T00:36:05.330917Z","end":"2026-02-06T00:36:05.64524Z","steps":["trace[1121483778] 'read index received' (duration: 68.539424ms)","trace[1121483778] 'applied index is now lower than readState.Index' (duration: 245.783059ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:05.645305Z","caller":"traceutil/trace.go:171","msg":"trace[55686933] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"389.283639ms","start":"2026-02-06T00:36:05.256017Z","end":"2026-02-06T00:36:05.6453Z","steps":["trace[55686933] 'process raft request' (duration: 143.497699ms)","trace[55686933] 'compare' (duration: 245.501992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:05.645336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:05.255993Z","time spent":"389.32886ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:05.645535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.757295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-02-06T00:36:05.645556Z","caller":"traceutil/trace.go:171","msg":"trace[1363035325] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"337.778605ms","start":"2026-02-06T00:36:05.307771Z","end":"2026-02-06T00:36:05.64555Z","steps":["trace[1363035325] 'agreement among raft nodes before linearized reading' (duration: 337.648812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:05.645571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:05.307734Z","time spent":"337.833327ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188510,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-06T00:36:05.645688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.30375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:05.645705Z","caller":"traceutil/trace.go:171","msg":"trace[198690920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"517.34834ms","start":"2026-02-06T00:36:05.128353Z","end":"2026-02-06T00:36:05.645701Z","steps":["trace[198690920] 'agreement among raft nodes before linearized reading' (duration: 517.323989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:05.645719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:05.128337Z","time spent":"517.379461ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:36:05.64585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.960145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:187261"} {"level":"info","ts":"2026-02-06T00:36:05.645868Z","caller":"traceutil/trace.go:171","msg":"trace[1243183409] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"199.977585ms","start":"2026-02-06T00:36:05.445886Z","end":"2026-02-06T00:36:05.645864Z","steps":["trace[1243183409] 'agreement among raft nodes before linearized reading' (duration: 199.890253ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:07.096086Z","caller":"traceutil/trace.go:171","msg":"trace[78409225] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"100.538938ms","start":"2026-02-06T00:36:06.995535Z","end":"2026-02-06T00:36:07.096074Z","steps":["trace[78409225] 'process raft request' (duration: 100.320272ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:08.099453Z","caller":"traceutil/trace.go:171","msg":"trace[22527312] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"100.275421ms","start":"2026-02-06T00:36:07.999163Z","end":"2026-02-06T00:36:08.099439Z","steps":["trace[22527312] 'process raft request' (duration: 100.155858ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:10.288549Z","caller":"traceutil/trace.go:171","msg":"trace[8362840] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"157.933639ms","start":"2026-02-06T00:36:10.130598Z","end":"2026-02-06T00:36:10.288531Z","steps":["trace[8362840] 'read index received' (duration: 157.697552ms)","trace[8362840] 'applied index is now lower than readState.Index' (duration: 235.187µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:10.288978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.364252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:10.289033Z","caller":"traceutil/trace.go:171","msg":"trace[1635105066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"158.447424ms","start":"2026-02-06T00:36:10.130576Z","end":"2026-02-06T00:36:10.289023Z","steps":["trace[1635105066] 'agreement among raft nodes before linearized reading' (duration: 158.078593ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:10.289579Z","caller":"traceutil/trace.go:171","msg":"trace[1455907025] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"184.592594ms","start":"2026-02-06T00:36:10.104971Z","end":"2026-02-06T00:36:10.289563Z","steps":["trace[1455907025] 'process raft request' (duration: 183.39592ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:12.626447Z","caller":"traceutil/trace.go:171","msg":"trace[1366912877] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"498.663584ms","start":"2026-02-06T00:36:12.127769Z","end":"2026-02-06T00:36:12.626433Z","steps":["trace[1366912877] 'read index received' (duration: 498.479569ms)","trace[1366912877] 'applied index is now lower than readState.Index' (duration: 183.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:12.626646Z","caller":"traceutil/trace.go:171","msg":"trace[1165051697] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"889.9703ms","start":"2026-02-06T00:36:11.73667Z","end":"2026-02-06T00:36:12.62664Z","steps":["trace[1165051697] 'process raft request' (duration: 889.62899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:12.626713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.736657Z","time spent":"890.003552ms","remote":"127.0.0.1:35010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:12.626818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.055576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:12.626834Z","caller":"traceutil/trace.go:171","msg":"trace[529793521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"499.102097ms","start":"2026-02-06T00:36:12.127727Z","end":"2026-02-06T00:36:12.626829Z","steps":["trace[529793521] 'agreement among raft nodes before linearized reading' (duration: 499.073067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:12.626847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:12.127708Z","time spent":"499.136228ms","remote":"127.0.0.1:34794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:36:12.626955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.972565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T00:36:12.626968Z","caller":"traceutil/trace.go:171","msg":"trace[321145581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"335.009425ms","start":"2026-02-06T00:36:12.291954Z","end":"2026-02-06T00:36:12.626964Z","steps":["trace[321145581] 'agreement among raft nodes before linearized reading' (duration: 334.947763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:12.62698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:12.291941Z","time spent":"335.035756ms","remote":"127.0.0.1:35088","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-02-06T00:36:12.627272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.266583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:36:12.627287Z","caller":"traceutil/trace.go:171","msg":"trace[1161541533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"146.317224ms","start":"2026-02-06T00:36:12.480966Z","end":"2026-02-06T00:36:12.627283Z","steps":["trace[1161541533] 'agreement among raft nodes before linearized reading' (duration: 146.275163ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:13.086612Z","caller":"traceutil/trace.go:171","msg":"trace[11812629] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"439.639846ms","start":"2026-02-06T00:36:12.646927Z","end":"2026-02-06T00:36:13.086567Z","steps":["trace[11812629] 'process raft request' (duration: 439.551544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:13.086734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:12.64692Z","time spent":"439.749829ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:13.086737Z","caller":"traceutil/trace.go:171","msg":"trace[1283384081] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"233.624572ms","start":"2026-02-06T00:36:12.853094Z","end":"2026-02-06T00:36:13.086718Z","steps":["trace[1283384081] 'read index received' (duration: 233.236851ms)","trace[1283384081] 'applied index is now lower than readState.Index' (duration: 387.051µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:13.086863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.756415ms","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-02-06T00:36:13.086881Z","caller":"traceutil/trace.go:171","msg":"trace[1764968561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"233.824167ms","start":"2026-02-06T00:36:12.85305Z","end":"2026-02-06T00:36:13.086874Z","steps":["trace[1764968561] 'agreement among raft nodes before linearized reading' (duration: 233.725604ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:13.086912Z","caller":"traceutil/trace.go:171","msg":"trace[2119981651] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"445.001299ms","start":"2026-02-06T00:36:12.641898Z","end":"2026-02-06T00:36:13.086899Z","steps":["trace[2119981651] 'process raft request' (duration: 444.434643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:13.086959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:12.641891Z","time spent":"445.04395ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:13.087025Z","caller":"traceutil/trace.go:171","msg":"trace[127106177] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"437.124684ms","start":"2026-02-06T00:36:12.649893Z","end":"2026-02-06T00:36:13.087018Z","steps":["trace[127106177] 'process raft request' (duration: 436.645991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:13.08708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:12.649877Z","time spent":"437.162016ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:15.169154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:36:15.669633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:36:15.876741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.555650796s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:36:15.878464Z","caller":"traceutil/trace.go:171","msg":"trace[856398614] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"1.210252664s","start":"2026-02-06T00:36:14.668188Z","end":"2026-02-06T00:36:15.87844Z","steps":["trace[856398614] 'read index received' (duration: 1.208664577s)","trace[856398614] 'applied index is now lower than readState.Index' (duration: 1.586726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:15.878604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:14.321005Z","time spent":"1.557594244s","remote":"127.0.0.1:34828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T00:36:15.878973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210769138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:36:15.879023Z","caller":"traceutil/trace.go:171","msg":"trace[1621675318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:634; }","duration":"1.210864201s","start":"2026-02-06T00:36:14.668147Z","end":"2026-02-06T00:36:15.879012Z","steps":["trace[1621675318] 'agreement among raft nodes before linearized reading' (duration: 1.210705876s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:15.879052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:14.668129Z","time spent":"1.210915823s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:15.87924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.060011ms","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-02-06T00:36:15.879267Z","caller":"traceutil/trace.go:171","msg":"trace[1537959882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"788.105872ms","start":"2026-02-06T00:36:15.091153Z","end":"2026-02-06T00:36:15.879258Z","steps":["trace[1537959882] 'agreement among raft nodes before linearized reading' (duration: 788.03223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:15.879291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.091144Z","time spent":"788.142344ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:15.879485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.07791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:15.87951Z","caller":"traceutil/trace.go:171","msg":"trace[1027552228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"750.134042ms","start":"2026-02-06T00:36:15.129368Z","end":"2026-02-06T00:36:15.879502Z","steps":["trace[1027552228] 'agreement among raft nodes before linearized reading' (duration: 750.093491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:15.879536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.129351Z","time spent":"750.179704ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:36:15.879701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.490917ms","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-02-06T00:36:15.879733Z","caller":"traceutil/trace.go:171","msg":"trace[1574704974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"772.543468ms","start":"2026-02-06T00:36:15.10718Z","end":"2026-02-06T00:36:15.879723Z","steps":["trace[1574704974] 'agreement among raft nodes before linearized reading' (duration: 772.464155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:15.87977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.107169Z","time spent":"772.589069ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:36:15.879925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.914597ms","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-02-06T00:36:15.879949Z","caller":"traceutil/trace.go:171","msg":"trace[1645963243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"787.962168ms","start":"2026-02-06T00:36:15.091979Z","end":"2026-02-06T00:36:15.879942Z","steps":["trace[1645963243] 'agreement among raft nodes before linearized reading' (duration: 787.893796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:15.879973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.09197Z","time spent":"787.99726ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:15.880089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.896745ms","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-02-06T00:36:15.880113Z","caller":"traceutil/trace.go:171","msg":"trace[1044275474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"789.106092ms","start":"2026-02-06T00:36:15.091Z","end":"2026-02-06T00:36:15.880106Z","steps":["trace[1044275474] 'agreement among raft nodes before linearized reading' (duration: 789.06782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:15.880136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.090983Z","time spent":"789.148362ms","remote":"127.0.0.1:35088","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-02-06T00:36:16.387593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:36:16.887832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:36:17.388399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:36:17.882198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995106923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-06T00:36:17.882289Z","caller":"traceutil/trace.go:171","msg":"trace[272433541] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.995220457s","start":"2026-02-06T00:36:15.887052Z","end":"2026-02-06T00:36:17.882272Z","steps":["trace[272433541] 'agreement among raft nodes before linearized reading' (duration: 1.995112783s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:17.882335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.887047Z","time spent":"1.995278038s","remote":"127.0.0.1:34794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/06 00:36:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-06T00:36:17.888475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811700149253444,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:36:18.020572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.139611493s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:36:18.028073Z","caller":"traceutil/trace.go:171","msg":"trace[643539270] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.1471611s","start":"2026-02-06T00:36:15.880892Z","end":"2026-02-06T00:36:18.028053Z","steps":["trace[643539270] 'process raft request' (duration: 2.13986151s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:18.028197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.880876Z","time spent":"2.147254472s","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:18.03154Z","caller":"traceutil/trace.go:171","msg":"trace[1877155725] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:656; }","duration":"2.144460753s","start":"2026-02-06T00:36:15.887063Z","end":"2026-02-06T00:36:18.031523Z","steps":["trace[1877155725] 'read index received' (duration: 2.13369821s)","trace[1877155725] 'applied index is now lower than readState.Index' (duration: 10.761713ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:18.033868Z","caller":"traceutil/trace.go:171","msg":"trace[1496186810] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"2.146829884s","start":"2026-02-06T00:36:15.887021Z","end":"2026-02-06T00:36:18.033851Z","steps":["trace[1496186810] 'process raft request' (duration: 2.144231096s)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:18.03417Z","caller":"traceutil/trace.go:171","msg":"trace[2021250299] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.144772714s","start":"2026-02-06T00:36:15.889388Z","end":"2026-02-06T00:36:18.03416Z","steps":["trace[2021250299] 'process raft request' (duration: 2.142049632s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:18.034244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.889384Z","time spent":"2.144807385s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:18.034346Z","caller":"traceutil/trace.go:171","msg":"trace[311282127] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.146368011s","start":"2026-02-06T00:36:15.887971Z","end":"2026-02-06T00:36:18.034339Z","steps":["trace[311282127] 'process raft request' (duration: 2.143377531s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:18.03441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.887965Z","time spent":"2.146395503s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:18.034489Z","caller":"traceutil/trace.go:171","msg":"trace[1573686943] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.146458694s","start":"2026-02-06T00:36:15.888023Z","end":"2026-02-06T00:36:18.034482Z","steps":["trace[1573686943] 'process raft request' (duration: 2.143355791s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:18.034524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.888018Z","time spent":"2.146487385s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:18.034603Z","caller":"traceutil/trace.go:171","msg":"trace[2069917687] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.145241928s","start":"2026-02-06T00:36:15.889355Z","end":"2026-02-06T00:36:18.034597Z","steps":["trace[2069917687] 'process raft request' (duration: 2.142055962s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:18.034635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.889349Z","time spent":"2.145267508s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:18.034911Z","caller":"traceutil/trace.go:171","msg":"trace[2086499443] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"149.566778ms","start":"2026-02-06T00:36:17.885337Z","end":"2026-02-06T00:36:18.034904Z","steps":["trace[2086499443] 'process raft request' (duration: 146.155014ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:18.035032Z","caller":"traceutil/trace.go:171","msg":"trace[1302910896] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.213173618s","start":"2026-02-06T00:36:16.821852Z","end":"2026-02-06T00:36:18.035025Z","steps":["trace[1302910896] 'process raft request' (duration: 1.209614811s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:18.035082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:16.821835Z","time spent":"1.21321534s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:18.037408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:15.887012Z","time spent":"2.146892716s","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:18.037856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.9435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:18.038666Z","caller":"traceutil/trace.go:171","msg":"trace[306767535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"148.739933ms","start":"2026-02-06T00:36:17.889886Z","end":"2026-02-06T00:36:18.038625Z","steps":["trace[306767535] 'agreement among raft nodes before linearized reading' (duration: 147.780725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:23.275348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.555898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:23.275418Z","caller":"traceutil/trace.go:171","msg":"trace[436465344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"146.704323ms","start":"2026-02-06T00:36:23.128696Z","end":"2026-02-06T00:36:23.2754Z","steps":["trace[436465344] 'range keys from in-memory index tree' (duration: 146.442075ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:31.073034Z","caller":"traceutil/trace.go:171","msg":"trace[1785162328] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"136.067569ms","start":"2026-02-06T00:36:30.936952Z","end":"2026-02-06T00:36:31.073019Z","steps":["trace[1785162328] 'read index received' (duration: 127.182927ms)","trace[1785162328] 'applied index is now lower than readState.Index' (duration: 8.884142ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:31.07309Z","caller":"traceutil/trace.go:171","msg":"trace[1091782504] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"139.801551ms","start":"2026-02-06T00:36:30.933284Z","end":"2026-02-06T00:36:31.073086Z","steps":["trace[1091782504] 'process raft request' (duration: 130.842817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:31.073298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.986462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-hvqrf\" ","response":"range_response_count:1 size:4990"} {"level":"info","ts":"2026-02-06T00:36:31.073316Z","caller":"traceutil/trace.go:171","msg":"trace[1628265919] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-hvqrf; range_end:; response_count:1; response_revision:756; }","duration":"142.024163ms","start":"2026-02-06T00:36:30.931286Z","end":"2026-02-06T00:36:31.07331Z","steps":["trace[1628265919] 'agreement among raft nodes before linearized reading' (duration: 141.93358ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:31.076544Z","caller":"traceutil/trace.go:171","msg":"trace[958129339] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"123.646164ms","start":"2026-02-06T00:36:30.952891Z","end":"2026-02-06T00:36:31.076537Z","steps":["trace[958129339] 'process raft request' (duration: 123.623643ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:31.076853Z","caller":"traceutil/trace.go:171","msg":"trace[1364076064] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"138.033033ms","start":"2026-02-06T00:36:30.938814Z","end":"2026-02-06T00:36:31.076847Z","steps":["trace[1364076064] 'process raft request' (duration: 137.655601ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:38.452462Z","caller":"traceutil/trace.go:171","msg":"trace[892353046] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"393.43503ms","start":"2026-02-06T00:36:38.05901Z","end":"2026-02-06T00:36:38.452445Z","steps":["trace[892353046] 'process raft request' (duration: 393.330957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.452567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.058991Z","time spent":"393.524484ms","remote":"127.0.0.1:34874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:36:38.45283Z","caller":"traceutil/trace.go:171","msg":"trace[1319757584] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:826; }","duration":"341.559027ms","start":"2026-02-06T00:36:38.11126Z","end":"2026-02-06T00:36:38.452819Z","steps":["trace[1319757584] 'read index received' (duration: 341.555657ms)","trace[1319757584] 'applied index is now lower than readState.Index' (duration: 2.91µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:38.453054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.791884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T00:36:38.453089Z","caller":"traceutil/trace.go:171","msg":"trace[389397782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"341.866127ms","start":"2026-02-06T00:36:38.111215Z","end":"2026-02-06T00:36:38.453082Z","steps":["trace[389397782] 'agreement among raft nodes before linearized reading' (duration: 341.733142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.453114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.111184Z","time spent":"341.924919ms","remote":"127.0.0.1:35088","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":"info","ts":"2026-02-06T00:36:38.537303Z","caller":"traceutil/trace.go:171","msg":"trace[1157190436] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"218.49633ms","start":"2026-02-06T00:36:38.31879Z","end":"2026-02-06T00:36:38.537286Z","steps":["trace[1157190436] 'process raft request' (duration: 214.630968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.538789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.770605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:38.538852Z","caller":"traceutil/trace.go:171","msg":"trace[888585273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"408.885278ms","start":"2026-02-06T00:36:38.129954Z","end":"2026-02-06T00:36:38.53884Z","steps":["trace[888585273] 'agreement among raft nodes before linearized reading' (duration: 408.769384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.53888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.129933Z","time spent":"408.94183ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:36:38.53908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.911588ms","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-02-06T00:36:38.539096Z","caller":"traceutil/trace.go:171","msg":"trace[97220398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"391.93827ms","start":"2026-02-06T00:36:38.147153Z","end":"2026-02-06T00:36:38.539091Z","steps":["trace[97220398] 'agreement among raft nodes before linearized reading' (duration: 391.875027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.539117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.147146Z","time spent":"391.95968ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:38.539123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.794993ms","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-02-06T00:36:38.539157Z","caller":"traceutil/trace.go:171","msg":"trace[1804647815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"386.855025ms","start":"2026-02-06T00:36:38.152293Z","end":"2026-02-06T00:36:38.539148Z","steps":["trace[1804647815] 'agreement among raft nodes before linearized reading' (duration: 386.719261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.539179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.152285Z","time spent":"386.888596ms","remote":"127.0.0.1:35088","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-02-06T00:36:38.539194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.972448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-06T00:36:38.539209Z","caller":"traceutil/trace.go:171","msg":"trace[1065425174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"401.009439ms","start":"2026-02-06T00:36:38.138195Z","end":"2026-02-06T00:36:38.539204Z","steps":["trace[1065425174] 'agreement among raft nodes before linearized reading' (duration: 400.971088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.539231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.138183Z","time spent":"401.04553ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:36:38.539374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.799922ms","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-02-06T00:36:38.539398Z","caller":"traceutil/trace.go:171","msg":"trace[514859453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"376.839604ms","start":"2026-02-06T00:36:38.16255Z","end":"2026-02-06T00:36:38.53939Z","steps":["trace[514859453] 'agreement among raft nodes before linearized reading' (duration: 376.778212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:38.539417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:38.162541Z","time spent":"376.872464ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:36:49.0857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.32716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:49.085854Z","caller":"traceutil/trace.go:171","msg":"trace[772015397] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:862; }","duration":"351.955405ms","start":"2026-02-06T00:36:48.733887Z","end":"2026-02-06T00:36:49.085843Z","steps":["trace[772015397] 'read index received' (duration: 45.432148ms)","trace[772015397] 'applied index is now lower than readState.Index' (duration: 306.522267ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:49.085942Z","caller":"traceutil/trace.go:171","msg":"trace[11333290] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"322.455557ms","start":"2026-02-06T00:36:48.763467Z","end":"2026-02-06T00:36:49.085923Z","steps":["trace[11333290] 'process raft request' (duration: 322.354133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:49.086042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.763463Z","time spent":"322.522189ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:49.086081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.191613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:36:49.086106Z","caller":"traceutil/trace.go:171","msg":"trace[823596506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"352.262245ms","start":"2026-02-06T00:36:48.733835Z","end":"2026-02-06T00:36:49.086098Z","steps":["trace[823596506] 'agreement among raft nodes before linearized reading' (duration: 352.168732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:49.086128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.733823Z","time spent":"352.299997ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T00:36:49.086154Z","caller":"traceutil/trace.go:171","msg":"trace[1171033651] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"370.50777ms","start":"2026-02-06T00:36:48.715637Z","end":"2026-02-06T00:36:49.086144Z","steps":["trace[1171033651] 'process raft request' (duration: 63.674722ms)","trace[1171033651] 'compare' (duration: 306.235707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:49.086183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.715617Z","time spent":"370.551092ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:49.086228Z","caller":"traceutil/trace.go:171","msg":"trace[39414163] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"368.379494ms","start":"2026-02-06T00:36:48.717843Z","end":"2026-02-06T00:36:49.086223Z","steps":["trace[39414163] 'process raft request' (duration: 367.926768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:49.086251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.717835Z","time spent":"368.402484ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:49.086272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.549738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:36:49.086292Z","caller":"traceutil/trace.go:171","msg":"trace[748170697] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:835; }","duration":"337.58384ms","start":"2026-02-06T00:36:48.748702Z","end":"2026-02-06T00:36:49.086286Z","steps":["trace[748170697] 'agreement among raft nodes before linearized reading' (duration: 337.551999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:49.086309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.748693Z","time spent":"337.611221ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-02-06T00:36:49.086315Z","caller":"traceutil/trace.go:171","msg":"trace[2002501899] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"322.920324ms","start":"2026-02-06T00:36:48.763389Z","end":"2026-02-06T00:36:49.08631Z","steps":["trace[2002501899] 'process raft request' (duration: 322.410816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:49.086343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.763375Z","time spent":"322.953074ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:36:49.086495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.580228ms","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-02-06T00:36:49.086514Z","caller":"traceutil/trace.go:171","msg":"trace[1537060340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:835; }","duration":"330.62757ms","start":"2026-02-06T00:36:48.755881Z","end":"2026-02-06T00:36:49.086509Z","steps":["trace[1537060340] 'agreement among raft nodes before linearized reading' (duration: 330.545507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:49.086529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:48.755874Z","time spent":"330.652481ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-06T00:36:49.294485Z","caller":"traceutil/trace.go:171","msg":"trace[1387239240] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"201.113256ms","start":"2026-02-06T00:36:49.093356Z","end":"2026-02-06T00:36:49.294469Z","steps":["trace[1387239240] 'process raft request' (duration: 201.082585ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:49.294654Z","caller":"traceutil/trace.go:171","msg":"trace[1773361168] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"203.289404ms","start":"2026-02-06T00:36:49.091349Z","end":"2026-02-06T00:36:49.294638Z","steps":["trace[1773361168] 'process raft request' (duration: 113.240605ms)","trace[1773361168] 'compare' (duration: 89.730908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:57.38682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.771996ms","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-02-06T00:36:57.386868Z","caller":"traceutil/trace.go:171","msg":"trace[63418651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:855; }","duration":"181.860559ms","start":"2026-02-06T00:36:57.204997Z","end":"2026-02-06T00:36:57.386858Z","steps":["trace[63418651] 'range keys from in-memory index tree' (duration: 181.6111ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:58.95677Z","caller":"traceutil/trace.go:171","msg":"trace[1640887724] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"173.838224ms","start":"2026-02-06T00:36:58.782915Z","end":"2026-02-06T00:36:58.956754Z","steps":["trace[1640887724] 'process raft request' (duration: 173.74115ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:59.191623Z","caller":"traceutil/trace.go:171","msg":"trace[442574269] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"313.658635ms","start":"2026-02-06T00:36:58.877948Z","end":"2026-02-06T00:36:59.191607Z","steps":["trace[442574269] 'process raft request' (duration: 256.193958ms)","trace[442574269] 'compare' (duration: 57.098234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:59.191715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:58.877932Z","time spent":"313.732078ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4898,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:36:59.496353Z","caller":"traceutil/trace.go:171","msg":"trace[307080414] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"103.895393ms","start":"2026-02-06T00:36:59.392447Z","end":"2026-02-06T00:36:59.496342Z","steps":["trace[307080414] 'process raft request' (duration: 103.307621ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:23.39488Z","caller":"traceutil/trace.go:171","msg":"trace[1542411470] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"144.00204ms","start":"2026-02-06T00:37:23.250856Z","end":"2026-02-06T00:37:23.394858Z","steps":["trace[1542411470] 'process raft request' (duration: 143.843454ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:24.014187Z","caller":"traceutil/trace.go:171","msg":"trace[65841447] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"262.966481ms","start":"2026-02-06T00:37:23.7512Z","end":"2026-02-06T00:37:24.014166Z","steps":["trace[65841447] 'read index received' (duration: 256.441729ms)","trace[65841447] 'applied index is now lower than readState.Index' (duration: 6.523802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:24.014504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.286103ms","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-02-06T00:37:24.014586Z","caller":"traceutil/trace.go:171","msg":"trace[1545656105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"263.400168ms","start":"2026-02-06T00:37:23.751175Z","end":"2026-02-06T00:37:24.014575Z","steps":["trace[1545656105] 'agreement among raft nodes before linearized reading' (duration: 263.143278ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:24.015685Z","caller":"traceutil/trace.go:171","msg":"trace[1247631820] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"275.952148ms","start":"2026-02-06T00:37:23.739716Z","end":"2026-02-06T00:37:24.015668Z","steps":["trace[1247631820] 'process raft request' (duration: 267.917587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:24.322424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.217192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:24.322486Z","caller":"traceutil/trace.go:171","msg":"trace[398593768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"191.318796ms","start":"2026-02-06T00:37:24.131152Z","end":"2026-02-06T00:37:24.322471Z","steps":["trace[398593768] 'range keys from in-memory index tree' (duration: 191.122777ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:25.047844Z","caller":"traceutil/trace.go:171","msg":"trace[1626503313] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"123.007726ms","start":"2026-02-06T00:37:24.924787Z","end":"2026-02-06T00:37:25.047795Z","steps":["trace[1626503313] 'process raft request' (duration: 122.826509ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:25.319427Z","caller":"traceutil/trace.go:171","msg":"trace[84364978] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"260.631131ms","start":"2026-02-06T00:37:25.05878Z","end":"2026-02-06T00:37:25.319411Z","steps":["trace[84364978] 'process raft request' (duration: 260.1326ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:29.529361Z","caller":"traceutil/trace.go:171","msg":"trace[953820124] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"113.321611ms","start":"2026-02-06T00:37:29.416009Z","end":"2026-02-06T00:37:29.529331Z","steps":["trace[953820124] 'read index received' (duration: 113.161225ms)","trace[953820124] 'applied index is now lower than readState.Index' (duration: 159.506µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:29.529882Z","caller":"traceutil/trace.go:171","msg":"trace[1104036939] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"124.065286ms","start":"2026-02-06T00:37:29.405714Z","end":"2026-02-06T00:37:29.529779Z","steps":["trace[1104036939] 'process raft request' (duration: 123.325116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:29.530579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.451738ms","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-02-06T00:37:29.530615Z","caller":"traceutil/trace.go:171","msg":"trace[1936749470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"114.620424ms","start":"2026-02-06T00:37:29.415984Z","end":"2026-02-06T00:37:29.530605Z","steps":["trace[1936749470] 'agreement among raft nodes before linearized reading' (duration: 114.26112ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:29.660413Z","caller":"traceutil/trace.go:171","msg":"trace[247404391] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"106.806088ms","start":"2026-02-06T00:37:29.553578Z","end":"2026-02-06T00:37:29.660384Z","steps":["trace[247404391] 'read index received' (duration: 21.572533ms)","trace[247404391] 'applied index is now lower than readState.Index' (duration: 85.232555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:29.660776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.178033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-02-06T00:37:29.660835Z","caller":"traceutil/trace.go:171","msg":"trace[20327371] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:973; }","duration":"107.262796ms","start":"2026-02-06T00:37:29.553564Z","end":"2026-02-06T00:37:29.660827Z","steps":["trace[20327371] 'agreement among raft nodes before linearized reading' (duration: 106.981454ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:29.660427Z","caller":"traceutil/trace.go:171","msg":"trace[537467061] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"107.171642ms","start":"2026-02-06T00:37:29.553219Z","end":"2026-02-06T00:37:29.66039Z","steps":["trace[537467061] 'process raft request' (duration: 21.921976ms)","trace[537467061] 'compare' (duration: 85.108081ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:29.661893Z","caller":"traceutil/trace.go:171","msg":"trace[1070248474] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"105.09918ms","start":"2026-02-06T00:37:29.556779Z","end":"2026-02-06T00:37:29.661878Z","steps":["trace[1070248474] 'process raft request' (duration: 104.417291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.449513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.066968ms","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-02-06T00:37:32.449586Z","caller":"traceutil/trace.go:171","msg":"trace[409165645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"401.185564ms","start":"2026-02-06T00:37:32.048383Z","end":"2026-02-06T00:37:32.449569Z","steps":["trace[409165645] 'range keys from in-memory index tree' (duration: 400.898602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.449624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:32.048364Z","time spent":"401.252317ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:37:32.449922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.268509ms","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-02-06T00:37:32.449953Z","caller":"traceutil/trace.go:171","msg":"trace[1915227996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"359.334282ms","start":"2026-02-06T00:37:32.090609Z","end":"2026-02-06T00:37:32.449943Z","steps":["trace[1915227996] 'range keys from in-memory index tree' (duration: 359.166785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.449978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:32.09059Z","time spent":"359.381584ms","remote":"127.0.0.1:35088","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-02-06T00:37:32.450175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.108297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:32.450209Z","caller":"traceutil/trace.go:171","msg":"trace[1558994200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"320.163429ms","start":"2026-02-06T00:37:32.130037Z","end":"2026-02-06T00:37:32.4502Z","steps":["trace[1558994200] 'range keys from in-memory index tree' (duration: 320.059625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.450232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:32.130026Z","time spent":"320.20126ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T00:37:38.587251Z","caller":"traceutil/trace.go:171","msg":"trace[1149241242] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"100.409367ms","start":"2026-02-06T00:37:38.486819Z","end":"2026-02-06T00:37:38.587229Z","steps":["trace[1149241242] 'process raft request' (duration: 100.078963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:39.0402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.555193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:39.040558Z","caller":"traceutil/trace.go:171","msg":"trace[1527511576] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"550.560698ms","start":"2026-02-06T00:37:38.489959Z","end":"2026-02-06T00:37:39.040519Z","steps":["trace[1527511576] 'process raft request' (duration: 148.565706ms)","trace[1527511576] 'compare' (duration: 401.410167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:39.040774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:38.48994Z","time spent":"550.742625ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:40.073003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.239523ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-06T00:37:40.073101Z","caller":"traceutil/trace.go:171","msg":"trace[537462241] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"464.901853ms","start":"2026-02-06T00:37:39.608181Z","end":"2026-02-06T00:37:40.073083Z","steps":["trace[537462241] 'read index received' (duration: 26.021µs)","trace[537462241] 'applied index is now lower than readState.Index' (duration: 464.874362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:40.073475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.457399ms","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-02-06T00:37:40.073523Z","caller":"traceutil/trace.go:171","msg":"trace[249166844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"340.512141ms","start":"2026-02-06T00:37:39.732998Z","end":"2026-02-06T00:37:40.07351Z","steps":["trace[249166844] 'agreement among raft nodes before linearized reading' (duration: 340.303443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.07356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:39.732923Z","time spent":"340.628767ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:37:40.073936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.748708ms","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-02-06T00:37:40.073977Z","caller":"traceutil/trace.go:171","msg":"trace[2041971144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"465.816841ms","start":"2026-02-06T00:37:39.60815Z","end":"2026-02-06T00:37:40.073967Z","steps":["trace[2041971144] 'agreement among raft nodes before linearized reading' (duration: 465.711177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.074009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:39.608135Z","time spent":"465.866632ms","remote":"127.0.0.1:35088","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-02-06T00:37:40.074407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.397682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:37:40.074451Z","caller":"traceutil/trace.go:171","msg":"trace[938302816] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1010; }","duration":"104.473505ms","start":"2026-02-06T00:37:39.969967Z","end":"2026-02-06T00:37:40.074441Z","steps":["trace[938302816] 'agreement among raft nodes before linearized reading' (duration: 104.373421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.074816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.646897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:37:40.074862Z","caller":"traceutil/trace.go:171","msg":"trace[845445585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"126.725601ms","start":"2026-02-06T00:37:39.948125Z","end":"2026-02-06T00:37:40.074851Z","steps":["trace[845445585] 'agreement among raft nodes before linearized reading' (duration: 126.614477ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:40.595831Z","caller":"traceutil/trace.go:171","msg":"trace[902321793] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"513.729993ms","start":"2026-02-06T00:37:40.082082Z","end":"2026-02-06T00:37:40.595812Z","steps":["trace[902321793] 'process raft request' (duration: 513.585657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.59612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.082066Z","time spent":"513.821658ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:40.732363Z","caller":"traceutil/trace.go:171","msg":"trace[1712751736] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"650.007902ms","start":"2026-02-06T00:37:40.08233Z","end":"2026-02-06T00:37:40.732338Z","steps":["trace[1712751736] 'process raft request' (duration: 634.810746ms)","trace[1712751736] 'compare' (duration: 14.644373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:40.732766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.082323Z","time spent":"650.374558ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:40.733569Z","caller":"traceutil/trace.go:171","msg":"trace[1412028183] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"648.260601ms","start":"2026-02-06T00:37:40.085294Z","end":"2026-02-06T00:37:40.733555Z","steps":["trace[1412028183] 'process raft request' (duration: 646.665745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.733738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.085285Z","time spent":"648.387076ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:40.734326Z","caller":"traceutil/trace.go:171","msg":"trace[827211556] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"602.794885ms","start":"2026-02-06T00:37:40.131518Z","end":"2026-02-06T00:37:40.734313Z","steps":["trace[827211556] 'read index received' (duration: 464.559165ms)","trace[827211556] 'applied index is now lower than readState.Index' (duration: 138.23268ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:40.734505Z","caller":"traceutil/trace.go:171","msg":"trace[1806158980] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"344.61514ms","start":"2026-02-06T00:37:40.389878Z","end":"2026-02-06T00:37:40.734493Z","steps":["trace[1806158980] 'process raft request' (duration: 342.42933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.735202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.389857Z","time spent":"345.310129ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:40.734602Z","caller":"traceutil/trace.go:171","msg":"trace[998304429] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"245.90377ms","start":"2026-02-06T00:37:40.488686Z","end":"2026-02-06T00:37:40.73459Z","steps":["trace[998304429] 'process raft request' (duration: 244.68389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.734666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.14344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:40.73612Z","caller":"traceutil/trace.go:171","msg":"trace[1298607024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"604.641862ms","start":"2026-02-06T00:37:40.131461Z","end":"2026-02-06T00:37:40.736103Z","steps":["trace[1298607024] 'agreement among raft nodes before linearized reading' (duration: 603.169291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.736684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.131439Z","time spent":"605.226566ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:37:40.735015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.206368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:40.737184Z","caller":"traceutil/trace.go:171","msg":"trace[955390456] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1015; }","duration":"439.407508ms","start":"2026-02-06T00:37:40.297756Z","end":"2026-02-06T00:37:40.737163Z","steps":["trace[955390456] 'agreement among raft nodes before linearized reading' (duration: 437.109293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.737295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.29773Z","time spent":"439.546164ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:37:40.735116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.471405ms","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-02-06T00:37:40.737817Z","caller":"traceutil/trace.go:171","msg":"trace[800939944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"146.202148ms","start":"2026-02-06T00:37:40.591599Z","end":"2026-02-06T00:37:40.737802Z","steps":["trace[800939944] 'agreement among raft nodes before linearized reading' (duration: 143.440144ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:52.902641Z","caller":"traceutil/trace.go:171","msg":"trace[1509874178] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"211.571129ms","start":"2026-02-06T00:37:52.691055Z","end":"2026-02-06T00:37:52.902626Z","steps":["trace[1509874178] 'process raft request' (duration: 211.217285ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:52.902461Z","caller":"traceutil/trace.go:171","msg":"trace[1075649499] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"180.150901ms","start":"2026-02-06T00:37:52.72229Z","end":"2026-02-06T00:37:52.902441Z","steps":["trace[1075649499] 'read index received' (duration: 179.89749ms)","trace[1075649499] 'applied index is now lower than readState.Index' (duration: 252.471µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:52.903828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.515178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-02-06T00:37:52.904173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.83493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-02-06T00:37:52.90427Z","caller":"traceutil/trace.go:171","msg":"trace[123768644] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"145.967826ms","start":"2026-02-06T00:37:52.758283Z","end":"2026-02-06T00:37:52.90425Z","steps":["trace[123768644] 'agreement among raft nodes before linearized reading' (duration: 145.668504ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:52.904176Z","caller":"traceutil/trace.go:171","msg":"trace[1546048260] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1050; }","duration":"181.898154ms","start":"2026-02-06T00:37:52.72226Z","end":"2026-02-06T00:37:52.904158Z","steps":["trace[1546048260] 'agreement among raft nodes before linearized reading' (duration: 181.173804ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:53.041553Z","caller":"traceutil/trace.go:171","msg":"trace[123118375] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"125.831793ms","start":"2026-02-06T00:37:52.915698Z","end":"2026-02-06T00:37:53.04153Z","steps":["trace[123118375] 'process raft request' (duration: 98.240954ms)","trace[123118375] 'compare' (duration: 27.439143ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:53.041942Z","caller":"traceutil/trace.go:171","msg":"trace[102810688] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"119.904974ms","start":"2026-02-06T00:37:52.922024Z","end":"2026-02-06T00:37:53.041929Z","steps":["trace[102810688] 'process raft request' (duration: 119.467365ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:53.388411Z","caller":"traceutil/trace.go:171","msg":"trace[567336983] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"228.593736ms","start":"2026-02-06T00:37:53.159796Z","end":"2026-02-06T00:37:53.38839Z","steps":["trace[567336983] 'read index received' (duration: 220.015696ms)","trace[567336983] 'applied index is now lower than readState.Index' (duration: 8.5771ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:53.388648Z","caller":"traceutil/trace.go:171","msg":"trace[1583603718] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"247.994981ms","start":"2026-02-06T00:37:53.140641Z","end":"2026-02-06T00:37:53.388636Z","steps":["trace[1583603718] 'process raft request' (duration: 239.16024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:53.389131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.317736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-02-06T00:37:53.389174Z","caller":"traceutil/trace.go:171","msg":"trace[335869117] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:1054; }","duration":"229.372928ms","start":"2026-02-06T00:37:53.159791Z","end":"2026-02-06T00:37:53.389164Z","steps":["trace[335869117] 'agreement among raft nodes before linearized reading' (duration: 229.284724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:53.389643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.659011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-02-06T00:37:53.389683Z","caller":"traceutil/trace.go:171","msg":"trace[2010122151] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:1054; }","duration":"170.759255ms","start":"2026-02-06T00:37:53.218914Z","end":"2026-02-06T00:37:53.389673Z","steps":["trace[2010122151] 'agreement among raft nodes before linearized reading' (duration: 170.650861ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:55.048187Z","caller":"traceutil/trace.go:171","msg":"trace[854970106] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"123.048341ms","start":"2026-02-06T00:37:54.925121Z","end":"2026-02-06T00:37:55.048169Z","steps":["trace[854970106] 'read index received' (duration: 122.920265ms)","trace[854970106] 'applied index is now lower than readState.Index' (duration: 126.826µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:55.048297Z","caller":"traceutil/trace.go:171","msg":"trace[1333620713] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"132.162064ms","start":"2026-02-06T00:37:54.916125Z","end":"2026-02-06T00:37:55.048287Z","steps":["trace[1333620713] 'process raft request' (duration: 131.925394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:55.048686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.551341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:1 size:363"} {"level":"info","ts":"2026-02-06T00:37:55.048728Z","caller":"traceutil/trace.go:171","msg":"trace[982496701] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:1; response_revision:1056; }","duration":"123.627865ms","start":"2026-02-06T00:37:54.92509Z","end":"2026-02-06T00:37:55.048718Z","steps":["trace[982496701] 'agreement among raft nodes before linearized reading' (duration: 123.53062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:55.049138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.552296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-02-06T00:37:55.049404Z","caller":"traceutil/trace.go:171","msg":"trace[970165693] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:1056; }","duration":"106.715983ms","start":"2026-02-06T00:37:54.942563Z","end":"2026-02-06T00:37:55.049279Z","steps":["trace[970165693] 'agreement among raft nodes before linearized reading' (duration: 106.491284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:55.050418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.224229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:37:55.050695Z","caller":"traceutil/trace.go:171","msg":"trace[2130570570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"100.478981ms","start":"2026-02-06T00:37:54.95018Z","end":"2026-02-06T00:37:55.050659Z","steps":["trace[2130570570] 'agreement among raft nodes before linearized reading' (duration: 100.090713ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:57.601255Z","caller":"traceutil/trace.go:171","msg":"trace[1512154904] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1107; }","duration":"520.584527ms","start":"2026-02-06T00:37:57.08065Z","end":"2026-02-06T00:37:57.601235Z","steps":["trace[1512154904] 'read index received' (duration: 454.577728ms)","trace[1512154904] 'applied index is now lower than readState.Index' (duration: 66.005899ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:57.601365Z","caller":"traceutil/trace.go:171","msg":"trace[1574781938] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"521.316428ms","start":"2026-02-06T00:37:57.080039Z","end":"2026-02-06T00:37:57.601355Z","steps":["trace[1574781938] 'process raft request' (duration: 521.161371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.601461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.080033Z","time spent":"521.36292ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:57.601724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.067747ms","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-02-06T00:37:57.601764Z","caller":"traceutil/trace.go:171","msg":"trace[1780618378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"521.11728ms","start":"2026-02-06T00:37:57.080637Z","end":"2026-02-06T00:37:57.601754Z","steps":["trace[1780618378] 'agreement among raft nodes before linearized reading' (duration: 520.983654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.601794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.080632Z","time spent":"521.154971ms","remote":"127.0.0.1:35088","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-02-06T00:37:57.601952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.473903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:57.601992Z","caller":"traceutil/trace.go:171","msg":"trace[1347937908] range","detail":"{range_begin:/registry/services/endpoints/kube-node-lease/; range_end:/registry/services/endpoints/kube-node-lease0; response_count:0; response_revision:1063; }","duration":"516.520025ms","start":"2026-02-06T00:37:57.085458Z","end":"2026-02-06T00:37:57.601978Z","steps":["trace[1347937908] 'agreement among raft nodes before linearized reading' (duration: 516.470183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.602022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.085453Z","time spent":"516.562476ms","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 "} {"level":"info","ts":"2026-02-06T00:37:57.601735Z","caller":"traceutil/trace.go:171","msg":"trace[1964673723] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"521.396801ms","start":"2026-02-06T00:37:57.079866Z","end":"2026-02-06T00:37:57.601263Z","steps":["trace[1964673723] 'process raft request' (duration: 455.352721ms)","trace[1964673723] 'compare' (duration: 65.878563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:57.602545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.079855Z","time spent":"522.584441ms","remote":"127.0.0.1:35088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:57.60335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.247957ms","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-02-06T00:37:57.603643Z","caller":"traceutil/trace.go:171","msg":"trace[257550673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"159.518818ms","start":"2026-02-06T00:37:57.444052Z","end":"2026-02-06T00:37:57.603571Z","steps":["trace[257550673] 'agreement among raft nodes before linearized reading' (duration: 159.190694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.604702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.735286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T00:37:57.604725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.546562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:57.605122Z","caller":"traceutil/trace.go:171","msg":"trace[104037592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"474.96538ms","start":"2026-02-06T00:37:57.130138Z","end":"2026-02-06T00:37:57.605103Z","steps":["trace[104037592] 'agreement among raft nodes before linearized reading' (duration: 474.546222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.605815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.130124Z","time spent":"475.636538ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:37:57.606293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.475109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-06T00:37:57.60702Z","caller":"traceutil/trace.go:171","msg":"trace[1249818144] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1063; }","duration":"470.212129ms","start":"2026-02-06T00:37:57.136793Z","end":"2026-02-06T00:37:57.607005Z","steps":["trace[1249818144] 'agreement among raft nodes before linearized reading' (duration: 469.447827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.607501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.136786Z","time spent":"470.69791ms","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-06T00:37:57.604765Z","caller":"traceutil/trace.go:171","msg":"trace[286883766] range","detail":"{range_begin:/registry/ingress/kube-node-lease/; range_end:/registry/ingress/kube-node-lease0; response_count:0; response_revision:1063; }","duration":"441.81708ms","start":"2026-02-06T00:37:57.162928Z","end":"2026-02-06T00:37:57.604745Z","steps":["trace[286883766] 'agreement among raft nodes before linearized reading' (duration: 441.731276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:57.607871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:57.162918Z","time spent":"444.90674ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:37:57.826684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.823986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:57.826994Z","caller":"traceutil/trace.go:171","msg":"trace[1897216297] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"210.790723ms","start":"2026-02-06T00:37:57.616183Z","end":"2026-02-06T00:37:57.826974Z","steps":["trace[1897216297] 'process raft request' (duration: 105.623911ms)","trace[1897216297] 'compare' (duration: 104.740153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:58.202176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.855996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:58.202446Z","caller":"traceutil/trace.go:171","msg":"trace[1085513457] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1065; }","duration":"207.138587ms","start":"2026-02-06T00:37:57.995275Z","end":"2026-02-06T00:37:58.202413Z","steps":["trace[1085513457] 'range keys from in-memory index tree' (duration: 206.814103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:58.2023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.731607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:58.202702Z","caller":"traceutil/trace.go:171","msg":"trace[278428030] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1065; }","duration":"232.160104ms","start":"2026-02-06T00:37:57.970515Z","end":"2026-02-06T00:37:58.202675Z","steps":["trace[278428030] 'range keys from in-memory index tree' (duration: 231.680545ms)"],"step_count":1}