{"level":"warn","ts":"2026-01-28T01:10:37.855901Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T01:10:37.857501Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-01-28T01:10:37.857932Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T01:10:37.858615Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-01-28T01:10:37.859086Z","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-01-28T01:10:37.862191Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-01-28T01:10:37.86986Z","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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-01-28T01:10:37.880555Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.325516ms"} {"level":"info","ts":"2026-01-28T01:10:37.89285Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-01-28T01:10:37.893741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-01-28T01:10:37.894325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-01-28T01:10:37.89444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-28T01:10:37.894549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-01-28T01:10:37.894698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-01-28T01:10:37.904338Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-28T01:10:37.910012Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-28T01:10:37.913332Z","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-01-28T01:10:37.918094Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-28T01:10:37.93388Z","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-01-28T01:10:37.934001Z","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-01-28T01:10:37.934058Z","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-01-28T01:10:37.934521Z","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-01-28T01:10:37.935491Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-28T01:10:37.935536Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-28T01:10:37.936439Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-28T01:10:37.936607Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-01-28T01:10:37.937807Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-01-28T01:10:37.936889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-01-28T01:10:37.938005Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-01-28T01:10:38.096146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-01-28T01:10:38.096388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-01-28T01:10:38.096412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-01-28T01:10:38.096476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-01-28T01:10:38.096523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-01-28T01:10:38.096564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-01-28T01:10:38.096592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-01-28T01:10:38.106326Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:10:38.111276Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-01-28T01:10:38.111465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T01:10:38.11596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T01:10:38.116378Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-28T01:10:38.116399Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-28T01:10:38.1234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-01-28T01:10:38.123977Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-28T01:10:38.124156Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:10:38.124946Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:10:38.125019Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-28T01:10:45.485445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.469855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-28T01:10:45.485515Z","caller":"traceutil/trace.go:171","msg":"trace[887367307] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:319; }","duration":"319.575058ms","start":"2026-01-28T01:10:45.165929Z","end":"2026-01-28T01:10:45.485504Z","steps":["trace[887367307] 'range keys from in-memory index tree' (duration: 319.411154ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:45.485547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:45.165917Z","time spent":"319.624838ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-01-28T01:11:08.36059Z","caller":"traceutil/trace.go:171","msg":"trace[512361385] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"262.026918ms","start":"2026-01-28T01:11:08.098551Z","end":"2026-01-28T01:11:08.360578Z","steps":["trace[512361385] 'process raft request' (duration: 261.605189ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:08.360372Z","caller":"traceutil/trace.go:171","msg":"trace[248916543] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"140.62596ms","start":"2026-01-28T01:11:08.219728Z","end":"2026-01-28T01:11:08.360354Z","steps":["trace[248916543] 'read index received' (duration: 140.349654ms)","trace[248916543] 'applied index is now lower than readState.Index' (duration: 275.546µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:08.36101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.267545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-28T01:11:08.361055Z","caller":"traceutil/trace.go:171","msg":"trace[1701309314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"141.363497ms","start":"2026-01-28T01:11:08.21968Z","end":"2026-01-28T01:11:08.361044Z","steps":["trace[1701309314] 'agreement among raft nodes before linearized reading' (duration: 141.274285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:08.752532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.636198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:08.75263Z","caller":"traceutil/trace.go:171","msg":"trace[1090897036] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"254.110773ms","start":"2026-01-28T01:11:08.498503Z","end":"2026-01-28T01:11:08.752613Z","steps":["trace[1090897036] 'read index received' (duration: 60.801321ms)","trace[1090897036] 'applied index is now lower than readState.Index' (duration: 193.308292ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:08.752644Z","caller":"traceutil/trace.go:171","msg":"trace[1787315989] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"386.840769ms","start":"2026-01-28T01:11:08.365779Z","end":"2026-01-28T01:11:08.75262Z","steps":["trace[1787315989] 'process raft request' (duration: 193.583958ms)","trace[1787315989] 'compare' (duration: 192.533935ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:08.752718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.222445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:08.752745Z","caller":"traceutil/trace.go:171","msg":"trace[1057709290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"254.272187ms","start":"2026-01-28T01:11:08.498464Z","end":"2026-01-28T01:11:08.752736Z","steps":["trace[1057709290] 'agreement among raft nodes before linearized reading' (duration: 254.226816ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:08.752749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:08.365763Z","time spent":"386.928261ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:09.248113Z","caller":"traceutil/trace.go:171","msg":"trace[1085484364] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"489.244508ms","start":"2026-01-28T01:11:08.758845Z","end":"2026-01-28T01:11:09.248089Z","steps":["trace[1085484364] 'process raft request' (duration: 486.926597ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:09.24825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:08.7588Z","time spent":"489.37841ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:09.252036Z","caller":"traceutil/trace.go:171","msg":"trace[1219265111] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"491.242381ms","start":"2026-01-28T01:11:08.760779Z","end":"2026-01-28T01:11:09.252022Z","steps":["trace[1219265111] 'process raft request' (duration: 491.221231ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:09.252123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:08.760766Z","time spent":"491.308092ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:09.25223Z","caller":"traceutil/trace.go:171","msg":"trace[744522430] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"490.72663ms","start":"2026-01-28T01:11:08.761487Z","end":"2026-01-28T01:11:09.252214Z","steps":["trace[744522430] 'process raft request' (duration: 490.440413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:09.252318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:08.760361Z","time spent":"491.906417ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:11.22363Z","caller":"traceutil/trace.go:171","msg":"trace[628369193] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"461.591437ms","start":"2026-01-28T01:11:10.762023Z","end":"2026-01-28T01:11:11.223615Z","steps":["trace[628369193] 'process raft request' (duration: 461.442434ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.223771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:10.762007Z","time spent":"461.69457ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:11.494187Z","caller":"traceutil/trace.go:171","msg":"trace[2138653201] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"243.944558ms","start":"2026-01-28T01:11:11.250221Z","end":"2026-01-28T01:11:11.494165Z","steps":["trace[2138653201] 'read index received' (duration: 233.810075ms)","trace[2138653201] 'applied index is now lower than readState.Index' (duration: 10.133903ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:11.494326Z","caller":"traceutil/trace.go:171","msg":"trace[1145163157] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"256.903303ms","start":"2026-01-28T01:11:11.237418Z","end":"2026-01-28T01:11:11.494321Z","steps":["trace[1145163157] 'process raft request' (duration: 246.661178ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.494483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.242205ms","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-01-28T01:11:11.494501Z","caller":"traceutil/trace.go:171","msg":"trace[635150850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"244.304706ms","start":"2026-01-28T01:11:11.250191Z","end":"2026-01-28T01:11:11.494496Z","steps":["trace[635150850] 'agreement among raft nodes before linearized reading' (duration: 244.218684ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.494588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.714247ms","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-01-28T01:11:11.494601Z","caller":"traceutil/trace.go:171","msg":"trace[82961444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"240.727827ms","start":"2026-01-28T01:11:11.25387Z","end":"2026-01-28T01:11:11.494598Z","steps":["trace[82961444] 'agreement among raft nodes before linearized reading' (duration: 240.689587ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.494714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.573343ms","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-01-28T01:11:11.494727Z","caller":"traceutil/trace.go:171","msg":"trace[1790721793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"239.601603ms","start":"2026-01-28T01:11:11.255122Z","end":"2026-01-28T01:11:11.494723Z","steps":["trace[1790721793] 'agreement among raft nodes before linearized reading' (duration: 239.566352ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:12.060454Z","caller":"traceutil/trace.go:171","msg":"trace[419655762] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"562.665257ms","start":"2026-01-28T01:11:11.497774Z","end":"2026-01-28T01:11:12.060439Z","steps":["trace[419655762] 'process raft request' (duration: 562.549923ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.060631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.497768Z","time spent":"562.7923ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:12.060682Z","caller":"traceutil/trace.go:171","msg":"trace[1864011089] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"555.006299ms","start":"2026-01-28T01:11:11.505657Z","end":"2026-01-28T01:11:12.060663Z","steps":["trace[1864011089] 'read index received' (duration: 37.870556ms)","trace[1864011089] 'applied index is now lower than readState.Index' (duration: 517.134703ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:12.06088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.105259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-28T01:11:12.060921Z","caller":"traceutil/trace.go:171","msg":"trace[301300096] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:521; }","duration":"184.179771ms","start":"2026-01-28T01:11:11.876735Z","end":"2026-01-28T01:11:12.060915Z","steps":["trace[301300096] 'agreement among raft nodes before linearized reading' (duration: 184.12208ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:12.060946Z","caller":"traceutil/trace.go:171","msg":"trace[1876682957] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"559.833944ms","start":"2026-01-28T01:11:11.5011Z","end":"2026-01-28T01:11:12.060934Z","steps":["trace[1876682957] 'process raft request' (duration: 559.316122ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.061049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.390117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-28T01:11:12.06105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.501094Z","time spent":"559.887866ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:12.061106Z","caller":"traceutil/trace.go:171","msg":"trace[129556730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"555.442758ms","start":"2026-01-28T01:11:11.505639Z","end":"2026-01-28T01:11:12.061082Z","steps":["trace[129556730] 'agreement among raft nodes before linearized reading' (duration: 555.395237ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.061133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.505628Z","time spent":"555.499909ms","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-28T01:11:12.438571Z","caller":"traceutil/trace.go:171","msg":"trace[979412471] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"374.532907ms","start":"2026-01-28T01:11:12.064024Z","end":"2026-01-28T01:11:12.438557Z","steps":["trace[979412471] 'read index received' (duration: 374.437145ms)","trace[979412471] 'applied index is now lower than readState.Index' (duration: 95.182µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:12.438652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.60734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:12.438668Z","caller":"traceutil/trace.go:171","msg":"trace[1309482332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"374.667141ms","start":"2026-01-28T01:11:12.063996Z","end":"2026-01-28T01:11:12.438663Z","steps":["trace[1309482332] 'agreement among raft nodes before linearized reading' (duration: 374.61195ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.438683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:12.063985Z","time spent":"374.694032ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T01:11:12.438785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:12.063772Z","time spent":"375.012479ms","remote":"127.0.0.1:37828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-28T01:11:13.369715Z","caller":"traceutil/trace.go:171","msg":"trace[94963872] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"141.330267ms","start":"2026-01-28T01:11:13.228367Z","end":"2026-01-28T01:11:13.369697Z","steps":["trace[94963872] 'process raft request' (duration: 141.204105ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:14.172902Z","caller":"traceutil/trace.go:171","msg":"trace[1034078898] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"103.048952ms","start":"2026-01-28T01:11:14.069837Z","end":"2026-01-28T01:11:14.172885Z","steps":["trace[1034078898] 'process raft request' (duration: 102.708594ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:18.067122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.599296ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-28T01:11:18.067252Z","caller":"traceutil/trace.go:171","msg":"trace[2072602257] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"687.769305ms","start":"2026-01-28T01:11:17.37945Z","end":"2026-01-28T01:11:18.067219Z","steps":["trace[2072602257] 'read index received' (duration: 38.051µs)","trace[2072602257] 'applied index is now lower than readState.Index' (duration: 687.729754ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:18.067822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.362978ms","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-01-28T01:11:18.067855Z","caller":"traceutil/trace.go:171","msg":"trace[920401385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"688.398739ms","start":"2026-01-28T01:11:17.379445Z","end":"2026-01-28T01:11:18.067844Z","steps":["trace[920401385] 'agreement among raft nodes before linearized reading' (duration: 688.235205ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:18.067878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:17.379393Z","time spent":"688.47906ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T01:11:18.068013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.322336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:18.068036Z","caller":"traceutil/trace.go:171","msg":"trace[2006780391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"566.373618ms","start":"2026-01-28T01:11:17.501657Z","end":"2026-01-28T01:11:18.06803Z","steps":["trace[2006780391] 'agreement among raft nodes before linearized reading' (duration: 566.339807ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:18.068051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:17.501632Z","time spent":"566.415819ms","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T01:11:18.0682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.628197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xf8t8\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-28T01:11:18.068225Z","caller":"traceutil/trace.go:171","msg":"trace[10901764] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xf8t8; range_end:; response_count:1; response_revision:534; }","duration":"314.679598ms","start":"2026-01-28T01:11:17.753539Z","end":"2026-01-28T01:11:18.068219Z","steps":["trace[10901764] 'agreement among raft nodes before linearized reading' (duration: 314.611676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:18.068241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:17.75351Z","time spent":"314.727249ms","remote":"127.0.0.1:37978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-xf8t8\" "} {"level":"warn","ts":"2026-01-28T01:11:18.068836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.559129ms","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-01-28T01:11:18.068866Z","caller":"traceutil/trace.go:171","msg":"trace[726191335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"467.634641ms","start":"2026-01-28T01:11:17.601225Z","end":"2026-01-28T01:11:18.068859Z","steps":["trace[726191335] 'agreement among raft nodes before linearized reading' (duration: 467.563339ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:18.068884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:17.601199Z","time spent":"467.680802ms","remote":"127.0.0.1:38068","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-01-28T01:11:20.324054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.585878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:20.32424Z","caller":"traceutil/trace.go:171","msg":"trace[2070001371] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"153.435552ms","start":"2026-01-28T01:11:20.170788Z","end":"2026-01-28T01:11:20.324224Z","steps":["trace[2070001371] 'process raft request' (duration: 153.382081ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:20.324484Z","caller":"traceutil/trace.go:171","msg":"trace[2071607023] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"246.529377ms","start":"2026-01-28T01:11:20.077942Z","end":"2026-01-28T01:11:20.324471Z","steps":["trace[2071607023] 'read index received' (duration: 91.13384ms)","trace[2071607023] 'applied index is now lower than readState.Index' (duration: 155.393847ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:20.324581Z","caller":"traceutil/trace.go:171","msg":"trace[19726958] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"393.350494ms","start":"2026-01-28T01:11:19.931219Z","end":"2026-01-28T01:11:20.324569Z","steps":["trace[19726958] 'process raft request' (duration: 237.912466ms)","trace[19726958] 'compare' (duration: 154.21146ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:20.324641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:19.931214Z","time spent":"393.389264ms","remote":"127.0.0.1:37846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:20.324879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.945085ms","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-01-28T01:11:20.325109Z","caller":"traceutil/trace.go:171","msg":"trace[201262733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"247.013428ms","start":"2026-01-28T01:11:20.077891Z","end":"2026-01-28T01:11:20.324905Z","steps":["trace[201262733] 'agreement among raft nodes before linearized reading' (duration: 246.880565ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:20.326246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.078193ms","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-01-28T01:11:20.326378Z","caller":"traceutil/trace.go:171","msg":"trace[335062597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"137.232316ms","start":"2026-01-28T01:11:20.189132Z","end":"2026-01-28T01:11:20.326365Z","steps":["trace[335062597] 'agreement among raft nodes before linearized reading' (duration: 137.009631ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:20.326827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.641735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:20.326909Z","caller":"traceutil/trace.go:171","msg":"trace[1521644961] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"108.752827ms","start":"2026-01-28T01:11:20.218142Z","end":"2026-01-28T01:11:20.326895Z","steps":["trace[1521644961] 'agreement among raft nodes before linearized reading' (duration: 108.630614ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:20.328396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.101833ms","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-01-28T01:11:20.328641Z","caller":"traceutil/trace.go:171","msg":"trace[204571576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"142.37852ms","start":"2026-01-28T01:11:20.186249Z","end":"2026-01-28T01:11:20.328628Z","steps":["trace[204571576] 'agreement among raft nodes before linearized reading' (duration: 141.91379ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:20.330804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.310968ms","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-01-28T01:11:20.330887Z","caller":"traceutil/trace.go:171","msg":"trace[2081422234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"249.45096ms","start":"2026-01-28T01:11:20.081418Z","end":"2026-01-28T01:11:20.330869Z","steps":["trace[2081422234] 'agreement among raft nodes before linearized reading' (duration: 243.881218ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:23.1554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.851705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-01-28T01:11:23.155481Z","caller":"traceutil/trace.go:171","msg":"trace[79041591] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:612; }","duration":"135.970287ms","start":"2026-01-28T01:11:23.019495Z","end":"2026-01-28T01:11:23.155465Z","steps":["trace[79041591] 'range keys from in-memory index tree' (duration: 135.681242ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:30.250123Z","caller":"traceutil/trace.go:171","msg":"trace[175451296] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"202.2107ms","start":"2026-01-28T01:11:30.047895Z","end":"2026-01-28T01:11:30.250106Z","steps":["trace[175451296] 'process raft request' (duration: 202.066146ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.339841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.237578ms","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-01-28T01:11:31.339905Z","caller":"traceutil/trace.go:171","msg":"trace[1563754529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"870.32296ms","start":"2026-01-28T01:11:30.469563Z","end":"2026-01-28T01:11:31.339886Z","steps":["trace[1563754529] 'range keys from in-memory index tree' (duration: 870.131065ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.339931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.469559Z","time spent":"870.367781ms","remote":"127.0.0.1:38068","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-01-28T01:11:31.340236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.803777ms","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-01-28T01:11:31.340253Z","caller":"traceutil/trace.go:171","msg":"trace[911178291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"883.835138ms","start":"2026-01-28T01:11:30.456411Z","end":"2026-01-28T01:11:31.340246Z","steps":["trace[911178291] 'range keys from in-memory index tree' (duration: 883.714146ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.340265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.456397Z","time spent":"883.865549ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T01:11:31.340351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.950808ms","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-01-28T01:11:31.340363Z","caller":"traceutil/trace.go:171","msg":"trace[1153414390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"872.985769ms","start":"2026-01-28T01:11:30.467374Z","end":"2026-01-28T01:11:31.34036Z","steps":["trace[1153414390] 'range keys from in-memory index tree' (duration: 872.900856ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.340375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.46736Z","time spent":"873.012539ms","remote":"127.0.0.1:38068","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-01-28T01:11:31.340522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.442494ms","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-01-28T01:11:31.340599Z","caller":"traceutil/trace.go:171","msg":"trace[1450166350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"871.533116ms","start":"2026-01-28T01:11:30.469034Z","end":"2026-01-28T01:11:31.340567Z","steps":["trace[1450166350] 'range keys from in-memory index tree' (duration: 871.329852ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.343187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.469029Z","time spent":"874.145094ms","remote":"127.0.0.1:38068","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":"info","ts":"2026-01-28T01:11:31.917225Z","caller":"traceutil/trace.go:171","msg":"trace[1624538] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"571.90019ms","start":"2026-01-28T01:11:31.345305Z","end":"2026-01-28T01:11:31.917205Z","steps":["trace[1624538] 'process raft request' (duration: 571.765987ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.917389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:31.345293Z","time spent":"572.015762ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:31.962253Z","caller":"traceutil/trace.go:171","msg":"trace[1076757055] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"460.976715ms","start":"2026-01-28T01:11:31.501262Z","end":"2026-01-28T01:11:31.962238Z","steps":["trace[1076757055] 'read index received' (duration: 416.619377ms)","trace[1076757055] 'applied index is now lower than readState.Index' (duration: 44.356698ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:31.962282Z","caller":"traceutil/trace.go:171","msg":"trace[1471017301] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"616.880351ms","start":"2026-01-28T01:11:31.345373Z","end":"2026-01-28T01:11:31.962253Z","steps":["trace[1471017301] 'process raft request' (duration: 616.715198ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:31.962324Z","caller":"traceutil/trace.go:171","msg":"trace[491724521] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"616.917533ms","start":"2026-01-28T01:11:31.345401Z","end":"2026-01-28T01:11:31.962318Z","steps":["trace[491724521] 'process raft request' (duration: 616.80043ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.962389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:31.345369Z","time spent":"616.959583ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:31.962404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:31.345397Z","time spent":"616.952324ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:31.962488Z","caller":"traceutil/trace.go:171","msg":"trace[374488817] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"604.885447ms","start":"2026-01-28T01:11:31.357598Z","end":"2026-01-28T01:11:31.962484Z","steps":["trace[374488817] 'process raft request' (duration: 604.622082ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.962511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:31.35758Z","time spent":"604.917288ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:31.962575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.321292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:31.96259Z","caller":"traceutil/trace.go:171","msg":"trace[516753895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"461.363493ms","start":"2026-01-28T01:11:31.501222Z","end":"2026-01-28T01:11:31.962585Z","steps":["trace[516753895] 'agreement among raft nodes before linearized reading' (duration: 461.318942ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.962604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:31.501202Z","time spent":"461.399634ms","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T01:11:32.662596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.703457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:32.662696Z","caller":"traceutil/trace.go:171","msg":"trace[424987025] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"325.001486ms","start":"2026-01-28T01:11:32.337682Z","end":"2026-01-28T01:11:32.662684Z","steps":["trace[424987025] 'process raft request' (duration: 60.155916ms)","trace[424987025] 'compare' (duration: 264.505112ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:32.662733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:32.337617Z","time spent":"325.103068ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-28T01:11:40.437281Z","caller":"traceutil/trace.go:171","msg":"trace[1637503591] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"443.08644ms","start":"2026-01-28T01:11:39.994174Z","end":"2026-01-28T01:11:40.437261Z","steps":["trace[1637503591] 'read index received' (duration: 442.944267ms)","trace[1637503591] 'applied index is now lower than readState.Index' (duration: 141.393µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:40.437393Z","caller":"traceutil/trace.go:171","msg":"trace[1334689898] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"449.285307ms","start":"2026-01-28T01:11:39.988101Z","end":"2026-01-28T01:11:40.437387Z","steps":["trace[1334689898] 'process raft request' (duration: 449.038771ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:40.437498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:39.988093Z","time spent":"449.320047ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:40.4376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.648086ms","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-01-28T01:11:40.437656Z","caller":"traceutil/trace.go:171","msg":"trace[989449009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"440.722877ms","start":"2026-01-28T01:11:39.996922Z","end":"2026-01-28T01:11:40.437645Z","steps":["trace[989449009] 'agreement among raft nodes before linearized reading' (duration: 440.572204ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:40.437679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:39.996917Z","time spent":"440.756488ms","remote":"127.0.0.1:38068","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-01-28T01:11:40.437793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.614261ms","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-01-28T01:11:40.437818Z","caller":"traceutil/trace.go:171","msg":"trace[2124667510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"443.638712ms","start":"2026-01-28T01:11:39.994172Z","end":"2026-01-28T01:11:40.437811Z","steps":["trace[2124667510] 'agreement among raft nodes before linearized reading' (duration: 443.54955ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:40.437829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.795438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-01-28T01:11:40.437838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:39.994167Z","time spent":"443.666412ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-28T01:11:40.437846Z","caller":"traceutil/trace.go:171","msg":"trace[1792816348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"147.819158ms","start":"2026-01-28T01:11:40.290021Z","end":"2026-01-28T01:11:40.43784Z","steps":["trace[1792816348] 'agreement among raft nodes before linearized reading' (duration: 147.767707ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:42.217716Z","caller":"traceutil/trace.go:171","msg":"trace[215523509] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"186.689367ms","start":"2026-01-28T01:11:42.031011Z","end":"2026-01-28T01:11:42.2177Z","steps":["trace[215523509] 'process raft request' (duration: 186.646826ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:42.217747Z","caller":"traceutil/trace.go:171","msg":"trace[1719846055] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"189.631101ms","start":"2026-01-28T01:11:42.028091Z","end":"2026-01-28T01:11:42.217722Z","steps":["trace[1719846055] 'process raft request' (duration: 105.797733ms)","trace[1719846055] 'compare' (duration: 83.672905ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:56.423057Z","caller":"traceutil/trace.go:171","msg":"trace[1768289338] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"136.205984ms","start":"2026-01-28T01:11:56.286833Z","end":"2026-01-28T01:11:56.423039Z","steps":["trace[1768289338] 'process raft request' (duration: 136.082521ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.786034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.587002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-28T01:11:57.786158Z","caller":"traceutil/trace.go:171","msg":"trace[1062675814] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"305.413874ms","start":"2026-01-28T01:11:57.480711Z","end":"2026-01-28T01:11:57.786125Z","steps":["trace[1062675814] 'read index received' (duration: 168.668099ms)","trace[1062675814] 'applied index is now lower than readState.Index' (duration: 136.743935ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:57.787076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.345604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:57.787117Z","caller":"traceutil/trace.go:171","msg":"trace[1664357443] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:788; }","duration":"306.464607ms","start":"2026-01-28T01:11:57.480642Z","end":"2026-01-28T01:11:57.787107Z","steps":["trace[1664357443] 'agreement among raft nodes before linearized reading' (duration: 306.367604ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.78715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:57.480622Z","time spent":"306.518298ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:11:57.787354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.077719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:57.787384Z","caller":"traceutil/trace.go:171","msg":"trace[897423816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"286.142ms","start":"2026-01-28T01:11:57.501231Z","end":"2026-01-28T01:11:57.787373Z","steps":["trace[897423816] 'agreement among raft nodes before linearized reading' (duration: 286.097359ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:58.588721Z","caller":"traceutil/trace.go:171","msg":"trace[1937861882] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"157.709728ms","start":"2026-01-28T01:11:58.430991Z","end":"2026-01-28T01:11:58.5887Z","steps":["trace[1937861882] 'process raft request' (duration: 157.343859ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:01.240696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997599755867671984,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-28T01:12:01.360138Z","caller":"traceutil/trace.go:171","msg":"trace[1780462499] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"661.72823ms","start":"2026-01-28T01:12:00.698381Z","end":"2026-01-28T01:12:01.36011Z","steps":["trace[1780462499] 'process raft request' (duration: 642.719846ms)","trace[1780462499] 'compare' (duration: 18.568725ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:01.360287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:00.698372Z","time spent":"661.826532ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:01.367067Z","caller":"traceutil/trace.go:171","msg":"trace[722600347] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"627.39564ms","start":"2026-01-28T01:12:00.739651Z","end":"2026-01-28T01:12:01.367046Z","steps":["trace[722600347] 'read index received' (duration: 601.453689ms)","trace[722600347] 'applied index is now lower than readState.Index' (duration: 25.940921ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:01.36719Z","caller":"traceutil/trace.go:171","msg":"trace[1646332720] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"666.985292ms","start":"2026-01-28T01:12:00.700197Z","end":"2026-01-28T01:12:01.367182Z","steps":["trace[1646332720] 'process raft request' (duration: 666.656965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:01.367289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:00.700189Z","time spent":"667.024322ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:01.367447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.90981ms","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-01-28T01:12:01.367582Z","caller":"traceutil/trace.go:171","msg":"trace[2110655686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"627.064904ms","start":"2026-01-28T01:12:00.740507Z","end":"2026-01-28T01:12:01.367572Z","steps":["trace[2110655686] 'agreement among raft nodes before linearized reading' (duration: 626.856379ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:01.367666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:00.740498Z","time spent":"627.157735ms","remote":"127.0.0.1:38068","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-01-28T01:12:01.367849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.194978ms","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-01-28T01:12:01.368816Z","caller":"traceutil/trace.go:171","msg":"trace[431268287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"629.159788ms","start":"2026-01-28T01:12:00.739647Z","end":"2026-01-28T01:12:01.368806Z","steps":["trace[431268287] 'agreement among raft nodes before linearized reading' (duration: 628.155518ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:01.368924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:00.739605Z","time spent":"629.28305ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:12:12.212108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:11.881958Z","time spent":"330.14662ms","remote":"127.0.0.1:37828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-28T01:12:13.834333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.603473ms","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-01-28T01:12:13.834403Z","caller":"traceutil/trace.go:171","msg":"trace[983901681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"427.728735ms","start":"2026-01-28T01:12:13.406664Z","end":"2026-01-28T01:12:13.834393Z","steps":["trace[983901681] 'range keys from in-memory index tree' (duration: 427.46718ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:13.834428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.406587Z","time spent":"427.833028ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T01:12:13.83454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.104018ms","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-01-28T01:12:13.834554Z","caller":"traceutil/trace.go:171","msg":"trace[1387356306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"353.138639ms","start":"2026-01-28T01:12:13.48141Z","end":"2026-01-28T01:12:13.834548Z","steps":["trace[1387356306] 'range keys from in-memory index tree' (duration: 353.026747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:13.834568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.481397Z","time spent":"353.16818ms","remote":"127.0.0.1:38068","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-01-28T01:12:13.834656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.781979ms","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-01-28T01:12:13.834669Z","caller":"traceutil/trace.go:171","msg":"trace[1291562422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"350.83221ms","start":"2026-01-28T01:12:13.483832Z","end":"2026-01-28T01:12:13.834665Z","steps":["trace[1291562422] 'range keys from in-memory index tree' (duration: 350.702127ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:13.83468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.483827Z","time spent":"350.85086ms","remote":"127.0.0.1:38068","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-01-28T01:12:13.834749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.742338ms","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-01-28T01:12:13.83476Z","caller":"traceutil/trace.go:171","msg":"trace[1320085794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:881; }","duration":"342.754088ms","start":"2026-01-28T01:12:13.492003Z","end":"2026-01-28T01:12:13.834757Z","steps":["trace[1320085794] 'range keys from in-memory index tree' (duration: 342.699986ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:13.834774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.491987Z","time spent":"342.784858ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:12:13.834824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.213036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:13.834836Z","caller":"traceutil/trace.go:171","msg":"trace[110941593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"334.249198ms","start":"2026-01-28T01:12:13.500582Z","end":"2026-01-28T01:12:13.834831Z","steps":["trace[110941593] 'range keys from in-memory index tree' (duration: 334.171625ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:13.834846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.500566Z","time spent":"334.277578ms","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-28T01:12:14.250687Z","caller":"traceutil/trace.go:171","msg":"trace[1682585136] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"410.004158ms","start":"2026-01-28T01:12:13.840621Z","end":"2026-01-28T01:12:14.250625Z","steps":["trace[1682585136] 'process raft request' (duration: 409.876845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:14.250872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.840608Z","time spent":"410.186322ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:14.884027Z","caller":"traceutil/trace.go:171","msg":"trace[278526465] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:912; }","duration":"309.590263ms","start":"2026-01-28T01:12:14.574422Z","end":"2026-01-28T01:12:14.884013Z","steps":["trace[278526465] 'read index received' (duration: 309.283456ms)","trace[278526465] 'applied index is now lower than readState.Index' (duration: 306.277µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:14.884197Z","caller":"traceutil/trace.go:171","msg":"trace[933299975] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"1.043328075s","start":"2026-01-28T01:12:13.840863Z","end":"2026-01-28T01:12:14.884191Z","steps":["trace[933299975] 'process raft request' (duration: 1.043032258s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:14.88426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.840858Z","time spent":"1.043358535s","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:14.884357Z","caller":"traceutil/trace.go:171","msg":"trace[2039442400] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"1.043462088s","start":"2026-01-28T01:12:13.84089Z","end":"2026-01-28T01:12:14.884352Z","steps":["trace[2039442400] 'process raft request' (duration: 1.04308585s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:14.884389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.840887Z","time spent":"1.043479799s","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:14.884448Z","caller":"traceutil/trace.go:171","msg":"trace[394215382] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"1.04072809s","start":"2026-01-28T01:12:13.843716Z","end":"2026-01-28T01:12:14.884444Z","steps":["trace[394215382] 'process raft request' (duration: 1.040276991s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:14.88447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:13.843698Z","time spent":"1.04075982s","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:14.884536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.114514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:14.884554Z","caller":"traceutil/trace.go:171","msg":"trace[306999356] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:885; }","duration":"310.171545ms","start":"2026-01-28T01:12:14.574376Z","end":"2026-01-28T01:12:14.884547Z","steps":["trace[306999356] 'agreement among raft nodes before linearized reading' (duration: 310.141965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:14.884567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:14.57436Z","time spent":"310.203696ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:12:14.884684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.93106ms","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-01-28T01:12:14.884701Z","caller":"traceutil/trace.go:171","msg":"trace[747200815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"119.982771ms","start":"2026-01-28T01:12:14.764714Z","end":"2026-01-28T01:12:14.884697Z","steps":["trace[747200815] 'agreement among raft nodes before linearized reading' (duration: 119.92051ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:15.323053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.43983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:12:15.323176Z","caller":"traceutil/trace.go:171","msg":"trace[70435176] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"378.338505ms","start":"2026-01-28T01:12:14.944828Z","end":"2026-01-28T01:12:15.323167Z","steps":["trace[70435176] 'process raft request' (duration: 378.307315ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:15.323218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:14.944809Z","time spent":"378.384766ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:15.323384Z","caller":"traceutil/trace.go:171","msg":"trace[1550439802] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"434.648192ms","start":"2026-01-28T01:12:14.888729Z","end":"2026-01-28T01:12:15.323377Z","steps":["trace[1550439802] 'process raft request' (duration: 330.832184ms)","trace[1550439802] 'compare' (duration: 103.378499ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:15.32341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:14.888719Z","time spent":"434.677302ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:15.628862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.158344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:15.629144Z","caller":"traceutil/trace.go:171","msg":"trace[680970771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"127.475231ms","start":"2026-01-28T01:12:15.50165Z","end":"2026-01-28T01:12:15.629126Z","steps":["trace[680970771] 'range keys from in-memory index tree' (duration: 127.103262ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:23.23767Z","caller":"traceutil/trace.go:171","msg":"trace[691193142] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"236.421468ms","start":"2026-01-28T01:12:23.00123Z","end":"2026-01-28T01:12:23.237651Z","steps":["trace[691193142] 'process raft request' (duration: 236.376786ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:23.237965Z","caller":"traceutil/trace.go:171","msg":"trace[968642490] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"244.139632ms","start":"2026-01-28T01:12:22.993815Z","end":"2026-01-28T01:12:23.237955Z","steps":["trace[968642490] 'process raft request' (duration: 243.1489ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:23.945419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.372549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:12:23.945478Z","caller":"traceutil/trace.go:171","msg":"trace[1636651452] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"571.493031ms","start":"2026-01-28T01:12:23.373976Z","end":"2026-01-28T01:12:23.945469Z","steps":["trace[1636651452] 'read index received' (duration: 355.988519ms)","trace[1636651452] 'applied index is now lower than readState.Index' (duration: 215.503942ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:23.945526Z","caller":"traceutil/trace.go:171","msg":"trace[1145254922] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"677.700401ms","start":"2026-01-28T01:12:23.267821Z","end":"2026-01-28T01:12:23.945521Z","steps":["trace[1145254922] 'process raft request' (duration: 462.175608ms)","trace[1145254922] 'compare' (duration: 215.224026ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:23.945553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:23.267803Z","time spent":"677.735332ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:23.945719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.742527ms","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-01-28T01:12:23.945733Z","caller":"traceutil/trace.go:171","msg":"trace[291720] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:908; }","duration":"571.797688ms","start":"2026-01-28T01:12:23.373931Z","end":"2026-01-28T01:12:23.945729Z","steps":["trace[291720] 'agreement among raft nodes before linearized reading' (duration: 571.768777ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:23.945747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:23.373909Z","time spent":"571.834099ms","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:12:23.945723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.823567ms","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-01-28T01:12:23.945792Z","caller":"traceutil/trace.go:171","msg":"trace[338433578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"507.91573ms","start":"2026-01-28T01:12:23.43786Z","end":"2026-01-28T01:12:23.945775Z","steps":["trace[338433578] 'agreement among raft nodes before linearized reading' (duration: 507.747286ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:23.945837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:23.43785Z","time spent":"507.976301ms","remote":"127.0.0.1:38068","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-01-28T01:12:23.945882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.317724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:23.945897Z","caller":"traceutil/trace.go:171","msg":"trace[1569247656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"228.354214ms","start":"2026-01-28T01:12:23.717539Z","end":"2026-01-28T01:12:23.945893Z","steps":["trace[1569247656] 'agreement among raft nodes before linearized reading' (duration: 228.331344ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:24.208908Z","caller":"traceutil/trace.go:171","msg":"trace[1823447697] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"258.402204ms","start":"2026-01-28T01:12:23.950485Z","end":"2026-01-28T01:12:24.208887Z","steps":["trace[1823447697] 'process raft request' (duration: 253.879089ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:24.630331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.869546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:24.630408Z","caller":"traceutil/trace.go:171","msg":"trace[1623665162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"132.978718ms","start":"2026-01-28T01:12:24.497418Z","end":"2026-01-28T01:12:24.630396Z","steps":["trace[1623665162] 'range keys from in-memory index tree' (duration: 132.831085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:25.775913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.163955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:25.775957Z","caller":"traceutil/trace.go:171","msg":"trace[354564809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"278.214686ms","start":"2026-01-28T01:12:25.497732Z","end":"2026-01-28T01:12:25.775946Z","steps":["trace[354564809] 'range keys from in-memory index tree' (duration: 278.090604ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:25.776627Z","caller":"traceutil/trace.go:171","msg":"trace[1349702239] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"161.475154ms","start":"2026-01-28T01:12:25.615145Z","end":"2026-01-28T01:12:25.77662Z","steps":["trace[1349702239] 'process raft request' (duration: 161.272739ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:29.622897Z","caller":"traceutil/trace.go:171","msg":"trace[322038476] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"311.35783ms","start":"2026-01-28T01:12:29.311522Z","end":"2026-01-28T01:12:29.62288Z","steps":["trace[322038476] 'read index received' (duration: 311.220807ms)","trace[322038476] 'applied index is now lower than readState.Index' (duration: 136.343µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:29.623161Z","caller":"traceutil/trace.go:171","msg":"trace[800791743] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"545.942229ms","start":"2026-01-28T01:12:29.07721Z","end":"2026-01-28T01:12:29.623152Z","steps":["trace[800791743] 'process raft request' (duration: 545.575361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:29.623252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:29.077201Z","time spent":"545.98785ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:29.623433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.912732ms","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-01-28T01:12:29.62346Z","caller":"traceutil/trace.go:171","msg":"trace[1475257783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"311.956863ms","start":"2026-01-28T01:12:29.311497Z","end":"2026-01-28T01:12:29.623453Z","steps":["trace[1475257783] 'agreement among raft nodes before linearized reading' (duration: 311.869182ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:29.623484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:29.311482Z","time spent":"311.997244ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:12:29.623612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.408699ms","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-01-28T01:12:29.623636Z","caller":"traceutil/trace.go:171","msg":"trace[1633612984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"301.45613ms","start":"2026-01-28T01:12:29.322174Z","end":"2026-01-28T01:12:29.62363Z","steps":["trace[1633612984] 'agreement among raft nodes before linearized reading' (duration: 301.402229ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:29.623672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:29.322158Z","time spent":"301.510511ms","remote":"127.0.0.1:38068","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-01-28T01:12:29.62381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.733144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:29.623832Z","caller":"traceutil/trace.go:171","msg":"trace[2062970822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"125.771514ms","start":"2026-01-28T01:12:29.498055Z","end":"2026-01-28T01:12:29.623827Z","steps":["trace[2062970822] 'agreement among raft nodes before linearized reading' (duration: 125.725063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:30.752323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.147324ms","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-01-28T01:12:30.752472Z","caller":"traceutil/trace.go:171","msg":"trace[1036438316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"365.259967ms","start":"2026-01-28T01:12:30.387116Z","end":"2026-01-28T01:12:30.752376Z","steps":["trace[1036438316] 'range keys from in-memory index tree' (duration: 364.988541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:30.752538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:30.387102Z","time spent":"365.42332ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T01:12:30.753366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.139223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:30.753421Z","caller":"traceutil/trace.go:171","msg":"trace[1566378170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"254.208145ms","start":"2026-01-28T01:12:30.499195Z","end":"2026-01-28T01:12:30.753403Z","steps":["trace[1566378170] 'range keys from in-memory index tree' (duration: 254.100793ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:37.870158Z","caller":"traceutil/trace.go:171","msg":"trace[1011740074] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"166.252695ms","start":"2026-01-28T01:12:37.703883Z","end":"2026-01-28T01:12:37.870136Z","steps":["trace[1011740074] 'read index received' (duration: 166.0484ms)","trace[1011740074] 'applied index is now lower than readState.Index' (duration: 203.195µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:37.870429Z","caller":"traceutil/trace.go:171","msg":"trace[473592548] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"196.477497ms","start":"2026-01-28T01:12:37.67394Z","end":"2026-01-28T01:12:37.870418Z","steps":["trace[473592548] 'process raft request' (duration: 196.051709ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:37.870718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.821217ms","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-01-28T01:12:37.870757Z","caller":"traceutil/trace.go:171","msg":"trace[328039500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"166.899049ms","start":"2026-01-28T01:12:37.703844Z","end":"2026-01-28T01:12:37.870743Z","steps":["trace[328039500] 'agreement among raft nodes before linearized reading' (duration: 166.730936ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:37.870959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.948949ms","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-01-28T01:12:37.870996Z","caller":"traceutil/trace.go:171","msg":"trace[1731130901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"142.00711ms","start":"2026-01-28T01:12:37.728979Z","end":"2026-01-28T01:12:37.870986Z","steps":["trace[1731130901] 'agreement among raft nodes before linearized reading' (duration: 141.920139ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:38.253033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:37.889624Z","time spent":"363.404088ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-28T01:12:38.254353Z","caller":"traceutil/trace.go:171","msg":"trace[1404712722] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"368.217669ms","start":"2026-01-28T01:12:37.886121Z","end":"2026-01-28T01:12:38.254338Z","steps":["trace[1404712722] 'process raft request' (duration: 359.598626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:38.254431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:37.886105Z","time spent":"368.279281ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:38.727828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.074577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:12:38.72789Z","caller":"traceutil/trace.go:171","msg":"trace[1989872958] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"840.153665ms","start":"2026-01-28T01:12:37.887724Z","end":"2026-01-28T01:12:38.727877Z","steps":["trace[1989872958] 'process raft request' (duration: 716.976186ms)","trace[1989872958] 'compare' (duration: 122.957745ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:38.727922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:37.887715Z","time spent":"840.191406ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:38.734109Z","caller":"traceutil/trace.go:171","msg":"trace[437067426] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"381.822259ms","start":"2026-01-28T01:12:38.352266Z","end":"2026-01-28T01:12:38.734088Z","steps":["trace[437067426] 'read index received' (duration: 252.440888ms)","trace[437067426] 'applied index is now lower than readState.Index' (duration: 129.380491ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:38.734235Z","caller":"traceutil/trace.go:171","msg":"trace[1808243084] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"467.592003ms","start":"2026-01-28T01:12:38.266634Z","end":"2026-01-28T01:12:38.734226Z","steps":["trace[1808243084] 'process raft request' (duration: 467.343518ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:38.734321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:38.266616Z","time spent":"467.644503ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-28T01:12:38.734689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.412021ms","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-01-28T01:12:38.734728Z","caller":"traceutil/trace.go:171","msg":"trace[124626493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"382.477022ms","start":"2026-01-28T01:12:38.352241Z","end":"2026-01-28T01:12:38.734718Z","steps":["trace[124626493] 'agreement among raft nodes before linearized reading' (duration: 382.270818ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:38.734756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:38.352229Z","time spent":"382.521733ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-28T01:12:38.734925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.765212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:38.734959Z","caller":"traceutil/trace.go:171","msg":"trace[1706067605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"234.831644ms","start":"2026-01-28T01:12:38.500119Z","end":"2026-01-28T01:12:38.734951Z","steps":["trace[1706067605] 'agreement among raft nodes before linearized reading' (duration: 234.783763ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:39.143685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.920328ms","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-01-28T01:12:39.143738Z","caller":"traceutil/trace.go:171","msg":"trace[1031353782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"277.005549ms","start":"2026-01-28T01:12:38.866718Z","end":"2026-01-28T01:12:39.143723Z","steps":["trace[1031353782] 'range keys from in-memory index tree' (duration: 276.758474ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:41.268471Z","caller":"traceutil/trace.go:171","msg":"trace[1515446528] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"109.469827ms","start":"2026-01-28T01:12:41.158984Z","end":"2026-01-28T01:12:41.268453Z","steps":["trace[1515446528] 'process raft request' (duration: 109.363605ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:43.166897Z","caller":"traceutil/trace.go:171","msg":"trace[438979157] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"421.729828ms","start":"2026-01-28T01:12:42.745146Z","end":"2026-01-28T01:12:43.166875Z","steps":["trace[438979157] 'read index received' (duration: 421.550764ms)","trace[438979157] 'applied index is now lower than readState.Index' (duration: 178.054µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:43.167181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.016134ms","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-01-28T01:12:43.167239Z","caller":"traceutil/trace.go:171","msg":"trace[334960160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"422.110265ms","start":"2026-01-28T01:12:42.745114Z","end":"2026-01-28T01:12:43.167225Z","steps":["trace[334960160] 'agreement among raft nodes before linearized reading' (duration: 421.949222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:43.167264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:42.745099Z","time spent":"422.160867ms","remote":"127.0.0.1:38068","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-01-28T01:12:43.167331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.50672ms","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-01-28T01:12:43.16737Z","caller":"traceutil/trace.go:171","msg":"trace[1360767605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"363.582561ms","start":"2026-01-28T01:12:42.803777Z","end":"2026-01-28T01:12:43.167359Z","steps":["trace[1360767605] 'agreement among raft nodes before linearized reading' (duration: 363.444918ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:43.167411Z","caller":"traceutil/trace.go:171","msg":"trace[75594726] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"897.816251ms","start":"2026-01-28T01:12:42.269588Z","end":"2026-01-28T01:12:43.167404Z","steps":["trace[75594726] 'process raft request' (duration: 897.165357ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:43.167406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:42.803765Z","time spent":"363.633413ms","remote":"127.0.0.1:38068","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-01-28T01:12:43.167461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:42.269579Z","time spent":"897.847091ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:44.029713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.749724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:12:44.029841Z","caller":"traceutil/trace.go:171","msg":"trace[2012240632] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"839.563993ms","start":"2026-01-28T01:12:43.190253Z","end":"2026-01-28T01:12:44.029817Z","steps":["trace[2012240632] 'process raft request' (duration: 618.605635ms)","trace[2012240632] 'compare' (duration: 220.389965ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:44.029932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:43.190245Z","time spent":"839.633995ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:44.918773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.959467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:12:44.918955Z","caller":"traceutil/trace.go:171","msg":"trace[86257031] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"446.062185ms","start":"2026-01-28T01:12:44.472878Z","end":"2026-01-28T01:12:44.91894Z","steps":["trace[86257031] 'process raft request' (duration: 445.982643ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.919016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.472851Z","time spent":"446.134936ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:44.919308Z","caller":"traceutil/trace.go:171","msg":"trace[1002441351] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.729256811s","start":"2026-01-28T01:12:43.190036Z","end":"2026-01-28T01:12:44.919293Z","steps":["trace[1002441351] 'process raft request' (duration: 954.710372ms)","trace[1002441351] 'compare' (duration: 773.426666ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:44.919364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:43.190017Z","time spent":"1.729319162s","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:44.91949Z","caller":"traceutil/trace.go:171","msg":"trace[1804069503] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1020; }","duration":"1.607651304s","start":"2026-01-28T01:12:43.311828Z","end":"2026-01-28T01:12:44.919479Z","steps":["trace[1804069503] 'read index received' (duration: 497.114341ms)","trace[1804069503] 'applied index is now lower than readState.Index' (duration: 1.110536023s)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:44.919747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607919001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-28T01:12:44.919777Z","caller":"traceutil/trace.go:171","msg":"trace[1656182952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"1.607987611s","start":"2026-01-28T01:12:43.31178Z","end":"2026-01-28T01:12:44.919767Z","steps":["trace[1656182952] 'agreement among raft nodes before linearized reading' (duration: 1.607879119s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.919806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:43.311762Z","time spent":"1.608037523s","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T01:12:44.919948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418731417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:44.919976Z","caller":"traceutil/trace.go:171","msg":"trace[1124945360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"1.418779088s","start":"2026-01-28T01:12:43.501189Z","end":"2026-01-28T01:12:44.919968Z","steps":["trace[1124945360] 'agreement among raft nodes before linearized reading' (duration: 1.418737478s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.92Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:43.501172Z","time spent":"1.418823919s","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T01:12:44.920263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.35139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:44.920295Z","caller":"traceutil/trace.go:171","msg":"trace[1658752955] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:987; }","duration":"564.397381ms","start":"2026-01-28T01:12:44.355889Z","end":"2026-01-28T01:12:44.920286Z","steps":["trace[1658752955] 'agreement among raft nodes before linearized reading' (duration: 564.34722ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.920324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.355884Z","time spent":"564.432303ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:12:44.9205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.025981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-01-28T01:12:44.920528Z","caller":"traceutil/trace.go:171","msg":"trace[2041963742] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:987; }","duration":"618.052122ms","start":"2026-01-28T01:12:44.302467Z","end":"2026-01-28T01:12:44.920519Z","steps":["trace[2041963742] 'agreement among raft nodes before linearized reading' (duration: 617.96531ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.920554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.302431Z","time spent":"618.116884ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-28T01:12:44.920712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.402967ms","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-01-28T01:12:44.920741Z","caller":"traceutil/trace.go:171","msg":"trace[1641955877] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:987; }","duration":"829.468147ms","start":"2026-01-28T01:12:44.091265Z","end":"2026-01-28T01:12:44.920733Z","steps":["trace[1641955877] 'agreement among raft nodes before linearized reading' (duration: 829.423397ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.920767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.091246Z","time spent":"829.514429ms","remote":"127.0.0.1:38294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:12:44.920928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.986967ms","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-01-28T01:12:44.920954Z","caller":"traceutil/trace.go:171","msg":"trace[1172074911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"987.044499ms","start":"2026-01-28T01:12:43.933902Z","end":"2026-01-28T01:12:44.920946Z","steps":["trace[1172074911] 'agreement among raft nodes before linearized reading' (duration: 986.971947ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:44.920979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:43.933883Z","time spent":"987.090289ms","remote":"127.0.0.1:38068","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-01-28T01:12:45.269139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.244688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:12:45.269371Z","caller":"traceutil/trace.go:171","msg":"trace[527847302] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1023; }","duration":"335.572626ms","start":"2026-01-28T01:12:44.933779Z","end":"2026-01-28T01:12:45.269352Z","steps":["trace[527847302] 'read index received' (duration: 230.84184ms)","trace[527847302] 'applied index is now lower than readState.Index' (duration: 104.729816ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:12:45.269567Z","caller":"traceutil/trace.go:171","msg":"trace[260591379] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"340.766095ms","start":"2026-01-28T01:12:44.928793Z","end":"2026-01-28T01:12:45.269559Z","steps":["trace[260591379] 'process raft request' (duration: 233.998316ms)","trace[260591379] 'compare' (duration: 106.083205ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:12:45.26961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.92877Z","time spent":"340.805626ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:12:45.26972Z","caller":"traceutil/trace.go:171","msg":"trace[1858895111] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"340.619522ms","start":"2026-01-28T01:12:44.929095Z","end":"2026-01-28T01:12:45.269714Z","steps":["trace[1858895111] 'process raft request' (duration: 340.159693ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:45.269753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.929074Z","time spent":"340.660033ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:45.27002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.238469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:12:45.270041Z","caller":"traceutil/trace.go:171","msg":"trace[1412952547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"336.27593ms","start":"2026-01-28T01:12:44.933758Z","end":"2026-01-28T01:12:45.270034Z","steps":["trace[1412952547] 'agreement among raft nodes before linearized reading' (duration: 336.241189ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:45.270059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.93375Z","time spent":"336.305421ms","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-28T01:12:45.270148Z","caller":"traceutil/trace.go:171","msg":"trace[948634166] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"338.876475ms","start":"2026-01-28T01:12:44.931265Z","end":"2026-01-28T01:12:45.270142Z","steps":["trace[948634166] 'process raft request' (duration: 338.040398ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:12:45.27018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:12:44.931255Z","time spent":"338.905337ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:12:45.269863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.354793ms","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-01-28T01:12:45.274156Z","caller":"traceutil/trace.go:171","msg":"trace[766156733] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:990; }","duration":"255.480162ms","start":"2026-01-28T01:12:45.018425Z","end":"2026-01-28T01:12:45.273906Z","steps":["trace[766156733] 'agreement among raft nodes before linearized reading' (duration: 251.331503ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:12:50.245861Z","caller":"traceutil/trace.go:171","msg":"trace[650225912] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"189.231154ms","start":"2026-01-28T01:12:50.056612Z","end":"2026-01-28T01:12:50.245843Z","steps":["trace[650225912] 'process raft request' (duration: 189.116562ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:16:00.310404Z","caller":"traceutil/trace.go:171","msg":"trace[2124603283] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"197.010669ms","start":"2026-01-28T01:16:00.113374Z","end":"2026-01-28T01:16:00.310385Z","steps":["trace[2124603283] 'read index received' (duration: 196.829715ms)","trace[2124603283] 'applied index is now lower than readState.Index' (duration: 178.724µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:16:00.31095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.54364ms","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-01-28T01:16:00.311028Z","caller":"traceutil/trace.go:171","msg":"trace[1749317240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"197.673074ms","start":"2026-01-28T01:16:00.113337Z","end":"2026-01-28T01:16:00.31101Z","steps":["trace[1749317240] 'agreement among raft nodes before linearized reading' (duration: 197.347916ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:16:00.31127Z","caller":"traceutil/trace.go:171","msg":"trace[323979464] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"228.704942ms","start":"2026-01-28T01:16:00.082553Z","end":"2026-01-28T01:16:00.311258Z","steps":["trace[323979464] 'process raft request' (duration: 227.70468ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:16:00.311648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.029254ms","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-01-28T01:16:00.311679Z","caller":"traceutil/trace.go:171","msg":"trace[1565192518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1843; }","duration":"150.108035ms","start":"2026-01-28T01:16:00.161561Z","end":"2026-01-28T01:16:00.31167Z","steps":["trace[1565192518] 'agreement among raft nodes before linearized reading' (duration: 150.016323ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:16:00.631715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.080671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:16:00.631794Z","caller":"traceutil/trace.go:171","msg":"trace[1940025777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"133.218665ms","start":"2026-01-28T01:16:00.498559Z","end":"2026-01-28T01:16:00.631777Z","steps":["trace[1940025777] 'range keys from in-memory index tree' (duration: 132.887618ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:16:00.788516Z","caller":"traceutil/trace.go:171","msg":"trace[797444173] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"116.812435ms","start":"2026-01-28T01:16:00.671686Z","end":"2026-01-28T01:16:00.788499Z","steps":["trace[797444173] 'process raft request' (duration: 116.704183ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:16:01.160059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.892953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-01-28T01:16:01.160118Z","caller":"traceutil/trace.go:171","msg":"trace[244898214] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1846; }","duration":"267.955104ms","start":"2026-01-28T01:16:00.892146Z","end":"2026-01-28T01:16:01.160102Z","steps":["trace[244898214] 'range keys from in-memory index tree' (duration: 267.79055ms)"],"step_count":1}