{"level":"warn","ts":"2026-02-08T16:36:15.706955Z","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-08T16:36:15.70704Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-08T16:36:15.707296Z","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-08T16:36:15.707358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-08T16:36:15.707383Z","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-08T16:36:15.708079Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-08T16:36:15.708239Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-08T16:36:15.715084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.56988ms"} {"level":"info","ts":"2026-02-08T16:36:15.742392Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-08T16:36:15.742447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T16:36:15.742465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-08T16:36:15.742476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T16:36:15.742485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-08T16:36:15.742511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-08T16:36:15.751624Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T16:36:15.755804Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T16:36:15.756858Z","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-08T16:36:15.759195Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T16:36:15.766178Z","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-08T16:36:15.766382Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T16:36:15.766542Z","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-08T16:36:15.766565Z","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-08T16:36:15.766572Z","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-08T16:36:15.766895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-08T16:36:15.767718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-08T16:36:15.772488Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-08T16:36:15.772502Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-08T16:36:15.773022Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T16:36:15.773052Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T16:36:15.844062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T16:36:15.844228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T16:36:15.844302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-08T16:36:15.844345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-08T16:36:15.844391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-08T16:36:15.844428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-08T16:36:15.844465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-08T16:36:15.850641Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T16:36:15.850751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:36:15.85093Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:15.851105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:36:15.852029Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T16:36:15.85205Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T16:36:15.852106Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:15.852195Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:15.852239Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:15.853605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-08T16:36:15.856722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T16:36:38.334657Z","caller":"traceutil/trace.go:171","msg":"trace[1668503269] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"200.390199ms","start":"2026-02-08T16:36:38.13425Z","end":"2026-02-08T16:36:38.33464Z","steps":["trace[1668503269] 'read index received' (duration: 200.243316ms)","trace[1668503269] 'applied index is now lower than readState.Index' (duration: 146.323µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:36:38.334873Z","caller":"traceutil/trace.go:171","msg":"trace[1522807274] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"270.899767ms","start":"2026-02-08T16:36:38.063968Z","end":"2026-02-08T16:36:38.334868Z","steps":["trace[1522807274] 'process raft request' (duration: 270.58589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:38.335006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.738897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-08T16:36:38.335028Z","caller":"traceutil/trace.go:171","msg":"trace[1988070985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"200.795718ms","start":"2026-02-08T16:36:38.134225Z","end":"2026-02-08T16:36:38.335021Z","steps":["trace[1988070985] 'agreement among raft nodes before linearized reading' (duration: 200.744087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:38.3351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.740288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T16:36:38.335112Z","caller":"traceutil/trace.go:171","msg":"trace[308007527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"156.805029ms","start":"2026-02-08T16:36:38.178304Z","end":"2026-02-08T16:36:38.335109Z","steps":["trace[308007527] 'agreement among raft nodes before linearized reading' (duration: 156.781688ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:46.76274Z","caller":"traceutil/trace.go:171","msg":"trace[675562548] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"369.280364ms","start":"2026-02-08T16:36:46.393447Z","end":"2026-02-08T16:36:46.762727Z","steps":["trace[675562548] 'read index received' (duration: 369.180601ms)","trace[675562548] 'applied index is now lower than readState.Index' (duration: 98.873µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:36:46.762861Z","caller":"traceutil/trace.go:171","msg":"trace[966319048] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"381.822733ms","start":"2026-02-08T16:36:46.381021Z","end":"2026-02-08T16:36:46.762844Z","steps":["trace[966319048] 'process raft request' (duration: 381.613028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:46.763063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.558908ms","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-08T16:36:46.763198Z","caller":"traceutil/trace.go:171","msg":"trace[2016048766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"368.697351ms","start":"2026-02-08T16:36:46.394447Z","end":"2026-02-08T16:36:46.763144Z","steps":["trace[2016048766] 'agreement among raft nodes before linearized reading' (duration: 368.523287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:46.763244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:46.394441Z","time spent":"368.795303ms","remote":"127.0.0.1:40148","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-08T16:36:46.763439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.990321ms","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-08T16:36:46.763455Z","caller":"traceutil/trace.go:171","msg":"trace[182084879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"370.017621ms","start":"2026-02-08T16:36:46.393431Z","end":"2026-02-08T16:36:46.763449Z","steps":["trace[182084879] 'agreement among raft nodes before linearized reading' (duration: 369.99125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:46.763451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:46.38101Z","time spent":"381.875304ms","remote":"127.0.0.1:40148","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-08T16:36:46.763467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:46.393425Z","time spent":"370.039271ms","remote":"127.0.0.1:40148","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-08T16:36:46.763749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.261733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:36:46.763837Z","caller":"traceutil/trace.go:171","msg":"trace[1839741364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"253.321504ms","start":"2026-02-08T16:36:46.510442Z","end":"2026-02-08T16:36:46.763764Z","steps":["trace[1839741364] 'agreement among raft nodes before linearized reading' (duration: 253.287573ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:49.065566Z","caller":"traceutil/trace.go:171","msg":"trace[300520989] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"291.579242ms","start":"2026-02-08T16:36:48.773969Z","end":"2026-02-08T16:36:49.065548Z","steps":["trace[300520989] 'read index received' (duration: 291.394307ms)","trace[300520989] 'applied index is now lower than readState.Index' (duration: 184.065µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:36:49.065669Z","caller":"traceutil/trace.go:171","msg":"trace[1829695406] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"296.643658ms","start":"2026-02-08T16:36:48.769018Z","end":"2026-02-08T16:36:49.065661Z","steps":["trace[1829695406] 'process raft request' (duration: 296.383131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:49.065833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.181768ms","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-08T16:36:49.065877Z","caller":"traceutil/trace.go:171","msg":"trace[258657952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"289.239409ms","start":"2026-02-08T16:36:48.776626Z","end":"2026-02-08T16:36:49.065865Z","steps":["trace[258657952] 'agreement among raft nodes before linearized reading' (duration: 289.128856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:49.065948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.980252ms","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-08T16:36:49.06596Z","caller":"traceutil/trace.go:171","msg":"trace[2018985159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"292.008013ms","start":"2026-02-08T16:36:48.773948Z","end":"2026-02-08T16:36:49.065956Z","steps":["trace[2018985159] 'agreement among raft nodes before linearized reading' (duration: 291.988112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:49.066114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.401525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:36:49.066141Z","caller":"traceutil/trace.go:171","msg":"trace[1934046934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"195.463816ms","start":"2026-02-08T16:36:48.87067Z","end":"2026-02-08T16:36:49.066134Z","steps":["trace[1934046934] 'agreement among raft nodes before linearized reading' (duration: 195.347283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:49.82185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.270735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T16:36:49.821919Z","caller":"traceutil/trace.go:171","msg":"trace[1481010871] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:482; }","duration":"417.373908ms","start":"2026-02-08T16:36:49.40453Z","end":"2026-02-08T16:36:49.821904Z","steps":["trace[1481010871] 'range keys from in-memory index tree' (duration: 417.181183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:49.821948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:49.404514Z","time spent":"417.4258ms","remote":"127.0.0.1:39934","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.200\" "} {"level":"warn","ts":"2026-02-08T16:36:49.822121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.818033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:36:49.82214Z","caller":"traceutil/trace.go:171","msg":"trace[2030698170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"310.899355ms","start":"2026-02-08T16:36:49.511235Z","end":"2026-02-08T16:36:49.822134Z","steps":["trace[2030698170] 'range keys from in-memory index tree' (duration: 310.720461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:49.822156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:49.511214Z","time spent":"310.938546ms","remote":"127.0.0.1:39906","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-08T16:36:52.107022Z","caller":"traceutil/trace.go:171","msg":"trace[1136670082] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"327.400994ms","start":"2026-02-08T16:36:51.779597Z","end":"2026-02-08T16:36:52.106998Z","steps":["trace[1136670082] 'process raft request' (duration: 327.244351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:52.107161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:51.779578Z","time spent":"327.513686ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:36:54.235948Z","caller":"traceutil/trace.go:171","msg":"trace[490475916] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"387.459372ms","start":"2026-02-08T16:36:53.84847Z","end":"2026-02-08T16:36:54.235929Z","steps":["trace[490475916] 'process raft request' (duration: 387.316669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:54.236279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:36:53.848447Z","time spent":"387.740398ms","remote":"127.0.0.1:39968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T16:36:55.214797Z","caller":"traceutil/trace.go:171","msg":"trace[931359593] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"126.621647ms","start":"2026-02-08T16:36:55.088157Z","end":"2026-02-08T16:36:55.214778Z","steps":["trace[931359593] 'process raft request' (duration: 126.300368ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:56.602717Z","caller":"traceutil/trace.go:171","msg":"trace[1983268237] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"205.587438ms","start":"2026-02-08T16:36:56.39711Z","end":"2026-02-08T16:36:56.602697Z","steps":["trace[1983268237] 'process raft request' (duration: 203.436618ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:56.609256Z","caller":"traceutil/trace.go:171","msg":"trace[1688474490] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"210.033401ms","start":"2026-02-08T16:36:56.399209Z","end":"2026-02-08T16:36:56.609242Z","steps":["trace[1688474490] 'process raft request' (duration: 209.783145ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:56.60949Z","caller":"traceutil/trace.go:171","msg":"trace[564683724] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"196.06757ms","start":"2026-02-08T16:36:56.413415Z","end":"2026-02-08T16:36:56.609482Z","steps":["trace[564683724] 'process raft request' (duration: 195.648011ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:56.60967Z","caller":"traceutil/trace.go:171","msg":"trace[225443963] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"198.652109ms","start":"2026-02-08T16:36:56.41101Z","end":"2026-02-08T16:36:56.609662Z","steps":["trace[225443963] 'read index received' (duration: 189.547049ms)","trace[225443963] 'applied index is now lower than readState.Index' (duration: 9.10424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:36:56.614478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.454058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2x9qp\" ","response":"range_response_count:1 size:16916"} {"level":"info","ts":"2026-02-08T16:36:56.614514Z","caller":"traceutil/trace.go:171","msg":"trace[197924132] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2x9qp; range_end:; response_count:1; response_revision:520; }","duration":"203.523451ms","start":"2026-02-08T16:36:56.410984Z","end":"2026-02-08T16:36:56.614507Z","steps":["trace[197924132] 'agreement among raft nodes before linearized reading' (duration: 198.899035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:36:56.615425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.880485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:36:56.615469Z","caller":"traceutil/trace.go:171","msg":"trace[1795290738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"103.933696ms","start":"2026-02-08T16:36:56.511524Z","end":"2026-02-08T16:36:56.615458Z","steps":["trace[1795290738] 'agreement among raft nodes before linearized reading' (duration: 103.832204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:08.008391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085527827153739,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T16:37:08.434586Z","caller":"traceutil/trace.go:171","msg":"trace[392641638] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"926.80187ms","start":"2026-02-08T16:37:07.507768Z","end":"2026-02-08T16:37:08.434569Z","steps":["trace[392641638] 'read index received' (duration: 926.677777ms)","trace[392641638] 'applied index is now lower than readState.Index' (duration: 123.583µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:08.43477Z","caller":"traceutil/trace.go:171","msg":"trace[179179505] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"951.596829ms","start":"2026-02-08T16:37:07.483167Z","end":"2026-02-08T16:37:08.434764Z","steps":["trace[179179505] 'process raft request' (duration: 951.317693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:08.43483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:07.483157Z","time spent":"951.62736ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5011,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:37:08.435029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.24994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:08.435069Z","caller":"traceutil/trace.go:171","msg":"trace[339825632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"927.313152ms","start":"2026-02-08T16:37:07.507745Z","end":"2026-02-08T16:37:08.435058Z","steps":["trace[339825632] 'agreement among raft nodes before linearized reading' (duration: 927.25825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:08.435086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:07.507727Z","time spent":"927.354862ms","remote":"127.0.0.1:39906","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-08T16:37:08.703899Z","caller":"traceutil/trace.go:171","msg":"trace[135443928] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"262.784191ms","start":"2026-02-08T16:37:08.441089Z","end":"2026-02-08T16:37:08.703873Z","steps":["trace[135443928] 'process raft request' (duration: 262.688509ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:08.707745Z","caller":"traceutil/trace.go:171","msg":"trace[2021950948] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"264.96554ms","start":"2026-02-08T16:37:08.442762Z","end":"2026-02-08T16:37:08.707728Z","steps":["trace[2021950948] 'process raft request' (duration: 264.828817ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:08.708162Z","caller":"traceutil/trace.go:171","msg":"trace[1417260413] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"261.424079ms","start":"2026-02-08T16:37:08.446733Z","end":"2026-02-08T16:37:08.708157Z","steps":["trace[1417260413] 'process raft request' (duration: 260.957849ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:08.70803Z","caller":"traceutil/trace.go:171","msg":"trace[791638383] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"264.143401ms","start":"2026-02-08T16:37:08.44388Z","end":"2026-02-08T16:37:08.708023Z","steps":["trace[791638383] 'read index received' (duration: 260.412006ms)","trace[791638383] 'applied index is now lower than readState.Index' (duration: 3.730765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:08.708379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.486599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-nsqpm\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-08T16:37:08.708395Z","caller":"traceutil/trace.go:171","msg":"trace[21164501] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-nsqpm; range_end:; response_count:1; response_revision:639; }","duration":"264.5286ms","start":"2026-02-08T16:37:08.443862Z","end":"2026-02-08T16:37:08.70839Z","steps":["trace[21164501] 'agreement among raft nodes before linearized reading' (duration: 264.441078ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:16.853016Z","caller":"traceutil/trace.go:171","msg":"trace[99204059] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"115.718225ms","start":"2026-02-08T16:37:16.737265Z","end":"2026-02-08T16:37:16.852984Z","steps":["trace[99204059] 'process raft request' (duration: 115.538491ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:19.075065Z","caller":"traceutil/trace.go:171","msg":"trace[1151748283] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"214.104284ms","start":"2026-02-08T16:37:18.860937Z","end":"2026-02-08T16:37:19.075042Z","steps":["trace[1151748283] 'process raft request' (duration: 214.008342ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:33.708141Z","caller":"traceutil/trace.go:171","msg":"trace[2043207642] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"198.432489ms","start":"2026-02-08T16:37:33.509682Z","end":"2026-02-08T16:37:33.708115Z","steps":["trace[2043207642] 'read index received' (duration: 198.208064ms)","trace[2043207642] 'applied index is now lower than readState.Index' (duration: 223.325µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:33.708255Z","caller":"traceutil/trace.go:171","msg":"trace[1860070369] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"264.187537ms","start":"2026-02-08T16:37:33.444056Z","end":"2026-02-08T16:37:33.708244Z","steps":["trace[1860070369] 'process raft request' (duration: 263.91117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.708496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.283495ms","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-08T16:37:33.70856Z","caller":"traceutil/trace.go:171","msg":"trace[1035302938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"179.373597ms","start":"2026-02-08T16:37:33.52917Z","end":"2026-02-08T16:37:33.708544Z","steps":["trace[1035302938] 'agreement among raft nodes before linearized reading' (duration: 179.206063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.708669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.125556ms","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-08T16:37:33.708731Z","caller":"traceutil/trace.go:171","msg":"trace[975023746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"174.189197ms","start":"2026-02-08T16:37:33.534507Z","end":"2026-02-08T16:37:33.708697Z","steps":["trace[975023746] 'agreement among raft nodes before linearized reading' (duration: 174.051954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.708774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.246508ms","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-08T16:37:33.708804Z","caller":"traceutil/trace.go:171","msg":"trace[1529639406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"177.287338ms","start":"2026-02-08T16:37:33.531508Z","end":"2026-02-08T16:37:33.708795Z","steps":["trace[1529639406] 'agreement among raft nodes before linearized reading' (duration: 177.205477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.708875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.188257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:33.708904Z","caller":"traceutil/trace.go:171","msg":"trace[1144786966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"199.233458ms","start":"2026-02-08T16:37:33.509662Z","end":"2026-02-08T16:37:33.708895Z","steps":["trace[1144786966] 'agreement among raft nodes before linearized reading' (duration: 199.192077ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:35.687039Z","caller":"traceutil/trace.go:171","msg":"trace[610607780] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"554.335046ms","start":"2026-02-08T16:37:35.132691Z","end":"2026-02-08T16:37:35.687026Z","steps":["trace[610607780] 'process raft request' (duration: 554.003478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:35.687476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:35.13268Z","time spent":"554.415909ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:35.687866Z","caller":"traceutil/trace.go:171","msg":"trace[1551154513] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"176.593012ms","start":"2026-02-08T16:37:35.510209Z","end":"2026-02-08T16:37:35.686802Z","steps":["trace[1551154513] 'read index received' (duration: 176.435118ms)","trace[1551154513] 'applied index is now lower than readState.Index' (duration: 155.404µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:35.68797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.758528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:35.688032Z","caller":"traceutil/trace.go:171","msg":"trace[1712054102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"177.846431ms","start":"2026-02-08T16:37:35.510175Z","end":"2026-02-08T16:37:35.688022Z","steps":["trace[1712054102] 'agreement among raft nodes before linearized reading' (duration: 177.763669ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:35.912493Z","caller":"traceutil/trace.go:171","msg":"trace[506538449] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"136.380658ms","start":"2026-02-08T16:37:35.77609Z","end":"2026-02-08T16:37:35.91247Z","steps":["trace[506538449] 'process raft request' (duration: 127.757787ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:35.932351Z","caller":"traceutil/trace.go:171","msg":"trace[175528068] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"152.579684ms","start":"2026-02-08T16:37:35.77975Z","end":"2026-02-08T16:37:35.93233Z","steps":["trace[175528068] 'process raft request' (duration: 152.540853ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:35.932664Z","caller":"traceutil/trace.go:171","msg":"trace[531525454] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"155.539534ms","start":"2026-02-08T16:37:35.777108Z","end":"2026-02-08T16:37:35.932647Z","steps":["trace[531525454] 'process raft request' (duration: 155.073822ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:37.951235Z","caller":"traceutil/trace.go:171","msg":"trace[1213722397] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"173.017689ms","start":"2026-02-08T16:37:37.778196Z","end":"2026-02-08T16:37:37.951213Z","steps":["trace[1213722397] 'process raft request' (duration: 172.60727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:49.804439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:49.406374Z","time spent":"398.062379ms","remote":"127.0.0.1:39934","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T16:37:49.80466Z","caller":"traceutil/trace.go:171","msg":"trace[2090850139] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:861; }","duration":"293.146094ms","start":"2026-02-08T16:37:49.511489Z","end":"2026-02-08T16:37:49.804635Z","steps":["trace[2090850139] 'read index received' (duration: 293.142874ms)","trace[2090850139] 'applied index is now lower than readState.Index' (duration: 2.51µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:49.804708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.206345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:49.804723Z","caller":"traceutil/trace.go:171","msg":"trace[989731083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"293.254517ms","start":"2026-02-08T16:37:49.511464Z","end":"2026-02-08T16:37:49.804718Z","steps":["trace[989731083] 'agreement among raft nodes before linearized reading' (duration: 293.212116ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:50.252248Z","caller":"traceutil/trace.go:171","msg":"trace[1420124514] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"446.571725ms","start":"2026-02-08T16:37:49.805664Z","end":"2026-02-08T16:37:50.252236Z","steps":["trace[1420124514] 'read index received' (duration: 404.848587ms)","trace[1420124514] 'applied index is now lower than readState.Index' (duration: 41.722488ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:50.25227Z","caller":"traceutil/trace.go:171","msg":"trace[1384833798] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"446.714269ms","start":"2026-02-08T16:37:49.805531Z","end":"2026-02-08T16:37:50.252245Z","steps":["trace[1384833798] 'process raft request' (duration: 404.914398ms)","trace[1384833798] 'compare' (duration: 41.499624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:50.252336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.657327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:50.252357Z","caller":"traceutil/trace.go:171","msg":"trace[1481936967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"446.698808ms","start":"2026-02-08T16:37:49.805651Z","end":"2026-02-08T16:37:50.25235Z","steps":["trace[1481936967] 'agreement among raft nodes before linearized reading' (duration: 446.641937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.252371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:49.805519Z","time spent":"446.79762ms","remote":"127.0.0.1:39934","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-08T16:37:50.252385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:49.805646Z","time spent":"446.734009ms","remote":"127.0.0.1:39922","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-08T16:37:50.271843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.053333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:37:50.27187Z","caller":"traceutil/trace.go:171","msg":"trace[1951381938] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:835; }","duration":"197.085904ms","start":"2026-02-08T16:37:50.074776Z","end":"2026-02-08T16:37:50.271862Z","steps":["trace[1951381938] 'agreement among raft nodes before linearized reading' (duration: 197.038903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.271973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.271836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-08T16:37:50.272006Z","caller":"traceutil/trace.go:171","msg":"trace[1161416199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:835; }","duration":"209.322888ms","start":"2026-02-08T16:37:50.062679Z","end":"2026-02-08T16:37:50.272002Z","steps":["trace[1161416199] 'agreement among raft nodes before linearized reading' (duration: 209.249347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.272097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.167449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T16:37:50.27211Z","caller":"traceutil/trace.go:171","msg":"trace[1992884146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:835; }","duration":"232.1902ms","start":"2026-02-08T16:37:50.039916Z","end":"2026-02-08T16:37:50.272106Z","steps":["trace[1992884146] 'agreement among raft nodes before linearized reading' (duration: 232.153959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.272195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.027001ms","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-08T16:37:50.272207Z","caller":"traceutil/trace.go:171","msg":"trace[1296567094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"246.049642ms","start":"2026-02-08T16:37:50.026153Z","end":"2026-02-08T16:37:50.272203Z","steps":["trace[1296567094] 'agreement among raft nodes before linearized reading' (duration: 246.010151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.272281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.253149ms","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-08T16:37:50.272293Z","caller":"traceutil/trace.go:171","msg":"trace[528500077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"250.286689ms","start":"2026-02-08T16:37:50.022003Z","end":"2026-02-08T16:37:50.27229Z","steps":["trace[528500077] 'agreement among raft nodes before linearized reading' (duration: 250.251868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.272368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.762258ms","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-08T16:37:50.272381Z","caller":"traceutil/trace.go:171","msg":"trace[440732584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"430.796669ms","start":"2026-02-08T16:37:49.841581Z","end":"2026-02-08T16:37:50.272378Z","steps":["trace[440732584] 'agreement among raft nodes before linearized reading' (duration: 430.759488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.272393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:49.84157Z","time spent":"430.819849ms","remote":"127.0.0.1:40148","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-08T16:37:50.520055Z","caller":"traceutil/trace.go:171","msg":"trace[1765874099] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"243.360919ms","start":"2026-02-08T16:37:50.276679Z","end":"2026-02-08T16:37:50.52004Z","steps":["trace[1765874099] 'process raft request' (duration: 243.238906ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:50.568541Z","caller":"traceutil/trace.go:171","msg":"trace[1100802473] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"290.776669ms","start":"2026-02-08T16:37:50.277751Z","end":"2026-02-08T16:37:50.568528Z","steps":["trace[1100802473] 'read index received' (duration: 242.575261ms)","trace[1100802473] 'applied index is now lower than readState.Index' (duration: 48.200818ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:50.568556Z","caller":"traceutil/trace.go:171","msg":"trace[1072212917] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"290.966424ms","start":"2026-02-08T16:37:50.277565Z","end":"2026-02-08T16:37:50.568532Z","steps":["trace[1072212917] 'process raft request' (duration: 290.498693ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:50.568617Z","caller":"traceutil/trace.go:171","msg":"trace[1831375240] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"290.331159ms","start":"2026-02-08T16:37:50.27828Z","end":"2026-02-08T16:37:50.568611Z","steps":["trace[1831375240] 'process raft request' (duration: 290.194396ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:50.568709Z","caller":"traceutil/trace.go:171","msg":"trace[193910023] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"290.049082ms","start":"2026-02-08T16:37:50.278656Z","end":"2026-02-08T16:37:50.568705Z","steps":["trace[193910023] 'process raft request' (duration: 289.838767ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:50.568742Z","caller":"traceutil/trace.go:171","msg":"trace[1926037599] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"287.962324ms","start":"2026-02-08T16:37:50.280776Z","end":"2026-02-08T16:37:50.568738Z","steps":["trace[1926037599] 'process raft request' (duration: 287.734569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.568821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.056405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-08T16:37:50.568836Z","caller":"traceutil/trace.go:171","msg":"trace[1519354228] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:840; }","duration":"291.094207ms","start":"2026-02-08T16:37:50.277737Z","end":"2026-02-08T16:37:50.568831Z","steps":["trace[1519354228] 'agreement among raft nodes before linearized reading' (duration: 291.035226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:50.568938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.305681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:50.568953Z","caller":"traceutil/trace.go:171","msg":"trace[761231544] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:840; }","duration":"129.320602ms","start":"2026-02-08T16:37:50.439627Z","end":"2026-02-08T16:37:50.568948Z","steps":["trace[761231544] 'agreement among raft nodes before linearized reading' (duration: 129.297211ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:53.034902Z","caller":"traceutil/trace.go:171","msg":"trace[475741080] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"462.001833ms","start":"2026-02-08T16:37:52.57288Z","end":"2026-02-08T16:37:53.034882Z","steps":["trace[475741080] 'read index received' (duration: 461.87878ms)","trace[475741080] 'applied index is now lower than readState.Index' (duration: 122.253µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:53.035002Z","caller":"traceutil/trace.go:171","msg":"trace[85068953] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"510.301176ms","start":"2026-02-08T16:37:52.524691Z","end":"2026-02-08T16:37:53.034993Z","steps":["trace[85068953] 'process raft request' (duration: 510.07401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.035098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:52.524681Z","time spent":"510.343886ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:37:53.035185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.24661ms","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-08T16:37:53.035214Z","caller":"traceutil/trace.go:171","msg":"trace[1619756189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"462.288411ms","start":"2026-02-08T16:37:52.572918Z","end":"2026-02-08T16:37:53.035206Z","steps":["trace[1619756189] 'agreement among raft nodes before linearized reading' (duration: 462.173718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.035236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:52.572915Z","time spent":"462.315841ms","remote":"127.0.0.1:40148","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-08T16:37:53.035299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.928574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:53.03532Z","caller":"traceutil/trace.go:171","msg":"trace[1937618619] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:841; }","duration":"343.977624ms","start":"2026-02-08T16:37:52.691336Z","end":"2026-02-08T16:37:53.035314Z","steps":["trace[1937618619] 'agreement among raft nodes before linearized reading' (duration: 343.942753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.035339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:52.691314Z","time spent":"344.019586ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:37:53.035359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.024735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-08T16:37:53.035381Z","caller":"traceutil/trace.go:171","msg":"trace[1968700428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:841; }","duration":"459.051985ms","start":"2026-02-08T16:37:52.576322Z","end":"2026-02-08T16:37:53.035373Z","steps":["trace[1968700428] 'agreement among raft nodes before linearized reading' (duration: 458.999784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.035398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:52.576318Z","time spent":"459.075726ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:53.035462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.579977ms","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-08T16:37:53.035481Z","caller":"traceutil/trace.go:171","msg":"trace[22033194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:841; }","duration":"462.615858ms","start":"2026-02-08T16:37:52.57286Z","end":"2026-02-08T16:37:53.035475Z","steps":["trace[22033194] 'agreement among raft nodes before linearized reading' (duration: 462.562257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.035497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:52.572848Z","time spent":"462.645388ms","remote":"127.0.0.1:40148","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-08T16:37:53.035498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.542496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T16:37:53.035515Z","caller":"traceutil/trace.go:171","msg":"trace[1085148031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"459.568378ms","start":"2026-02-08T16:37:52.575941Z","end":"2026-02-08T16:37:53.03551Z","steps":["trace[1085148031] 'agreement among raft nodes before linearized reading' (duration: 459.520876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.035531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:52.575923Z","time spent":"459.604568ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:53.461806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.696553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:37:53.461896Z","caller":"traceutil/trace.go:171","msg":"trace[1029792091] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"419.16058ms","start":"2026-02-08T16:37:53.042719Z","end":"2026-02-08T16:37:53.461879Z","steps":["trace[1029792091] 'process raft request' (duration: 39.17149ms)","trace[1029792091] 'compare' (duration: 379.271743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:53.461952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.042699Z","time spent":"419.226061ms","remote":"127.0.0.1:40148","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-08T16:37:53.469131Z","caller":"traceutil/trace.go:171","msg":"trace[870728753] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"424.054562ms","start":"2026-02-08T16:37:53.045067Z","end":"2026-02-08T16:37:53.469122Z","steps":["trace[870728753] 'process raft request' (duration: 424.038062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.469184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.045061Z","time spent":"424.095784ms","remote":"127.0.0.1:40148","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-08T16:37:53.469673Z","caller":"traceutil/trace.go:171","msg":"trace[1007122431] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"425.829944ms","start":"2026-02-08T16:37:53.043836Z","end":"2026-02-08T16:37:53.469666Z","steps":["trace[1007122431] 'process raft request' (duration: 425.198608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.469708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.04383Z","time spent":"425.858724ms","remote":"127.0.0.1:40148","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-08T16:37:53.469834Z","caller":"traceutil/trace.go:171","msg":"trace[973118176] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"425.960837ms","start":"2026-02-08T16:37:53.043868Z","end":"2026-02-08T16:37:53.469829Z","steps":["trace[973118176] 'process raft request' (duration: 425.2205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:53.469863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.043865Z","time spent":"425.981838ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:37:53.781226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.365956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:37:53.781286Z","caller":"traceutil/trace.go:171","msg":"trace[1534131072] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"235.522076ms","start":"2026-02-08T16:37:53.545755Z","end":"2026-02-08T16:37:53.781277Z","steps":["trace[1534131072] 'read index received' (duration: 76.985456ms)","trace[1534131072] 'applied index is now lower than readState.Index' (duration: 158.53605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:53.78134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.590599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:37:53.781354Z","caller":"traceutil/trace.go:171","msg":"trace[1556443289] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:846; }","duration":"235.66638ms","start":"2026-02-08T16:37:53.545682Z","end":"2026-02-08T16:37:53.781348Z","steps":["trace[1556443289] 'agreement among raft nodes before linearized reading' (duration: 235.618619ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:53.781511Z","caller":"traceutil/trace.go:171","msg":"trace[2099812422] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"565.976036ms","start":"2026-02-08T16:37:53.215529Z","end":"2026-02-08T16:37:53.781506Z","steps":["trace[2099812422] 'process raft request' (duration: 407.277383ms)","trace[2099812422] 'compare' (duration: 158.300644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:53.781552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.215505Z","time spent":"566.029238ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:03.722642Z","caller":"traceutil/trace.go:171","msg":"trace[1316208974] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"185.591218ms","start":"2026-02-08T16:38:03.537028Z","end":"2026-02-08T16:38:03.722619Z","steps":["trace[1316208974] 'process raft request' (duration: 185.533627ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:03.722994Z","caller":"traceutil/trace.go:171","msg":"trace[694078277] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"186.363097ms","start":"2026-02-08T16:38:03.536619Z","end":"2026-02-08T16:38:03.722982Z","steps":["trace[694078277] 'process raft request' (duration: 131.79344ms)","trace[694078277] 'compare' (duration: 53.520112ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:04.214797Z","caller":"traceutil/trace.go:171","msg":"trace[1374774257] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"202.837488ms","start":"2026-02-08T16:38:04.011945Z","end":"2026-02-08T16:38:04.214783Z","steps":["trace[1374774257] 'read index received' (duration: 168.127542ms)","trace[1374774257] 'applied index is now lower than readState.Index' (duration: 34.709436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:04.214881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.94334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:38:04.214897Z","caller":"traceutil/trace.go:171","msg":"trace[2105927833] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:874; }","duration":"203.009132ms","start":"2026-02-08T16:38:04.011883Z","end":"2026-02-08T16:38:04.214892Z","steps":["trace[2105927833] 'agreement among raft nodes before linearized reading' (duration: 202.949081ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:04.214948Z","caller":"traceutil/trace.go:171","msg":"trace[1055118012] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"235.541467ms","start":"2026-02-08T16:38:03.979389Z","end":"2026-02-08T16:38:04.214931Z","steps":["trace[1055118012] 'process raft request' (duration: 200.728589ms)","trace[1055118012] 'compare' (duration: 34.497021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:07.957236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.169196ms","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-08T16:38:07.957309Z","caller":"traceutil/trace.go:171","msg":"trace[216274694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"418.271058ms","start":"2026-02-08T16:38:07.539018Z","end":"2026-02-08T16:38:07.957289Z","steps":["trace[216274694] 'range keys from in-memory index tree' (duration: 418.004802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:07.957363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:07.539003Z","time spent":"418.35087ms","remote":"127.0.0.1:40148","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-08T16:38:07.957661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.12791ms","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-08T16:38:07.9577Z","caller":"traceutil/trace.go:171","msg":"trace[1107938622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"351.195821ms","start":"2026-02-08T16:38:07.606495Z","end":"2026-02-08T16:38:07.957691Z","steps":["trace[1107938622] 'range keys from in-memory index tree' (duration: 351.011818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:07.957755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:07.60648Z","time spent":"351.242112ms","remote":"127.0.0.1:40148","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-08T16:38:07.958054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.927373ms","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-08T16:38:07.958083Z","caller":"traceutil/trace.go:171","msg":"trace[1010346666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"221.975923ms","start":"2026-02-08T16:38:07.736098Z","end":"2026-02-08T16:38:07.958074Z","steps":["trace[1010346666] 'range keys from in-memory index tree' (duration: 221.83536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:07.958133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.461301ms","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-08T16:38:07.958178Z","caller":"traceutil/trace.go:171","msg":"trace[572203925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"221.539782ms","start":"2026-02-08T16:38:07.736626Z","end":"2026-02-08T16:38:07.958166Z","steps":["trace[572203925] 'range keys from in-memory index tree' (duration: 221.364498ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:08.251516Z","caller":"traceutil/trace.go:171","msg":"trace[2010761942] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"289.743965ms","start":"2026-02-08T16:38:07.96175Z","end":"2026-02-08T16:38:08.251494Z","steps":["trace[2010761942] 'process raft request' (duration: 289.619932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:08.471104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.303786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:08.471245Z","caller":"traceutil/trace.go:171","msg":"trace[793395025] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:912; }","duration":"194.993167ms","start":"2026-02-08T16:38:08.276238Z","end":"2026-02-08T16:38:08.471231Z","steps":["trace[793395025] 'read index received' (duration: 92.428926ms)","trace[793395025] 'applied index is now lower than readState.Index' (duration: 102.563631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:08.471334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.091109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:38:08.471352Z","caller":"traceutil/trace.go:171","msg":"trace[724953566] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:884; }","duration":"195.1356ms","start":"2026-02-08T16:38:08.276211Z","end":"2026-02-08T16:38:08.471346Z","steps":["trace[724953566] 'agreement among raft nodes before linearized reading' (duration: 195.062978ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:08.471563Z","caller":"traceutil/trace.go:171","msg":"trace[1769417497] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"508.446983ms","start":"2026-02-08T16:38:07.963108Z","end":"2026-02-08T16:38:08.471555Z","steps":["trace[1769417497] 'process raft request' (duration: 405.629556ms)","trace[1769417497] 'compare' (duration: 102.144211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:08.471614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:07.963091Z","time spent":"508.494153ms","remote":"127.0.0.1:40148","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-08T16:38:08.471704Z","caller":"traceutil/trace.go:171","msg":"trace[1070389561] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"506.584899ms","start":"2026-02-08T16:38:07.965112Z","end":"2026-02-08T16:38:08.471697Z","steps":["trace[1070389561] 'process raft request' (duration: 506.053067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:08.471737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:07.965103Z","time spent":"506.613489ms","remote":"127.0.0.1:40148","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-08T16:38:08.471821Z","caller":"traceutil/trace.go:171","msg":"trace[1705745720] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"504.878198ms","start":"2026-02-08T16:38:07.966931Z","end":"2026-02-08T16:38:08.471809Z","steps":["trace[1705745720] 'process raft request' (duration: 504.272014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:08.471851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:07.966923Z","time spent":"504.91104ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:08.896434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.327575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:08.896492Z","caller":"traceutil/trace.go:171","msg":"trace[750139026] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:884; }","duration":"186.425188ms","start":"2026-02-08T16:38:08.710054Z","end":"2026-02-08T16:38:08.89648Z","steps":["trace[750139026] 'count revisions from in-memory index tree' (duration: 186.219473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:13.717365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.876175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:13.717441Z","caller":"traceutil/trace.go:171","msg":"trace[289879473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"207.807753ms","start":"2026-02-08T16:38:13.509616Z","end":"2026-02-08T16:38:13.717424Z","steps":["trace[289879473] 'range keys from in-memory index tree' (duration: 201.806184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.154423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.737027ms","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-08T16:38:22.154516Z","caller":"traceutil/trace.go:171","msg":"trace[1292139514] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:963; }","duration":"137.841379ms","start":"2026-02-08T16:38:22.016659Z","end":"2026-02-08T16:38:22.1545Z","steps":["trace[1292139514] 'count revisions from in-memory index tree' (duration: 137.578104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.145867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.738627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:23.145938Z","caller":"traceutil/trace.go:171","msg":"trace[226356329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"635.820519ms","start":"2026-02-08T16:38:22.510101Z","end":"2026-02-08T16:38:23.145921Z","steps":["trace[226356329] 'range keys from in-memory index tree' (duration: 635.671175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.145976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.510045Z","time spent":"635.923071ms","remote":"127.0.0.1:39906","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-08T16:38:23.163101Z","caller":"traceutil/trace.go:171","msg":"trace[215091831] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"604.85563ms","start":"2026-02-08T16:38:22.558222Z","end":"2026-02-08T16:38:23.163078Z","steps":["trace[215091831] 'process raft request' (duration: 604.714746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.16326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.558207Z","time spent":"604.961552ms","remote":"127.0.0.1:39968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":865,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T16:38:23.066468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085527827154507,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T16:38:23.164578Z","caller":"traceutil/trace.go:171","msg":"trace[358186805] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:999; }","duration":"598.148925ms","start":"2026-02-08T16:38:22.566415Z","end":"2026-02-08T16:38:23.164564Z","steps":["trace[358186805] 'read index received' (duration: 598.144205ms)","trace[358186805] 'applied index is now lower than readState.Index' (duration: 3.92µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:23.164738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.311148ms","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-08T16:38:23.164775Z","caller":"traceutil/trace.go:171","msg":"trace[1659067715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"598.39757ms","start":"2026-02-08T16:38:22.566367Z","end":"2026-02-08T16:38:23.164764Z","steps":["trace[1659067715] 'agreement among raft nodes before linearized reading' (duration: 598.247537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.164806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.566352Z","time spent":"598.447221ms","remote":"127.0.0.1:40148","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-08T16:38:23.165064Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":9823085527827154509,"received-request-id":9823085527827154507} {"level":"warn","ts":"2026-02-08T16:38:23.165186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.776782ms","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-08T16:38:23.165218Z","caller":"traceutil/trace.go:171","msg":"trace[1920608888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"596.824343ms","start":"2026-02-08T16:38:22.568385Z","end":"2026-02-08T16:38:23.165209Z","steps":["trace[1920608888] 'agreement among raft nodes before linearized reading' (duration: 596.733201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.165247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.568378Z","time spent":"596.862664ms","remote":"127.0.0.1:40148","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-08T16:38:23.165604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.364464ms","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-08T16:38:23.165642Z","caller":"traceutil/trace.go:171","msg":"trace[517759819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"506.435375ms","start":"2026-02-08T16:38:22.659198Z","end":"2026-02-08T16:38:23.165633Z","steps":["trace[517759819] 'agreement among raft nodes before linearized reading' (duration: 506.342433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.165674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.659177Z","time spent":"506.487397ms","remote":"127.0.0.1:40148","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-08T16:38:23.640378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.569034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:23.64057Z","caller":"traceutil/trace.go:171","msg":"trace[2137748846] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:999; }","duration":"402.326379ms","start":"2026-02-08T16:38:23.238229Z","end":"2026-02-08T16:38:23.640555Z","steps":["trace[2137748846] 'read index received' (duration: 85.694309ms)","trace[2137748846] 'applied index is now lower than readState.Index' (duration: 316.63115ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:23.64087Z","caller":"traceutil/trace.go:171","msg":"trace[235787094] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"470.190964ms","start":"2026-02-08T16:38:23.170664Z","end":"2026-02-08T16:38:23.640855Z","steps":["trace[235787094] 'process raft request' (duration: 153.248177ms)","trace[235787094] 'compare' (duration: 315.195156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:23.640937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.170645Z","time spent":"470.255126ms","remote":"127.0.0.1:40148","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-08T16:38:23.641167Z","caller":"traceutil/trace.go:171","msg":"trace[981829611] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"469.329055ms","start":"2026-02-08T16:38:23.171828Z","end":"2026-02-08T16:38:23.641157Z","steps":["trace[981829611] 'process raft request' (duration: 468.635459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.641226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.171821Z","time spent":"469.371435ms","remote":"127.0.0.1:40148","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-08T16:38:23.641385Z","caller":"traceutil/trace.go:171","msg":"trace[485790113] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"469.490688ms","start":"2026-02-08T16:38:23.171863Z","end":"2026-02-08T16:38:23.641354Z","steps":["trace[485790113] 'process raft request' (duration: 468.655219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.641432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.171859Z","time spent":"469.548449ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:23.641615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.383453ms","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-08T16:38:23.641644Z","caller":"traceutil/trace.go:171","msg":"trace[1620515281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"403.434865ms","start":"2026-02-08T16:38:23.238198Z","end":"2026-02-08T16:38:23.641633Z","steps":["trace[1620515281] 'agreement among raft nodes before linearized reading' (duration: 403.342812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.641669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.238188Z","time spent":"403.475515ms","remote":"127.0.0.1:40148","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-08T16:38:23.642948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.089156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:23.642972Z","caller":"traceutil/trace.go:171","msg":"trace[1716807228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"132.138938ms","start":"2026-02-08T16:38:23.510827Z","end":"2026-02-08T16:38:23.642965Z","steps":["trace[1716807228] 'agreement among raft nodes before linearized reading' (duration: 132.103237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.643244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.41441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:23.643265Z","caller":"traceutil/trace.go:171","msg":"trace[1614910127] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:968; }","duration":"103.460102ms","start":"2026-02-08T16:38:23.5398Z","end":"2026-02-08T16:38:23.64326Z","steps":["trace[1614910127] 'agreement among raft nodes before linearized reading' (duration: 103.424501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:23.998655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.080097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:23.998712Z","caller":"traceutil/trace.go:171","msg":"trace[1485772055] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"349.561194ms","start":"2026-02-08T16:38:23.649141Z","end":"2026-02-08T16:38:23.998702Z","steps":["trace[1485772055] 'process raft request' (duration: 142.373485ms)","trace[1485772055] 'compare' (duration: 206.967183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:23.998742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.649095Z","time spent":"349.598585ms","remote":"127.0.0.1:40148","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-08T16:38:24.497292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.79552ms","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-08T16:38:24.497354Z","caller":"traceutil/trace.go:171","msg":"trace[496428861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"125.900932ms","start":"2026-02-08T16:38:24.37144Z","end":"2026-02-08T16:38:24.497341Z","steps":["trace[496428861] 'range keys from in-memory index tree' (duration: 125.702658ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:37.872451Z","caller":"traceutil/trace.go:171","msg":"trace[832138169] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"112.01543ms","start":"2026-02-08T16:38:37.760405Z","end":"2026-02-08T16:38:37.872421Z","steps":["trace[832138169] 'process raft request' (duration: 75.39599ms)","trace[832138169] 'compare' (duration: 35.993866ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:39.527434Z","caller":"traceutil/trace.go:171","msg":"trace[1770410465] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"109.43565ms","start":"2026-02-08T16:38:39.417968Z","end":"2026-02-08T16:38:39.527404Z","steps":["trace[1770410465] 'process raft request' (duration: 32.050474ms)","trace[1770410465] 'compare' (duration: 77.278274ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:39.863533Z","caller":"traceutil/trace.go:171","msg":"trace[550082182] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"107.288331ms","start":"2026-02-08T16:38:39.756225Z","end":"2026-02-08T16:38:39.863513Z","steps":["trace[550082182] 'process raft request' (duration: 72.968964ms)","trace[550082182] 'compare' (duration: 34.237075ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:39:59.994377Z","caller":"traceutil/trace.go:171","msg":"trace[155651648] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"138.308291ms","start":"2026-02-08T16:39:59.856014Z","end":"2026-02-08T16:39:59.994323Z","steps":["trace[155651648] 'process raft request' (duration: 136.945969ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:40:00.519781Z","caller":"traceutil/trace.go:171","msg":"trace[91349423] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"177.308144ms","start":"2026-02-08T16:40:00.342454Z","end":"2026-02-08T16:40:00.519762Z","steps":["trace[91349423] 'read index received' (duration: 111.967732ms)","trace[91349423] 'applied index is now lower than readState.Index' (duration: 65.339442ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:40:00.519895Z","caller":"traceutil/trace.go:171","msg":"trace[1787458879] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"179.056885ms","start":"2026-02-08T16:40:00.340822Z","end":"2026-02-08T16:40:00.519879Z","steps":["trace[1787458879] 'process raft request' (duration: 113.64362ms)","trace[1787458879] 'compare' (duration: 65.209669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:40:00.520139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.671493ms","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-08T16:40:00.520179Z","caller":"traceutil/trace.go:171","msg":"trace[1454577835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"177.774115ms","start":"2026-02-08T16:40:00.342395Z","end":"2026-02-08T16:40:00.520169Z","steps":["trace[1454577835] 'agreement among raft nodes before linearized reading' (duration: 177.663983ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:41:33.571512Z","caller":"traceutil/trace.go:171","msg":"trace[462660085] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"190.275078ms","start":"2026-02-08T16:41:33.381219Z","end":"2026-02-08T16:41:33.571494Z","steps":["trace[462660085] 'process raft request' (duration: 190.059803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:33.881464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.187272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:41:33.882499Z","caller":"traceutil/trace.go:171","msg":"trace[507866922] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"256.263211ms","start":"2026-02-08T16:41:33.626213Z","end":"2026-02-08T16:41:33.882476Z","steps":["trace[507866922] 'process raft request' (duration: 63.008784ms)","trace[507866922] 'compare' (duration: 191.965146ms)"],"step_count":2}