{"level":"warn","ts":"2026-05-30T01:53:29.203121Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T01:53:29.20323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-05-30T01:53:29.20332Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T01:53:29.203339Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-30T01:53:29.203366Z","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-05-30T01:53:29.204153Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-05-30T01:53:29.204253Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-05-30T01:53:30.399255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.194738283s"} {"level":"info","ts":"2026-05-30T01:53:30.863598Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-05-30T01:53:30.863733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T01:53:30.863761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-05-30T01:53:30.863781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T01:53:30.863797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-05-30T01:53:30.863861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-05-30T01:53:30.868314Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T01:53:30.869802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T01:53:30.873135Z","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-05-30T01:53:30.876462Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T01:53:30.876664Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T01:53:30.877479Z","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-05-30T01:53:30.877642Z","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-05-30T01:53:30.877681Z","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-05-30T01:53:30.87864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-05-30T01:53:30.87891Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-30T01:53:30.882038Z","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-05-30T01:53:30.882132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-30T01:53:30.882187Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-30T01:53:30.882316Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T01:53:30.882349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T01:53:31.664387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T01:53:31.664459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T01:53:31.664482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-05-30T01:53:31.6645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-05-30T01:53:31.664512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-30T01:53:31.664534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-05-30T01:53:31.664561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-30T01:53:31.666959Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T01:53:31.666966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:53:31.666995Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:53:31.667082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:53:31.667332Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T01:53:31.667353Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T01:53:31.668479Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:53:31.669467Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:53:31.669512Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:53:31.670419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-05-30T01:53:31.672556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T01:53:42.819074Z","caller":"traceutil/trace.go:171","msg":"trace[672202690] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"153.666037ms","start":"2026-05-30T01:53:42.665385Z","end":"2026-05-30T01:53:42.819051Z","steps":["trace[672202690] 'process raft request' (duration: 149.229605ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:42.879Z","caller":"traceutil/trace.go:171","msg":"trace[649435208] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"166.665925ms","start":"2026-05-30T01:53:42.712298Z","end":"2026-05-30T01:53:42.878964Z","steps":["trace[649435208] 'process raft request' (duration: 166.564443ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:42.879051Z","caller":"traceutil/trace.go:171","msg":"trace[1209472658] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"166.965343ms","start":"2026-05-30T01:53:42.712057Z","end":"2026-05-30T01:53:42.879022Z","steps":["trace[1209472658] 'process raft request' (duration: 166.665126ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:42.879323Z","caller":"traceutil/trace.go:171","msg":"trace[62672832] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"162.747115ms","start":"2026-05-30T01:53:42.716541Z","end":"2026-05-30T01:53:42.879288Z","steps":["trace[62672832] 'process raft request' (duration: 162.433658ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:42.879602Z","caller":"traceutil/trace.go:171","msg":"trace[41274683] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"166.785347ms","start":"2026-05-30T01:53:42.712463Z","end":"2026-05-30T01:53:42.879248Z","steps":["trace[41274683] 'process raft request' (duration: 166.435659ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:42.879642Z","caller":"traceutil/trace.go:171","msg":"trace[1789291917] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"166.639755ms","start":"2026-05-30T01:53:42.712588Z","end":"2026-05-30T01:53:42.879227Z","steps":["trace[1789291917] 'process raft request' (duration: 166.357089ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:43.00638Z","caller":"traceutil/trace.go:171","msg":"trace[138543446] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"120.949777ms","start":"2026-05-30T01:53:42.885412Z","end":"2026-05-30T01:53:43.006362Z","steps":["trace[138543446] 'process raft request' (duration: 120.67424ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:43.006424Z","caller":"traceutil/trace.go:171","msg":"trace[140196020] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"116.29205ms","start":"2026-05-30T01:53:42.890108Z","end":"2026-05-30T01:53:43.0064Z","steps":["trace[140196020] 'process raft request' (duration: 116.168496ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:43.006675Z","caller":"traceutil/trace.go:171","msg":"trace[1782889002] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"116.383831ms","start":"2026-05-30T01:53:42.890271Z","end":"2026-05-30T01:53:43.006654Z","steps":["trace[1782889002] 'process raft request' (duration: 116.064804ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:43.00687Z","caller":"traceutil/trace.go:171","msg":"trace[1765351430] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"115.753768ms","start":"2026-05-30T01:53:42.89111Z","end":"2026-05-30T01:53:43.006863Z","steps":["trace[1765351430] 'process raft request' (duration: 115.710407ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:43.006966Z","caller":"traceutil/trace.go:171","msg":"trace[93448587] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"115.938511ms","start":"2026-05-30T01:53:42.891023Z","end":"2026-05-30T01:53:43.006961Z","steps":["trace[93448587] 'process raft request' (duration: 115.573873ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:53:43.130593Z","caller":"traceutil/trace.go:171","msg":"trace[415574638] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"108.02505ms","start":"2026-05-30T01:53:43.022535Z","end":"2026-05-30T01:53:43.13056Z","steps":["trace[415574638] 'process raft request' (duration: 68.178616ms)","trace[415574638] 'compare' (duration: 39.691821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:53:43.384499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.64623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-30T01:53:43.38458Z","caller":"traceutil/trace.go:171","msg":"trace[138710501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"134.799554ms","start":"2026-05-30T01:53:43.249763Z","end":"2026-05-30T01:53:43.384563Z","steps":["trace[138710501] 'range keys from in-memory index tree' (duration: 134.432236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:53:43.384661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.599283ms","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-05-30T01:53:43.384778Z","caller":"traceutil/trace.go:171","msg":"trace[2079161005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:360; }","duration":"101.775747ms","start":"2026-05-30T01:53:43.282977Z","end":"2026-05-30T01:53:43.384752Z","steps":["trace[2079161005] 'range keys from in-memory index tree' (duration: 101.4538ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:07.179737Z","caller":"traceutil/trace.go:171","msg":"trace[892127123] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"442.432188ms","start":"2026-05-30T01:54:06.737281Z","end":"2026-05-30T01:54:07.179714Z","steps":["trace[892127123] 'process raft request' (duration: 361.680236ms)","trace[892127123] 'compare' (duration: 80.467176ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:07.179818Z","caller":"traceutil/trace.go:171","msg":"trace[2036724112] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"442.373677ms","start":"2026-05-30T01:54:06.737414Z","end":"2026-05-30T01:54:07.179788Z","steps":["trace[2036724112] 'process raft request' (duration: 442.172272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:07.180091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:06.737267Z","time spent":"442.53197ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:54:07.18019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:06.737383Z","time spent":"442.50736ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.364811Z","caller":"traceutil/trace.go:171","msg":"trace[1487832728] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"532.058063ms","start":"2026-05-30T01:54:08.832727Z","end":"2026-05-30T01:54:09.364786Z","steps":["trace[1487832728] 'process raft request' (duration: 531.881609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.364951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.832711Z","time spent":"532.175876ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.372173Z","caller":"traceutil/trace.go:171","msg":"trace[1123579192] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:513; }","duration":"183.296854ms","start":"2026-05-30T01:54:09.188845Z","end":"2026-05-30T01:54:09.372142Z","steps":["trace[1123579192] 'read index received' (duration: 176.353435ms)","trace[1123579192] 'applied index is now lower than readState.Index' (duration: 6.942219ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:09.372466Z","caller":"traceutil/trace.go:171","msg":"trace[1020939321] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"539.544114ms","start":"2026-05-30T01:54:08.832909Z","end":"2026-05-30T01:54:09.372453Z","steps":["trace[1020939321] 'process raft request' (duration: 538.457769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.372555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.83288Z","time spent":"539.624966ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.372726Z","caller":"traceutil/trace.go:171","msg":"trace[1864090218] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"510.696243ms","start":"2026-05-30T01:54:08.86202Z","end":"2026-05-30T01:54:09.372716Z","steps":["trace[1864090218] 'process raft request' (duration: 509.879664ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.37272Z","caller":"traceutil/trace.go:171","msg":"trace[870581715] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"538.642333ms","start":"2026-05-30T01:54:08.834044Z","end":"2026-05-30T01:54:09.372686Z","steps":["trace[870581715] 'process raft request' (duration: 537.432866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.37278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.862004Z","time spent":"510.754044ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:54:09.372855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.834034Z","time spent":"538.758986ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.372903Z","caller":"traceutil/trace.go:171","msg":"trace[1418871556] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"499.111248ms","start":"2026-05-30T01:54:08.873783Z","end":"2026-05-30T01:54:09.372894Z","steps":["trace[1418871556] 'process raft request' (duration: 498.217007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.372946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.873754Z","time spent":"499.172459ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.37302Z","caller":"traceutil/trace.go:171","msg":"trace[1415314922] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"379.295549ms","start":"2026-05-30T01:54:08.993717Z","end":"2026-05-30T01:54:09.373012Z","steps":["trace[1415314922] 'process raft request' (duration: 378.356917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.373063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.99369Z","time spent":"379.356081ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.373106Z","caller":"traceutil/trace.go:171","msg":"trace[1937240816] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"513.016747ms","start":"2026-05-30T01:54:08.860056Z","end":"2026-05-30T01:54:09.373072Z","steps":["trace[1937240816] 'process raft request' (duration: 511.772988ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.373159Z","caller":"traceutil/trace.go:171","msg":"trace[2125919017] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"532.595925ms","start":"2026-05-30T01:54:08.840551Z","end":"2026-05-30T01:54:09.373147Z","steps":["trace[2125919017] 'process raft request' (duration: 530.963408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.373217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.840532Z","time spent":"532.657937ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:54:09.373264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.860031Z","time spent":"513.15977ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:54:09.373337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.498621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:54:09.373406Z","caller":"traceutil/trace.go:171","msg":"trace[516092029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"184.604894ms","start":"2026-05-30T01:54:09.188783Z","end":"2026-05-30T01:54:09.373388Z","steps":["trace[516092029] 'agreement among raft nodes before linearized reading' (duration: 184.45186ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.373521Z","caller":"traceutil/trace.go:171","msg":"trace[881527921] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"526.878304ms","start":"2026-05-30T01:54:08.84663Z","end":"2026-05-30T01:54:09.373508Z","steps":["trace[881527921] 'process raft request' (duration: 525.025831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.373587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.846611Z","time spent":"526.949046ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.373708Z","caller":"traceutil/trace.go:171","msg":"trace[520787747] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"526.023675ms","start":"2026-05-30T01:54:08.847673Z","end":"2026-05-30T01:54:09.373697Z","steps":["trace[520787747] 'process raft request' (duration: 524.023969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.373758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.847659Z","time spent":"526.078096ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.373829Z","caller":"traceutil/trace.go:171","msg":"trace[555449798] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"525.586585ms","start":"2026-05-30T01:54:08.848234Z","end":"2026-05-30T01:54:09.37382Z","steps":["trace[555449798] 'process raft request' (duration: 523.511487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.373872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.848223Z","time spent":"525.631985ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.372878Z","caller":"traceutil/trace.go:171","msg":"trace[447042286] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"532.065942ms","start":"2026-05-30T01:54:08.84062Z","end":"2026-05-30T01:54:09.372686Z","steps":["trace[447042286] 'process raft request' (duration: 530.974997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:09.374299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:08.840614Z","time spent":"533.570477ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:09.654542Z","caller":"traceutil/trace.go:171","msg":"trace[69464505] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"202.905494ms","start":"2026-05-30T01:54:09.451612Z","end":"2026-05-30T01:54:09.654518Z","steps":["trace[69464505] 'process raft request' (duration: 200.280123ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.654602Z","caller":"traceutil/trace.go:171","msg":"trace[556979806] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"195.815921ms","start":"2026-05-30T01:54:09.458764Z","end":"2026-05-30T01:54:09.65458Z","steps":["trace[556979806] 'process raft request' (duration: 195.699828ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.65463Z","caller":"traceutil/trace.go:171","msg":"trace[1246443420] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"195.015023ms","start":"2026-05-30T01:54:09.459608Z","end":"2026-05-30T01:54:09.654623Z","steps":["trace[1246443420] 'process raft request' (duration: 194.88034ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.654552Z","caller":"traceutil/trace.go:171","msg":"trace[979631860] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"202.847512ms","start":"2026-05-30T01:54:09.451686Z","end":"2026-05-30T01:54:09.654534Z","steps":["trace[979631860] 'process raft request' (duration: 202.733649ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.654912Z","caller":"traceutil/trace.go:171","msg":"trace[2029781336] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"188.418401ms","start":"2026-05-30T01:54:09.466478Z","end":"2026-05-30T01:54:09.654896Z","steps":["trace[2029781336] 'process raft request' (duration: 188.036543ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:09.654848Z","caller":"traceutil/trace.go:171","msg":"trace[397446403] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"169.527638ms","start":"2026-05-30T01:54:09.485304Z","end":"2026-05-30T01:54:09.654831Z","steps":["trace[397446403] 'process raft request' (duration: 169.457726ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:16.438604Z","caller":"traceutil/trace.go:171","msg":"trace[598932605] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"307.593345ms","start":"2026-05-30T01:54:16.13098Z","end":"2026-05-30T01:54:16.438573Z","steps":["trace[598932605] 'process raft request' (duration: 305.841294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:16.439635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:16.130949Z","time spent":"307.741749ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:54:17.062905Z","caller":"traceutil/trace.go:171","msg":"trace[636891729] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"504.86927ms","start":"2026-05-30T01:54:16.557994Z","end":"2026-05-30T01:54:17.062864Z","steps":["trace[636891729] 'process raft request' (duration: 470.098472ms)","trace[636891729] 'compare' (duration: 34.475231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:54:17.063192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:16.557984Z","time spent":"505.091936ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:54:17.075072Z","caller":"traceutil/trace.go:171","msg":"trace[443972230] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"149.076799ms","start":"2026-05-30T01:54:16.925975Z","end":"2026-05-30T01:54:17.075052Z","steps":["trace[443972230] 'process raft request' (duration: 149.009248ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:17.0755Z","caller":"traceutil/trace.go:171","msg":"trace[61289269] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"511.525412ms","start":"2026-05-30T01:54:16.563576Z","end":"2026-05-30T01:54:17.075101Z","steps":["trace[61289269] 'process raft request' (duration: 511.298597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:17.075707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:16.563551Z","time spent":"512.058305ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:54:17.931598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812262706509152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:54:18.432733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812262706509152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:54:18.72421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.64935486s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T01:54:18.72485Z","caller":"traceutil/trace.go:171","msg":"trace[1292313452] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:635; }","duration":"1.294097241s","start":"2026-05-30T01:54:17.430736Z","end":"2026-05-30T01:54:18.724834Z","steps":["trace[1292313452] 'read index received' (duration: 1.293762734s)","trace[1292313452] 'applied index is now lower than readState.Index' (duration: 333.817µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:18.725003Z","caller":"traceutil/trace.go:171","msg":"trace[112451446] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.656210537s","start":"2026-05-30T01:54:17.068672Z","end":"2026-05-30T01:54:18.724883Z","steps":["trace[112451446] 'process raft request' (duration: 1.656102505s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:18.725108Z","caller":"traceutil/trace.go:171","msg":"trace[1305612755] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.658278095s","start":"2026-05-30T01:54:17.066807Z","end":"2026-05-30T01:54:18.725085Z","steps":["trace[1305612755] 'process raft request' (duration: 1.657736233s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:18.72519Z","caller":"traceutil/trace.go:171","msg":"trace[757906281] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.656411331s","start":"2026-05-30T01:54:17.068769Z","end":"2026-05-30T01:54:18.725181Z","steps":["trace[757906281] 'process raft request' (duration: 1.656029512s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:18.725192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.068651Z","time spent":"1.656446772s","remote":"127.0.0.1:54306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:54:18.725331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.068756Z","time spent":"1.656481183s","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:54:18.725353Z","caller":"traceutil/trace.go:171","msg":"trace[145027778] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.656753919s","start":"2026-05-30T01:54:17.068583Z","end":"2026-05-30T01:54:18.725337Z","steps":["trace[145027778] 'process raft request' (duration: 1.656147576s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:18.725202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.066781Z","time spent":"1.658379346s","remote":"127.0.0.1:54306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T01:54:18.725459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.068563Z","time spent":"1.656836831s","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:54:18.725532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.925467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-30T01:54:18.72556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.473832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-30T01:54:18.72561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164410028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:54:18.725633Z","caller":"traceutil/trace.go:171","msg":"trace[1020428015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.164458939s","start":"2026-05-30T01:54:17.561167Z","end":"2026-05-30T01:54:18.725626Z","steps":["trace[1020428015] 'agreement among raft nodes before linearized reading' (duration: 1.164425128s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:18.725575Z","caller":"traceutil/trace.go:171","msg":"trace[1098762124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"986.997218ms","start":"2026-05-30T01:54:17.738564Z","end":"2026-05-30T01:54:18.725561Z","steps":["trace[1098762124] 'agreement among raft nodes before linearized reading' (duration: 986.914996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:18.725456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294109652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-30T01:54:18.725666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.56115Z","time spent":"1.16450967s","remote":"127.0.0.1:54226","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-05-30T01:54:18.725673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.738542Z","time spent":"987.120711ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T01:54:18.725696Z","caller":"traceutil/trace.go:171","msg":"trace[1256123841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.294383018s","start":"2026-05-30T01:54:17.431289Z","end":"2026-05-30T01:54:18.725672Z","steps":["trace[1256123841] 'agreement among raft nodes before linearized reading' (duration: 1.29403943s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:18.725763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.431262Z","time spent":"1.294484901s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T01:54:18.725628Z","caller":"traceutil/trace.go:171","msg":"trace[177861060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"978.580445ms","start":"2026-05-30T01:54:17.747033Z","end":"2026-05-30T01:54:18.725613Z","steps":["trace[177861060] 'agreement among raft nodes before linearized reading' (duration: 978.407111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:18.725876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.74701Z","time spent":"978.85385ms","remote":"127.0.0.1:54498","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-05-30T01:54:18.725894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295154216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:54:18.726044Z","caller":"traceutil/trace.go:171","msg":"trace[1080182731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.295300969s","start":"2026-05-30T01:54:17.430731Z","end":"2026-05-30T01:54:18.726032Z","steps":["trace[1080182731] 'agreement among raft nodes before linearized reading' (duration: 1.295092094s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:18.726083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:17.430676Z","time spent":"1.295399321s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T01:54:21.005252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.499949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-30T01:54:21.005362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.640472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:54:21.005353Z","caller":"traceutil/trace.go:171","msg":"trace[985146466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"217.654962ms","start":"2026-05-30T01:54:20.787674Z","end":"2026-05-30T01:54:21.005329Z","steps":["trace[985146466] 'range keys from in-memory index tree' (duration: 217.322465ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:21.005412Z","caller":"traceutil/trace.go:171","msg":"trace[608172479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"217.712074ms","start":"2026-05-30T01:54:20.787686Z","end":"2026-05-30T01:54:21.005398Z","steps":["trace[608172479] 'range keys from in-memory index tree' (duration: 217.543659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:21.005713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.941019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-30T01:54:21.005762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.135347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-30T01:54:21.00577Z","caller":"traceutil/trace.go:171","msg":"trace[259289259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"218.01487ms","start":"2026-05-30T01:54:20.787742Z","end":"2026-05-30T01:54:21.005757Z","steps":["trace[259289259] 'range keys from in-memory index tree' (duration: 217.802725ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:21.005796Z","caller":"traceutil/trace.go:171","msg":"trace[1869538217] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:631; }","duration":"260.210959ms","start":"2026-05-30T01:54:20.745572Z","end":"2026-05-30T01:54:21.005783Z","steps":["trace[1869538217] 'range keys from in-memory index tree' (duration: 259.794729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:21.005708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.063073ms","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-05-30T01:54:21.0061Z","caller":"traceutil/trace.go:171","msg":"trace[1756413829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"239.512083ms","start":"2026-05-30T01:54:20.766583Z","end":"2026-05-30T01:54:21.006095Z","steps":["trace[1756413829] 'range keys from in-memory index tree' (duration: 238.91275ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:22.71461Z","caller":"traceutil/trace.go:171","msg":"trace[1786643334] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"116.607295ms","start":"2026-05-30T01:54:22.597975Z","end":"2026-05-30T01:54:22.714583Z","steps":["trace[1786643334] 'process raft request' (duration: 116.3946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:23.182658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.690868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:54:23.182749Z","caller":"traceutil/trace.go:171","msg":"trace[2078808798] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"151.056485ms","start":"2026-05-30T01:54:23.031675Z","end":"2026-05-30T01:54:23.182732Z","steps":["trace[2078808798] 'process raft request' (duration: 30.113311ms)","trace[2078808798] 'compare' (duration: 120.564525ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:23.334679Z","caller":"traceutil/trace.go:171","msg":"trace[1756636586] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"302.778375ms","start":"2026-05-30T01:54:23.031876Z","end":"2026-05-30T01:54:23.334654Z","steps":["trace[1756636586] 'process raft request' (duration: 258.408948ms)","trace[1756636586] 'compare' (duration: 44.213584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:54:23.334901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:23.031869Z","time spent":"302.956389ms","remote":"127.0.0.1:54498","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-05-30T01:54:23.335024Z","caller":"traceutil/trace.go:171","msg":"trace[673250956] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:695; }","duration":"294.812003ms","start":"2026-05-30T01:54:23.040177Z","end":"2026-05-30T01:54:23.334989Z","steps":["trace[673250956] 'read index received' (duration: 21.601166ms)","trace[673250956] 'applied index is now lower than readState.Index' (duration: 273.208697ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:23.335024Z","caller":"traceutil/trace.go:171","msg":"trace[551511673] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"302.9792ms","start":"2026-05-30T01:54:23.032025Z","end":"2026-05-30T01:54:23.335004Z","steps":["trace[551511673] 'process raft request' (duration: 302.776264ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:23.335062Z","caller":"traceutil/trace.go:171","msg":"trace[1959686842] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"105.222264ms","start":"2026-05-30T01:54:23.229814Z","end":"2026-05-30T01:54:23.335036Z","steps":["trace[1959686842] 'process raft request' (duration: 105.118241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:23.335217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:23.032004Z","time spent":"303.107613ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:54:23.335314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.12357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-30T01:54:23.33533Z","caller":"traceutil/trace.go:171","msg":"trace[183280225] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"303.329298ms","start":"2026-05-30T01:54:23.031958Z","end":"2026-05-30T01:54:23.335288Z","steps":["trace[183280225] 'process raft request' (duration: 302.658192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:23.335429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.036655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-30T01:54:23.335484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:23.031927Z","time spent":"303.476321ms","remote":"127.0.0.1:54498","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-05-30T01:54:23.335361Z","caller":"traceutil/trace.go:171","msg":"trace[2052358487] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"295.195932ms","start":"2026-05-30T01:54:23.040151Z","end":"2026-05-30T01:54:23.335347Z","steps":["trace[2052358487] 'agreement among raft nodes before linearized reading' (duration: 294.992757ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:23.33562Z","caller":"traceutil/trace.go:171","msg":"trace[516867496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"291.21885ms","start":"2026-05-30T01:54:23.044282Z","end":"2026-05-30T01:54:23.3355Z","steps":["trace[516867496] 'agreement among raft nodes before linearized reading' (duration: 290.895653ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:23.600331Z","caller":"traceutil/trace.go:171","msg":"trace[670607053] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"141.840182ms","start":"2026-05-30T01:54:23.458467Z","end":"2026-05-30T01:54:23.600307Z","steps":["trace[670607053] 'process raft request' (duration: 95.95136ms)","trace[670607053] 'compare' (duration: 45.810811ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:23.729308Z","caller":"traceutil/trace.go:171","msg":"trace[1819635069] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"121.892866ms","start":"2026-05-30T01:54:23.607392Z","end":"2026-05-30T01:54:23.729285Z","steps":["trace[1819635069] 'process raft request' (duration: 121.741752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:31.781349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.91589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:54:31.781345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.382269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T01:54:31.781472Z","caller":"traceutil/trace.go:171","msg":"trace[2030608462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"222.080464ms","start":"2026-05-30T01:54:31.55937Z","end":"2026-05-30T01:54:31.78145Z","steps":["trace[2030608462] 'range keys from in-memory index tree' (duration: 221.845099ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:31.781665Z","caller":"traceutil/trace.go:171","msg":"trace[82087185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"276.589844ms","start":"2026-05-30T01:54:31.504862Z","end":"2026-05-30T01:54:31.781451Z","steps":["trace[82087185] 'range keys from in-memory index tree' (duration: 276.164475ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:35.713223Z","caller":"traceutil/trace.go:171","msg":"trace[430462932] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"1.045491029s","start":"2026-05-30T01:54:34.66771Z","end":"2026-05-30T01:54:35.713201Z","steps":["trace[430462932] 'process raft request' (duration: 1.045346516s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.713383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:34.667681Z","time spent":"1.045629303s","remote":"127.0.0.1:54476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:54:35.796523Z","caller":"traceutil/trace.go:171","msg":"trace[583040049] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"532.488723ms","start":"2026-05-30T01:54:35.264008Z","end":"2026-05-30T01:54:35.796497Z","steps":["trace[583040049] 'read index received' (duration: 449.08452ms)","trace[583040049] 'applied index is now lower than readState.Index' (duration: 83.403183ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:35.796558Z","caller":"traceutil/trace.go:171","msg":"trace[902014498] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.015625385s","start":"2026-05-30T01:54:34.780882Z","end":"2026-05-30T01:54:35.796507Z","steps":["trace[902014498] 'process raft request' (duration: 1.003048876s)","trace[902014498] 'compare' (duration: 12.431486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:54:35.796885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.534589ms","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-05-30T01:54:35.796921Z","caller":"traceutil/trace.go:171","msg":"trace[1541906515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"397.596349ms","start":"2026-05-30T01:54:35.399313Z","end":"2026-05-30T01:54:35.79691Z","steps":["trace[1541906515] 'agreement among raft nodes before linearized reading' (duration: 397.485877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.796729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.696608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:54:35.796958Z","caller":"traceutil/trace.go:171","msg":"trace[1026487064] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:773; }","duration":"532.974244ms","start":"2026-05-30T01:54:35.263976Z","end":"2026-05-30T01:54:35.796951Z","steps":["trace[1026487064] 'agreement among raft nodes before linearized reading' (duration: 532.642187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.796962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:35.399296Z","time spent":"397.658411ms","remote":"127.0.0.1:54498","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-05-30T01:54:35.796982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:35.263961Z","time spent":"533.014785ms","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-30T01:54:35.796759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.09584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:54:35.797181Z","caller":"traceutil/trace.go:171","msg":"trace[1248157718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"234.494359ms","start":"2026-05-30T01:54:35.562587Z","end":"2026-05-30T01:54:35.797082Z","steps":["trace[1248157718] 'agreement among raft nodes before linearized reading' (duration: 234.125211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.796831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.528976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:54:35.797321Z","caller":"traceutil/trace.go:171","msg":"trace[1078593869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"397.030406ms","start":"2026-05-30T01:54:35.400276Z","end":"2026-05-30T01:54:35.797306Z","steps":["trace[1078593869] 'agreement among raft nodes before linearized reading' (duration: 396.479104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.79737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:35.400264Z","time spent":"397.089278ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T01:54:35.797377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:34.780859Z","time spent":"1.016034114s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:54:35.797835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.458726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:54:35.797982Z","caller":"traceutil/trace.go:171","msg":"trace[894447166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"397.532958ms","start":"2026-05-30T01:54:35.400329Z","end":"2026-05-30T01:54:35.797862Z","steps":["trace[894447166] 'agreement among raft nodes before linearized reading' (duration: 396.355221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.798013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:35.400309Z","time spent":"397.695582ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T01:54:35.798012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.819271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-30T01:54:35.798054Z","caller":"traceutil/trace.go:171","msg":"trace[1592069252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"532.874362ms","start":"2026-05-30T01:54:35.265165Z","end":"2026-05-30T01:54:35.79804Z","steps":["trace[1592069252] 'agreement among raft nodes before linearized reading' (duration: 531.47893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:54:35.798085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:54:35.265159Z","time spent":"532.919483ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T01:54:43.209773Z","caller":"traceutil/trace.go:171","msg":"trace[664958123] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"105.487238ms","start":"2026-05-30T01:54:43.104261Z","end":"2026-05-30T01:54:43.209748Z","steps":["trace[664958123] 'process raft request' (duration: 105.315854ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:16.316158Z","caller":"traceutil/trace.go:171","msg":"trace[916628148] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"233.311037ms","start":"2026-05-30T01:55:16.082822Z","end":"2026-05-30T01:55:16.316133Z","steps":["trace[916628148] 'read index received' (duration: 233.260246ms)","trace[916628148] 'applied index is now lower than readState.Index' (duration: 50.081µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:16.316284Z","caller":"traceutil/trace.go:171","msg":"trace[750881576] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"259.485545ms","start":"2026-05-30T01:55:16.056765Z","end":"2026-05-30T01:55:16.31625Z","steps":["trace[750881576] 'process raft request' (duration: 259.247389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:16.316351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.519772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-30T01:55:16.316381Z","caller":"traceutil/trace.go:171","msg":"trace[1454228177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"233.601493ms","start":"2026-05-30T01:55:16.08277Z","end":"2026-05-30T01:55:16.316372Z","steps":["trace[1454228177] 'agreement among raft nodes before linearized reading' (duration: 233.481691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:16.31646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.331997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-30T01:55:16.3165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.255423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T01:55:16.316493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.66159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T01:55:16.316522Z","caller":"traceutil/trace.go:171","msg":"trace[326283974] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1005; }","duration":"225.296755ms","start":"2026-05-30T01:55:16.091219Z","end":"2026-05-30T01:55:16.316516Z","steps":["trace[326283974] 'agreement among raft nodes before linearized reading' (duration: 225.253994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:16.316503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.948225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:55:16.316532Z","caller":"traceutil/trace.go:171","msg":"trace[883560688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"229.4401ms","start":"2026-05-30T01:55:16.087074Z","end":"2026-05-30T01:55:16.316514Z","steps":["trace[883560688] 'agreement among raft nodes before linearized reading' (duration: 229.216615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:16.316587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.077208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:55:16.316532Z","caller":"traceutil/trace.go:171","msg":"trace[1446985180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1005; }","duration":"164.736652ms","start":"2026-05-30T01:55:16.151785Z","end":"2026-05-30T01:55:16.316521Z","steps":["trace[1446985180] 'agreement among raft nodes before linearized reading' (duration: 164.64441ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:16.316606Z","caller":"traceutil/trace.go:171","msg":"trace[316306179] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1005; }","duration":"129.100589ms","start":"2026-05-30T01:55:16.1875Z","end":"2026-05-30T01:55:16.316601Z","steps":["trace[316306179] 'agreement among raft nodes before linearized reading' (duration: 129.065888ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:16.316579Z","caller":"traceutil/trace.go:171","msg":"trace[440751672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1005; }","duration":"161.056008ms","start":"2026-05-30T01:55:16.155504Z","end":"2026-05-30T01:55:16.31656Z","steps":["trace[440751672] 'agreement among raft nodes before linearized reading' (duration: 160.827833ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:16.485278Z","caller":"traceutil/trace.go:171","msg":"trace[569296529] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"164.172917ms","start":"2026-05-30T01:55:16.321082Z","end":"2026-05-30T01:55:16.485255Z","steps":["trace[569296529] 'process raft request' (duration: 144.318204ms)","trace[569296529] 'compare' (duration: 19.750031ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:16.808368Z","caller":"traceutil/trace.go:171","msg":"trace[1608181462] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"486.904727ms","start":"2026-05-30T01:55:16.321443Z","end":"2026-05-30T01:55:16.808347Z","steps":["trace[1608181462] 'process raft request' (duration: 486.873026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:16.808503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:16.321422Z","time spent":"487.01577ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:55:16.808473Z","caller":"traceutil/trace.go:171","msg":"trace[1945641300] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"487.093622ms","start":"2026-05-30T01:55:16.32134Z","end":"2026-05-30T01:55:16.808434Z","steps":["trace[1945641300] 'process raft request' (duration: 486.840996ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:16.808557Z","caller":"traceutil/trace.go:171","msg":"trace[1258495363] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"487.05634ms","start":"2026-05-30T01:55:16.321443Z","end":"2026-05-30T01:55:16.808499Z","steps":["trace[1258495363] 'process raft request' (duration: 486.844485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:16.808649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:16.321321Z","time spent":"487.241065ms","remote":"127.0.0.1:54498","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-05-30T01:55:16.808695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:16.321422Z","time spent":"487.198674ms","remote":"127.0.0.1:54498","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-05-30T01:55:36.839614Z","caller":"traceutil/trace.go:171","msg":"trace[567962714] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"278.002368ms","start":"2026-05-30T01:55:36.561587Z","end":"2026-05-30T01:55:36.839589Z","steps":["trace[567962714] 'read index received' (duration: 277.693991ms)","trace[567962714] 'applied index is now lower than readState.Index' (duration: 307.407µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:36.839706Z","caller":"traceutil/trace.go:171","msg":"trace[288080752] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"308.107696ms","start":"2026-05-30T01:55:36.531564Z","end":"2026-05-30T01:55:36.839672Z","steps":["trace[288080752] 'process raft request' (duration: 307.801469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:36.83979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.179482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:36.839826Z","caller":"traceutil/trace.go:171","msg":"trace[1277526133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"278.260104ms","start":"2026-05-30T01:55:36.561556Z","end":"2026-05-30T01:55:36.839816Z","steps":["trace[1277526133] 'agreement among raft nodes before linearized reading' (duration: 278.178662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:36.839853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:36.53154Z","time spent":"308.235248ms","remote":"127.0.0.1:54498","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-05-30T01:55:36.83999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.774587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T01:55:36.84038Z","caller":"traceutil/trace.go:171","msg":"trace[297662367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"195.202177ms","start":"2026-05-30T01:55:36.645159Z","end":"2026-05-30T01:55:36.840361Z","steps":["trace[297662367] 'agreement among raft nodes before linearized reading' (duration: 194.712025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:37.063145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.923157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:55:37.063288Z","caller":"traceutil/trace.go:171","msg":"trace[1968849186] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"154.010617ms","start":"2026-05-30T01:55:36.909251Z","end":"2026-05-30T01:55:37.063262Z","steps":["trace[1968849186] 'process raft request' (duration: 30.681331ms)","trace[1968849186] 'compare' (duration: 122.816344ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:37.063232Z","caller":"traceutil/trace.go:171","msg":"trace[619068454] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"126.182601ms","start":"2026-05-30T01:55:36.937035Z","end":"2026-05-30T01:55:37.063218Z","steps":["trace[619068454] 'read index received' (duration: 2.836924ms)","trace[619068454] 'applied index is now lower than readState.Index' (duration: 123.344487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:37.063518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.466928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:55:37.063589Z","caller":"traceutil/trace.go:171","msg":"trace[1515764945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"126.58429ms","start":"2026-05-30T01:55:36.936985Z","end":"2026-05-30T01:55:37.063569Z","steps":["trace[1515764945] 'agreement among raft nodes before linearized reading' (duration: 126.357235ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:37.409356Z","caller":"traceutil/trace.go:171","msg":"trace[1437741845] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"217.850255ms","start":"2026-05-30T01:55:37.191478Z","end":"2026-05-30T01:55:37.409328Z","steps":["trace[1437741845] 'process raft request' (duration: 133.356156ms)","trace[1437741845] 'compare' (duration: 84.345086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:38.430142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.817387ms","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-05-30T01:55:38.430226Z","caller":"traceutil/trace.go:171","msg":"trace[685341904] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1076; }","duration":"324.92639ms","start":"2026-05-30T01:55:38.105268Z","end":"2026-05-30T01:55:38.430194Z","steps":["trace[685341904] 'count revisions from in-memory index tree' (duration: 324.748366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:38.430255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:38.10525Z","time spent":"324.997052ms","remote":"127.0.0.1:54458","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-05-30T01:55:38.430561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.157857ms","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-05-30T01:55:38.430602Z","caller":"traceutil/trace.go:171","msg":"trace[395141736] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1076; }","duration":"213.239819ms","start":"2026-05-30T01:55:38.217351Z","end":"2026-05-30T01:55:38.430591Z","steps":["trace[395141736] 'count revisions from in-memory index tree' (duration: 213.034615ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:39.246647Z","caller":"traceutil/trace.go:171","msg":"trace[1770935857] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"117.666607ms","start":"2026-05-30T01:55:39.128867Z","end":"2026-05-30T01:55:39.246534Z","steps":["trace[1770935857] 'read index received' (duration: 117.42176ms)","trace[1770935857] 'applied index is now lower than readState.Index' (duration: 241.877µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:39.246749Z","caller":"traceutil/trace.go:171","msg":"trace[2115140152] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"174.323492ms","start":"2026-05-30T01:55:39.072381Z","end":"2026-05-30T01:55:39.246704Z","steps":["trace[2115140152] 'process raft request' (duration: 173.972773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:39.246893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.018604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:55:39.246949Z","caller":"traceutil/trace.go:171","msg":"trace[1549402293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"118.132038ms","start":"2026-05-30T01:55:39.128803Z","end":"2026-05-30T01:55:39.246935Z","steps":["trace[1549402293] 'agreement among raft nodes before linearized reading' (duration: 117.952524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:39.247036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.983951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:55:39.247117Z","caller":"traceutil/trace.go:171","msg":"trace[1780077905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"113.112444ms","start":"2026-05-30T01:55:39.133986Z","end":"2026-05-30T01:55:39.247098Z","steps":["trace[1780077905] 'agreement among raft nodes before linearized reading' (duration: 112.893699ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:39.428249Z","caller":"traceutil/trace.go:171","msg":"trace[1555246012] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"177.190786ms","start":"2026-05-30T01:55:39.251001Z","end":"2026-05-30T01:55:39.428191Z","steps":["trace[1555246012] 'process raft request' (duration: 135.818892ms)","trace[1555246012] 'compare' (duration: 41.036017ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:39.434935Z","caller":"traceutil/trace.go:171","msg":"trace[611060193] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"183.47365ms","start":"2026-05-30T01:55:39.251433Z","end":"2026-05-30T01:55:39.434907Z","steps":["trace[611060193] 'process raft request' (duration: 183.323917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:40.470513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.636228ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T01:55:40.470603Z","caller":"traceutil/trace.go:171","msg":"trace[1707309182] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"229.552101ms","start":"2026-05-30T01:55:40.241035Z","end":"2026-05-30T01:55:40.470587Z","steps":["trace[1707309182] 'read index received' (duration: 36.071µs)","trace[1707309182] 'applied index is now lower than readState.Index' (duration: 229.514789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:40.470682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.644223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:40.470715Z","caller":"traceutil/trace.go:171","msg":"trace[121697268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"229.754877ms","start":"2026-05-30T01:55:40.240951Z","end":"2026-05-30T01:55:40.470706Z","steps":["trace[121697268] 'agreement among raft nodes before linearized reading' (duration: 229.690945ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:47.065853Z","caller":"traceutil/trace.go:171","msg":"trace[657766073] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"101.213941ms","start":"2026-05-30T01:55:46.964614Z","end":"2026-05-30T01:55:47.065828Z","steps":["trace[657766073] 'process raft request' (duration: 39.759607ms)","trace[657766073] 'compare' (duration: 61.31405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:55.294364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.158071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-30T01:55:55.294423Z","caller":"traceutil/trace.go:171","msg":"trace[196559559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"186.262083ms","start":"2026-05-30T01:55:55.10815Z","end":"2026-05-30T01:55:55.294412Z","steps":["trace[196559559] 'range keys from in-memory index tree' (duration: 186.009617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.57536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812262706510418,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T01:55:57.748225Z","caller":"traceutil/trace.go:171","msg":"trace[608459065] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"695.009821ms","start":"2026-05-30T01:55:57.053192Z","end":"2026-05-30T01:55:57.748202Z","steps":["trace[608459065] 'process raft request' (duration: 694.846927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.748363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:57.053166Z","time spent":"695.125363ms","remote":"127.0.0.1:54498","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-05-30T01:55:57.749092Z","caller":"traceutil/trace.go:171","msg":"trace[378949280] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1237; }","duration":"674.320677ms","start":"2026-05-30T01:55:57.074747Z","end":"2026-05-30T01:55:57.749067Z","steps":["trace[378949280] 'read index received' (duration: 673.388947ms)","trace[378949280] 'applied index is now lower than readState.Index' (duration: 930.62µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:57.749154Z","caller":"traceutil/trace.go:171","msg":"trace[874171949] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"183.95083ms","start":"2026-05-30T01:55:57.565179Z","end":"2026-05-30T01:55:57.74913Z","steps":["trace[874171949] 'process raft request' (duration: 183.721765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.466961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:57.749274Z","caller":"traceutil/trace.go:171","msg":"trace[2036955841] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1189; }","duration":"674.571974ms","start":"2026-05-30T01:55:57.074691Z","end":"2026-05-30T01:55:57.749263Z","steps":["trace[2036955841] 'agreement among raft nodes before linearized reading' (duration: 674.487952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.74931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:57.074667Z","time spent":"674.633335ms","remote":"127.0.0.1:48694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-30T01:55:57.749353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.695361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-30T01:55:57.749422Z","caller":"traceutil/trace.go:171","msg":"trace[703810687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"404.806573ms","start":"2026-05-30T01:55:57.344596Z","end":"2026-05-30T01:55:57.749403Z","steps":["trace[703810687] 'agreement among raft nodes before linearized reading' (duration: 404.638199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:57.344574Z","time spent":"404.897736ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T01:55:57.74951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.044096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:57.749548Z","caller":"traceutil/trace.go:171","msg":"trace[166967610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"189.104718ms","start":"2026-05-30T01:55:57.560431Z","end":"2026-05-30T01:55:57.749536Z","steps":["trace[166967610] 'agreement among raft nodes before linearized reading' (duration: 189.047807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.655783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:55:57.749653Z","caller":"traceutil/trace.go:171","msg":"trace[1784143969] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1189; }","duration":"320.713564ms","start":"2026-05-30T01:55:57.428932Z","end":"2026-05-30T01:55:57.749646Z","steps":["trace[1784143969] 'agreement among raft nodes before linearized reading' (duration: 320.670003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:57.428909Z","time spent":"320.758335ms","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-30T01:55:57.749784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.471271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:57.749804Z","caller":"traceutil/trace.go:171","msg":"trace[1301685981] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1189; }","duration":"328.491952ms","start":"2026-05-30T01:55:57.421305Z","end":"2026-05-30T01:55:57.749797Z","steps":["trace[1301685981] 'agreement among raft nodes before linearized reading' (duration: 328.460411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.717619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:55:57.749856Z","caller":"traceutil/trace.go:171","msg":"trace[1067149792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"177.79043ms","start":"2026-05-30T01:55:57.572054Z","end":"2026-05-30T01:55:57.749844Z","steps":["trace[1067149792] 'agreement among raft nodes before linearized reading' (duration: 177.645797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.653881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-30T01:55:57.749897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.776277ms","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-05-30T01:55:57.749901Z","caller":"traceutil/trace.go:171","msg":"trace[843997090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"185.745752ms","start":"2026-05-30T01:55:57.564144Z","end":"2026-05-30T01:55:57.74989Z","steps":["trace[843997090] 'agreement among raft nodes before linearized reading' (duration: 185.612949ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:57.749921Z","caller":"traceutil/trace.go:171","msg":"trace[97664967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"384.842498ms","start":"2026-05-30T01:55:57.365072Z","end":"2026-05-30T01:55:57.749915Z","steps":["trace[97664967] 'agreement among raft nodes before linearized reading' (duration: 384.767537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:57.749939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:57.36505Z","time spent":"384.88575ms","remote":"127.0.0.1:54498","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-05-30T01:55:57.749823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:57.421247Z","time spent":"328.570303ms","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-30T01:56:10.092881Z","caller":"traceutil/trace.go:171","msg":"trace[887177016] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"299.356986ms","start":"2026-05-30T01:56:09.793504Z","end":"2026-05-30T01:56:10.092861Z","steps":["trace[887177016] 'read index received' (duration: 299.244334ms)","trace[887177016] 'applied index is now lower than readState.Index' (duration: 111.852µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:10.09292Z","caller":"traceutil/trace.go:171","msg":"trace[349614742] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"310.563522ms","start":"2026-05-30T01:56:09.782344Z","end":"2026-05-30T01:56:10.092908Z","steps":["trace[349614742] 'process raft request' (duration: 310.411539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:10.093053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.53896ms","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-05-30T01:56:10.093079Z","caller":"traceutil/trace.go:171","msg":"trace[2095798708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"299.607081ms","start":"2026-05-30T01:56:09.793465Z","end":"2026-05-30T01:56:10.093072Z","steps":["trace[2095798708] 'agreement among raft nodes before linearized reading' (duration: 299.490978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:10.093129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:09.78232Z","time spent":"310.714135ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:56:10.348779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.003713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T01:56:10.349226Z","caller":"traceutil/trace.go:171","msg":"trace[105667822] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"256.264972ms","start":"2026-05-30T01:56:10.092937Z","end":"2026-05-30T01:56:10.349202Z","steps":["trace[105667822] 'read index received' (duration: 141.828928ms)","trace[105667822] 'applied index is now lower than readState.Index' (duration: 114.433904ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:10.349397Z","caller":"traceutil/trace.go:171","msg":"trace[668127993] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"250.837137ms","start":"2026-05-30T01:56:10.098533Z","end":"2026-05-30T01:56:10.34937Z","steps":["trace[668127993] 'process raft request' (duration: 250.573402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:10.349467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.80405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:56:10.349519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.821965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:56:10.349575Z","caller":"traceutil/trace.go:171","msg":"trace[795381127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"110.944313ms","start":"2026-05-30T01:56:10.238608Z","end":"2026-05-30T01:56:10.349552Z","steps":["trace[795381127] 'agreement among raft nodes before linearized reading' (duration: 110.801259ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:10.34959Z","caller":"traceutil/trace.go:171","msg":"trace[579715257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"542.939249ms","start":"2026-05-30T01:56:09.806633Z","end":"2026-05-30T01:56:10.349572Z","steps":["trace[579715257] 'agreement among raft nodes before linearized reading' (duration: 542.654183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:10.349568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.638244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-30T01:56:10.349579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.271062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-30T01:56:10.34946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.993385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:56:10.349667Z","caller":"traceutil/trace.go:171","msg":"trace[2063886097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"550.768947ms","start":"2026-05-30T01:56:09.798879Z","end":"2026-05-30T01:56:10.349648Z","steps":["trace[2063886097] 'agreement among raft nodes before linearized reading' (duration: 550.526591ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:10.349689Z","caller":"traceutil/trace.go:171","msg":"trace[557433348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1229; }","duration":"528.413496ms","start":"2026-05-30T01:56:09.821252Z","end":"2026-05-30T01:56:10.349666Z","steps":["trace[557433348] 'agreement among raft nodes before linearized reading' (duration: 528.112189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:10.349722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:09.798856Z","time spent":"550.853859ms","remote":"127.0.0.1:54498","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-05-30T01:56:10.349743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:09.821241Z","time spent":"528.490768ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-30T01:56:10.349679Z","caller":"traceutil/trace.go:171","msg":"trace[1539376120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"545.261401ms","start":"2026-05-30T01:56:09.804408Z","end":"2026-05-30T01:56:10.34967Z","steps":["trace[1539376120] 'agreement among raft nodes before linearized reading' (duration: 544.908313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:10.34995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:09.804389Z","time spent":"545.550367ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T01:56:10.349638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:09.806613Z","time spent":"543.01675ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T01:56:10.461756Z","caller":"traceutil/trace.go:171","msg":"trace[1548493117] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"106.957122ms","start":"2026-05-30T01:56:10.354781Z","end":"2026-05-30T01:56:10.461738Z","steps":["trace[1548493117] 'process raft request' (duration: 106.826589ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:10.461951Z","caller":"traceutil/trace.go:171","msg":"trace[1588369050] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"106.811358ms","start":"2026-05-30T01:56:10.35512Z","end":"2026-05-30T01:56:10.461931Z","steps":["trace[1588369050] 'process raft request' (duration: 106.589183ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:10.462Z","caller":"traceutil/trace.go:171","msg":"trace[1929198346] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"106.274166ms","start":"2026-05-30T01:56:10.355706Z","end":"2026-05-30T01:56:10.461981Z","steps":["trace[1929198346] 'process raft request' (duration: 106.189135ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:14.124128Z","caller":"traceutil/trace.go:171","msg":"trace[818892713] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"116.922763ms","start":"2026-05-30T01:56:14.00717Z","end":"2026-05-30T01:56:14.124093Z","steps":["trace[818892713] 'process raft request' (duration: 116.674788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:14.661162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.403386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-30T01:56:14.661254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.646912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:56:14.661386Z","caller":"traceutil/trace.go:171","msg":"trace[871125748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"179.853158ms","start":"2026-05-30T01:56:14.481508Z","end":"2026-05-30T01:56:14.661361Z","steps":["trace[871125748] 'range keys from in-memory index tree' (duration: 179.399587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:14.66149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.951653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-30T01:56:14.661496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.247202ms","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-05-30T01:56:14.661524Z","caller":"traceutil/trace.go:171","msg":"trace[353259510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"113.026835ms","start":"2026-05-30T01:56:14.548489Z","end":"2026-05-30T01:56:14.661516Z","steps":["trace[353259510] 'range keys from in-memory index tree' (duration: 112.801139ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:14.661544Z","caller":"traceutil/trace.go:171","msg":"trace[1732460299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"107.296383ms","start":"2026-05-30T01:56:14.554234Z","end":"2026-05-30T01:56:14.66153Z","steps":["trace[1732460299] 'range keys from in-memory index tree' (duration: 107.156619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:14.661621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.742073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:56:14.66164Z","caller":"traceutil/trace.go:171","msg":"trace[567541561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"107.804575ms","start":"2026-05-30T01:56:14.553828Z","end":"2026-05-30T01:56:14.661633Z","steps":["trace[567541561] 'range keys from in-memory index tree' (duration: 107.629641ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:14.661262Z","caller":"traceutil/trace.go:171","msg":"trace[534913958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"294.54351ms","start":"2026-05-30T01:56:14.366692Z","end":"2026-05-30T01:56:14.661236Z","steps":["trace[534913958] 'range keys from in-memory index tree' (duration: 294.207412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:15.390643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:14.662922Z","time spent":"727.717931ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-30T01:56:15.401546Z","caller":"traceutil/trace.go:171","msg":"trace[1946494268] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"736.274757ms","start":"2026-05-30T01:56:14.665253Z","end":"2026-05-30T01:56:15.401528Z","steps":["trace[1946494268] 'process raft request' (duration: 736.111654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:15.401655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:14.66523Z","time spent":"736.36696ms","remote":"127.0.0.1:54498","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-05-30T01:56:15.401855Z","caller":"traceutil/trace.go:171","msg":"trace[1000572082] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"735.663553ms","start":"2026-05-30T01:56:14.666182Z","end":"2026-05-30T01:56:15.401846Z","steps":["trace[1000572082] 'process raft request' (duration: 735.52388ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:15.401863Z","caller":"traceutil/trace.go:171","msg":"trace[397297013] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"735.586182ms","start":"2026-05-30T01:56:14.666263Z","end":"2026-05-30T01:56:15.401849Z","steps":["trace[397297013] 'process raft request' (duration: 735.50889ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:15.401804Z","caller":"traceutil/trace.go:171","msg":"trace[997832737] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"735.640913ms","start":"2026-05-30T01:56:14.666141Z","end":"2026-05-30T01:56:15.401782Z","steps":["trace[997832737] 'process raft request' (duration: 735.362647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:15.401946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:14.666178Z","time spent":"735.697144ms","remote":"127.0.0.1:54498","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-05-30T01:56:15.402077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.50311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:15.402105Z","caller":"traceutil/trace.go:171","msg":"trace[171210534] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1265; }","duration":"206.566261ms","start":"2026-05-30T01:56:15.195533Z","end":"2026-05-30T01:56:15.402099Z","steps":["trace[171210534] 'agreement among raft nodes before linearized reading' (duration: 206.52276ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:15.401984Z","caller":"traceutil/trace.go:171","msg":"trace[1105736182] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"735.53447ms","start":"2026-05-30T01:56:14.666441Z","end":"2026-05-30T01:56:15.401976Z","steps":["trace[1105736182] 'process raft request' (duration: 735.356496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:15.402194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:14.666411Z","time spent":"735.761696ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:56:15.40199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:14.666249Z","time spent":"735.671374ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:56:15.402033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:14.666134Z","time spent":"735.814067ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:56:15.40204Z","caller":"traceutil/trace.go:171","msg":"trace[1338560308] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1313; }","duration":"206.447479ms","start":"2026-05-30T01:56:15.195584Z","end":"2026-05-30T01:56:15.402031Z","steps":["trace[1338560308] 'read index received' (duration: 194.990986ms)","trace[1338560308] 'applied index is now lower than readState.Index' (duration: 11.455643ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:16.585311Z","caller":"traceutil/trace.go:171","msg":"trace[407056619] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"419.60991ms","start":"2026-05-30T01:56:16.165671Z","end":"2026-05-30T01:56:16.585281Z","steps":["trace[407056619] 'process raft request' (duration: 419.422395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:16.585514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:16.165653Z","time spent":"419.758014ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:56:17.466439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.938555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:56:17.466743Z","caller":"traceutil/trace.go:171","msg":"trace[389003328] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"979.030889ms","start":"2026-05-30T01:56:16.487656Z","end":"2026-05-30T01:56:17.466687Z","steps":["trace[389003328] 'process raft request' (duration: 481.794067ms)","trace[389003328] 'compare' (duration: 496.852303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:56:17.466852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:16.487622Z","time spent":"979.170362ms","remote":"127.0.0.1:54498","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-05-30T01:56:17.69847Z","caller":"traceutil/trace.go:171","msg":"trace[567480014] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"227.521912ms","start":"2026-05-30T01:56:17.470922Z","end":"2026-05-30T01:56:17.698444Z","steps":["trace[567480014] 'process raft request' (duration: 227.275227ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.720611Z","caller":"traceutil/trace.go:171","msg":"trace[1976960716] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"249.449236ms","start":"2026-05-30T01:56:17.471139Z","end":"2026-05-30T01:56:17.720588Z","steps":["trace[1976960716] 'process raft request' (duration: 249.298912ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.720958Z","caller":"traceutil/trace.go:171","msg":"trace[1257051773] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"248.954513ms","start":"2026-05-30T01:56:17.471983Z","end":"2026-05-30T01:56:17.720938Z","steps":["trace[1257051773] 'process raft request' (duration: 248.567635ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.721189Z","caller":"traceutil/trace.go:171","msg":"trace[529536752] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"248.984155ms","start":"2026-05-30T01:56:17.472197Z","end":"2026-05-30T01:56:17.721181Z","steps":["trace[529536752] 'process raft request' (duration: 248.678298ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.721337Z","caller":"traceutil/trace.go:171","msg":"trace[614407847] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1325; }","duration":"233.248533ms","start":"2026-05-30T01:56:17.488079Z","end":"2026-05-30T01:56:17.721327Z","steps":["trace[614407847] 'read index received' (duration: 210.696616ms)","trace[614407847] 'applied index is now lower than readState.Index' (duration: 22.551267ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:17.721349Z","caller":"traceutil/trace.go:171","msg":"trace[113851094] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"247.796997ms","start":"2026-05-30T01:56:17.473533Z","end":"2026-05-30T01:56:17.72133Z","steps":["trace[113851094] 'process raft request' (duration: 247.621443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:17.721554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.306015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:56:17.721586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.485839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:17.721586Z","caller":"traceutil/trace.go:171","msg":"trace[415225588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"162.400527ms","start":"2026-05-30T01:56:17.559178Z","end":"2026-05-30T01:56:17.721578Z","steps":["trace[415225588] 'agreement among raft nodes before linearized reading' (duration: 162.281614ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.72163Z","caller":"traceutil/trace.go:171","msg":"trace[800043190] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"100.020916ms","start":"2026-05-30T01:56:17.621566Z","end":"2026-05-30T01:56:17.721587Z","steps":["trace[800043190] 'process raft request' (duration: 99.647388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:17.721744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.584193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:17.721667Z","caller":"traceutil/trace.go:171","msg":"trace[2076788893] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1276; }","duration":"233.612571ms","start":"2026-05-30T01:56:17.488042Z","end":"2026-05-30T01:56:17.721655Z","steps":["trace[2076788893] 'agreement among raft nodes before linearized reading' (duration: 233.496308ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.721806Z","caller":"traceutil/trace.go:171","msg":"trace[973580997] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1276; }","duration":"149.686365ms","start":"2026-05-30T01:56:17.572104Z","end":"2026-05-30T01:56:17.721791Z","steps":["trace[973580997] 'agreement among raft nodes before linearized reading' (duration: 149.561152ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:17.85504Z","caller":"traceutil/trace.go:171","msg":"trace[2032315544] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"127.418995ms","start":"2026-05-30T01:56:17.727599Z","end":"2026-05-30T01:56:17.855018Z","steps":["trace[2032315544] 'process raft request' (duration: 78.694436ms)","trace[2032315544] 'compare' (duration: 48.533813ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:17.85504Z","caller":"traceutil/trace.go:171","msg":"trace[1920536905] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"127.109418ms","start":"2026-05-30T01:56:17.727894Z","end":"2026-05-30T01:56:17.855003Z","steps":["trace[1920536905] 'process raft request' (duration: 127.058036ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:18.955801Z","caller":"traceutil/trace.go:171","msg":"trace[124543673] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"229.913897ms","start":"2026-05-30T01:56:18.725865Z","end":"2026-05-30T01:56:18.955778Z","steps":["trace[124543673] 'read index received' (duration: 229.692102ms)","trace[124543673] 'applied index is now lower than readState.Index' (duration: 220.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:18.955898Z","caller":"traceutil/trace.go:171","msg":"trace[436947521] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"364.457294ms","start":"2026-05-30T01:56:18.59141Z","end":"2026-05-30T01:56:18.955867Z","steps":["trace[436947521] 'process raft request' (duration: 364.199998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:18.955961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.07551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:56:18.955996Z","caller":"traceutil/trace.go:171","msg":"trace[2135345632] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1281; }","duration":"230.155743ms","start":"2026-05-30T01:56:18.72583Z","end":"2026-05-30T01:56:18.955986Z","steps":["trace[2135345632] 'agreement among raft nodes before linearized reading' (duration: 230.06228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:18.956039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:18.591387Z","time spent":"364.575778ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:56:19.691288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.95218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:56:19.691383Z","caller":"traceutil/trace.go:171","msg":"trace[1903077777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1281; }","duration":"275.142874ms","start":"2026-05-30T01:56:19.416221Z","end":"2026-05-30T01:56:19.691364Z","steps":["trace[1903077777] 'count revisions from in-memory index tree' (duration: 274.864368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.691376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.85163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:19.691445Z","caller":"traceutil/trace.go:171","msg":"trace[596706377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"129.957482ms","start":"2026-05-30T01:56:19.561469Z","end":"2026-05-30T01:56:19.691426Z","steps":["trace[596706377] 'range keys from in-memory index tree' (duration: 129.775928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.691494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.06396ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:19.691588Z","caller":"traceutil/trace.go:171","msg":"trace[943856434] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1281; }","duration":"257.158643ms","start":"2026-05-30T01:56:19.434415Z","end":"2026-05-30T01:56:19.691573Z","steps":["trace[943856434] 'range keys from in-memory index tree' (duration: 257.048529ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:19.97601Z","caller":"traceutil/trace.go:171","msg":"trace[783189511] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1335; }","duration":"300.547318ms","start":"2026-05-30T01:56:19.675439Z","end":"2026-05-30T01:56:19.975986Z","steps":["trace[783189511] 'read index received' (duration: 46.15089ms)","trace[783189511] 'applied index is now lower than readState.Index' (duration: 254.395158ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:19.976106Z","caller":"traceutil/trace.go:171","msg":"trace[2009448810] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"351.161469ms","start":"2026-05-30T01:56:19.624912Z","end":"2026-05-30T01:56:19.976074Z","steps":["trace[2009448810] 'process raft request' (duration: 350.900523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.976213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.753773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T01:56:19.976231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:19.62488Z","time spent":"351.283142ms","remote":"127.0.0.1:54498","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-05-30T01:56:19.976258Z","caller":"traceutil/trace.go:171","msg":"trace[104055803] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1282; }","duration":"300.855655ms","start":"2026-05-30T01:56:19.675389Z","end":"2026-05-30T01:56:19.976245Z","steps":["trace[104055803] 'agreement among raft nodes before linearized reading' (duration: 300.758053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.976297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:19.675368Z","time spent":"300.917936ms","remote":"127.0.0.1:49074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-30T01:56:19.976674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.169412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:19.976721Z","caller":"traceutil/trace.go:171","msg":"trace[783926559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"284.241454ms","start":"2026-05-30T01:56:19.692469Z","end":"2026-05-30T01:56:19.97671Z","steps":["trace[783926559] 'agreement among raft nodes before linearized reading' (duration: 284.175902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.976775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.353106ms","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-05-30T01:56:19.976842Z","caller":"traceutil/trace.go:171","msg":"trace[2060392848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"250.447257ms","start":"2026-05-30T01:56:19.726379Z","end":"2026-05-30T01:56:19.976826Z","steps":["trace[2060392848] 'agreement among raft nodes before linearized reading' (duration: 250.283184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.976931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.55318ms","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-05-30T01:56:19.976981Z","caller":"traceutil/trace.go:171","msg":"trace[1640052358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"250.623051ms","start":"2026-05-30T01:56:19.726337Z","end":"2026-05-30T01:56:19.97696Z","steps":["trace[1640052358] 'agreement among raft nodes before linearized reading' (duration: 250.510409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.977061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.428836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T01:56:19.977106Z","caller":"traceutil/trace.go:171","msg":"trace[390965702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"250.57072ms","start":"2026-05-30T01:56:19.726524Z","end":"2026-05-30T01:56:19.977094Z","steps":["trace[390965702] 'agreement among raft nodes before linearized reading' (duration: 250.472968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.977217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.871958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T01:56:19.977259Z","caller":"traceutil/trace.go:171","msg":"trace[1181596369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"274.93713ms","start":"2026-05-30T01:56:19.70231Z","end":"2026-05-30T01:56:19.977247Z","steps":["trace[1181596369] 'agreement among raft nodes before linearized reading' (duration: 274.827507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:19.977305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.745024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-30T01:56:19.977363Z","caller":"traceutil/trace.go:171","msg":"trace[1692251109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"250.816496ms","start":"2026-05-30T01:56:19.726532Z","end":"2026-05-30T01:56:19.977349Z","steps":["trace[1692251109] 'agreement among raft nodes before linearized reading' (duration: 250.706754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:27.960086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812262706510736,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:56:28.460842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812262706510736,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:56:28.961265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812262706510736,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:56:29.139828Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.15683441s","expected-duration":"1s"} {"level":"info","ts":"2026-05-30T01:56:29.140164Z","caller":"traceutil/trace.go:171","msg":"trace[2039657476] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"2.157274479s","start":"2026-05-30T01:56:26.982868Z","end":"2026-05-30T01:56:29.140143Z","steps":["trace[2039657476] 'process raft request' (duration: 2.157153436s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.140282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:26.982836Z","time spent":"2.157381902s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:56:29.151233Z","caller":"traceutil/trace.go:171","msg":"trace[1742042253] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.671676745s","start":"2026-05-30T01:56:27.479512Z","end":"2026-05-30T01:56:29.151188Z","steps":["trace[1742042253] 'process raft request' (duration: 1.671270736s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.151319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691909669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-30T01:56:29.151365Z","caller":"traceutil/trace.go:171","msg":"trace[239443105] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"1.691996651s","start":"2026-05-30T01:56:27.459356Z","end":"2026-05-30T01:56:29.151352Z","steps":["trace[239443105] 'agreement among raft nodes before linearized reading' (duration: 1.691826497s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.151405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:27.45934Z","time spent":"1.692057793s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-30T01:56:29.151458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:27.479496Z","time spent":"1.671846419s","remote":"127.0.0.1:54498","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-05-30T01:56:29.150894Z","caller":"traceutil/trace.go:171","msg":"trace[1326501395] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1398; }","duration":"1.691470699s","start":"2026-05-30T01:56:27.459407Z","end":"2026-05-30T01:56:29.150878Z","steps":["trace[1326501395] 'read index received' (duration: 1.680717632s)","trace[1326501395] 'applied index is now lower than readState.Index' (duration: 10.751887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:56:29.151931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056165469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-30T01:56:29.151927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.013787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:29.151986Z","caller":"traceutil/trace.go:171","msg":"trace[1247163782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"1.05624161s","start":"2026-05-30T01:56:28.095729Z","end":"2026-05-30T01:56:29.151971Z","steps":["trace[1247163782] 'agreement among raft nodes before linearized reading' (duration: 1.056118897s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:29.152009Z","caller":"traceutil/trace.go:171","msg":"trace[1790221318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"592.121528ms","start":"2026-05-30T01:56:28.559863Z","end":"2026-05-30T01:56:29.151985Z","steps":["trace[1790221318] 'agreement among raft nodes before linearized reading' (duration: 592.001076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.151991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039523506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-30T01:56:29.15207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.559839Z","time spent":"592.219751ms","remote":"127.0.0.1:54226","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-05-30T01:56:29.152056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040949009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:56:29.152101Z","caller":"traceutil/trace.go:171","msg":"trace[862901952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.03967371s","start":"2026-05-30T01:56:28.112402Z","end":"2026-05-30T01:56:29.152076Z","steps":["trace[862901952] 'agreement among raft nodes before linearized reading' (duration: 1.039371214s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:29.15214Z","caller":"traceutil/trace.go:171","msg":"trace[898788247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.041080932s","start":"2026-05-30T01:56:28.11104Z","end":"2026-05-30T01:56:29.152121Z","steps":["trace[898788247] 'agreement among raft nodes before linearized reading' (duration: 1.040831727s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.152174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.11239Z","time spent":"1.039771082s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T01:56:29.152179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.111014Z","time spent":"1.041156014s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T01:56:29.152216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05975486s","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-05-30T01:56:29.15225Z","caller":"traceutil/trace.go:171","msg":"trace[1927333622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"1.059826022s","start":"2026-05-30T01:56:28.092415Z","end":"2026-05-30T01:56:29.152241Z","steps":["trace[1927333622] 'agreement among raft nodes before linearized reading' (duration: 1.05972089s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.152286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.092392Z","time spent":"1.059887424s","remote":"127.0.0.1:54498","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-05-30T01:56:29.152032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.095719Z","time spent":"1.056302922s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T01:56:29.152442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214624834s","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":"warn","ts":"2026-05-30T01:56:29.152435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201535445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-30T01:56:29.152492Z","caller":"traceutil/trace.go:171","msg":"trace[502986770] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"1.214717337s","start":"2026-05-30T01:56:27.937762Z","end":"2026-05-30T01:56:29.15248Z","steps":["trace[502986770] 'agreement among raft nodes before linearized reading' (duration: 1.21398064s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.15253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:27.937737Z","time spent":"1.214784278s","remote":"127.0.0.1:54498","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":"info","ts":"2026-05-30T01:56:29.152524Z","caller":"traceutil/trace.go:171","msg":"trace[192427613] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1344; }","duration":"1.201638678s","start":"2026-05-30T01:56:27.950847Z","end":"2026-05-30T01:56:29.152486Z","steps":["trace[192427613] 'agreement among raft nodes before linearized reading' (duration: 1.201500465s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.152579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:27.950826Z","time spent":"1.2017449s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-30T01:56:29.152766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.75424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-30T01:56:29.152833Z","caller":"traceutil/trace.go:171","msg":"trace[861010037] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"333.853002ms","start":"2026-05-30T01:56:28.818964Z","end":"2026-05-30T01:56:29.152818Z","steps":["trace[861010037] 'agreement among raft nodes before linearized reading' (duration: 332.804749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.152868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.818945Z","time spent":"333.914033ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-30T01:56:29.153148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038462051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-30T01:56:29.153399Z","caller":"traceutil/trace.go:171","msg":"trace[259435082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.039086527s","start":"2026-05-30T01:56:28.114292Z","end":"2026-05-30T01:56:29.153379Z","steps":["trace[259435082] 'agreement among raft nodes before linearized reading' (duration: 1.037593952s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.153542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:28.114282Z","time spent":"1.03924528s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-30T01:56:30.155839Z","caller":"traceutil/trace.go:171","msg":"trace[681235672] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"114.840446ms","start":"2026-05-30T01:56:30.040972Z","end":"2026-05-30T01:56:30.155812Z","steps":["trace[681235672] 'process raft request' (duration: 114.695412ms)"],"step_count":1}