{"level":"warn","ts":"2026-06-28T19:57:43.62786Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:57:43.627954Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-06-28T19:57:43.628023Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:57:43.628036Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-06-28T19:57:43.628056Z","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-06-28T19:57:43.628736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-06-28T19:57:43.628814Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-06-28T19:57:43.636082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.039323ms"} {"level":"info","ts":"2026-06-28T19:57:43.648241Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-06-28T19:57:43.648391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-06-28T19:57:43.648427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-06-28T19:57:43.64844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-28T19:57:43.648451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-06-28T19:57:43.648506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-06-28T19:57:43.660733Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-28T19:57:43.662786Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-28T19:57:43.666905Z","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-06-28T19:57:43.669776Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-28T19:57:43.670818Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-28T19:57:43.671126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-06-28T19:57:43.671248Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-06-28T19:57:43.67132Z","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-06-28T19:57:43.671359Z","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-06-28T19:57:43.673247Z","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-06-28T19:57:43.676206Z","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-06-28T19:57:43.676523Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-28T19:57:43.676563Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-28T19:57:43.676691Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-06-28T19:57:43.676707Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-06-28T19:57:44.349356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-06-28T19:57:44.349408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-28T19:57:44.349441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-06-28T19:57:44.349456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-06-28T19:57:44.349462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-06-28T19:57:44.349469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-06-28T19:57:44.349478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-06-28T19:57:44.350564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:57:44.351796Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:57:44.351807Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-06-28T19:57:44.352022Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:57:44.352124Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:57:44.352107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:57:44.352181Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:57:44.352622Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-28T19:57:44.352699Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-28T19:57:44.355619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-06-28T19:57:44.35818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-28T19:57:52.938355Z","caller":"traceutil/trace.go:171","msg":"trace[424764232] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"276.577014ms","start":"2026-06-28T19:57:52.661749Z","end":"2026-06-28T19:57:52.938326Z","steps":["trace[424764232] 'process raft request' (duration: 276.36599ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:58.425402Z","caller":"traceutil/trace.go:171","msg":"trace[765407735] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"100.813074ms","start":"2026-06-28T19:57:58.32456Z","end":"2026-06-28T19:57:58.425373Z","steps":["trace[765407735] 'process raft request' (duration: 100.652662ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:16.770672Z","caller":"traceutil/trace.go:171","msg":"trace[685366582] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"261.565461ms","start":"2026-06-28T19:58:16.509069Z","end":"2026-06-28T19:58:16.770635Z","steps":["trace[685366582] 'process raft request' (duration: 261.366037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.112575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599818881,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:58:22.155903Z","caller":"traceutil/trace.go:171","msg":"trace[1258507991] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"689.31658ms","start":"2026-06-28T19:58:21.466557Z","end":"2026-06-28T19:58:22.155874Z","steps":["trace[1258507991] 'process raft request' (duration: 689.129867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.156429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.466533Z","time spent":"689.445332ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.165829Z","caller":"traceutil/trace.go:171","msg":"trace[932154056] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"687.284813ms","start":"2026-06-28T19:58:21.478512Z","end":"2026-06-28T19:58:22.165797Z","steps":["trace[932154056] 'process raft request' (duration: 687.008508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.165988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.478505Z","time spent":"687.421225ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.166004Z","caller":"traceutil/trace.go:171","msg":"trace[973182813] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"687.537397ms","start":"2026-06-28T19:58:21.478448Z","end":"2026-06-28T19:58:22.165986Z","steps":["trace[973182813] 'process raft request' (duration: 686.916286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.166068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.478428Z","time spent":"687.607318ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.166153Z","caller":"traceutil/trace.go:171","msg":"trace[57853545] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"671.86388ms","start":"2026-06-28T19:58:21.494281Z","end":"2026-06-28T19:58:22.166145Z","steps":["trace[57853545] 'process raft request' (duration: 671.532244ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:22.166153Z","caller":"traceutil/trace.go:171","msg":"trace[2061887572] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"683.343517ms","start":"2026-06-28T19:58:21.482797Z","end":"2026-06-28T19:58:22.16614Z","steps":["trace[2061887572] 'process raft request' (duration: 682.773106ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:22.166147Z","caller":"traceutil/trace.go:171","msg":"trace[1758102750] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"675.922006ms","start":"2026-06-28T19:58:21.490188Z","end":"2026-06-28T19:58:22.16611Z","steps":["trace[1758102750] 'process raft request' (duration: 675.504669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.166183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.494267Z","time spent":"671.89947ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:58:22.1662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.482782Z","time spent":"683.395678ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:58:22.166234Z","caller":"traceutil/trace.go:171","msg":"trace[1462960458] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"682.196596ms","start":"2026-06-28T19:58:21.483996Z","end":"2026-06-28T19:58:22.166192Z","steps":["trace[1462960458] 'process raft request' (duration: 681.614415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.166288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.49017Z","time spent":"676.028499ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.166281Z","caller":"traceutil/trace.go:171","msg":"trace[1598090681] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"675.911287ms","start":"2026-06-28T19:58:21.490268Z","end":"2026-06-28T19:58:22.166179Z","steps":["trace[1598090681] 'process raft request' (duration: 675.492919ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:22.166233Z","caller":"traceutil/trace.go:171","msg":"trace[466965682] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"667.886536ms","start":"2026-06-28T19:58:21.49834Z","end":"2026-06-28T19:58:22.166226Z","steps":["trace[466965682] 'process raft request' (duration: 667.554729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.166347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.483956Z","time spent":"682.340539ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.166453Z","caller":"traceutil/trace.go:171","msg":"trace[1860878100] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"661.127828ms","start":"2026-06-28T19:58:21.505316Z","end":"2026-06-28T19:58:22.166444Z","steps":["trace[1860878100] 'process raft request' (duration: 660.72433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.166438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.498328Z","time spent":"668.07258ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.166452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.49023Z","time spent":"676.138742ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:58:22.16649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.505305Z","time spent":"661.16677ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:58:22.166687Z","caller":"traceutil/trace.go:171","msg":"trace[1923511911] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:542; }","duration":"555.025584ms","start":"2026-06-28T19:58:21.611624Z","end":"2026-06-28T19:58:22.166649Z","steps":["trace[1923511911] 'read index received' (duration: 544.162948ms)","trace[1923511911] 'applied index is now lower than readState.Index' (duration: 10.861536ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:22.166716Z","caller":"traceutil/trace.go:171","msg":"trace[2115164557] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"646.225267ms","start":"2026-06-28T19:58:21.520469Z","end":"2026-06-28T19:58:22.166694Z","steps":["trace[2115164557] 'process raft request' (duration: 645.714827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.17053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.520457Z","time spent":"650.054529ms","remote":"127.0.0.1:33364","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-06-28T19:58:22.166732Z","caller":"traceutil/trace.go:171","msg":"trace[1907439013] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"514.55957ms","start":"2026-06-28T19:58:21.652156Z","end":"2026-06-28T19:58:22.166716Z","steps":["trace[1907439013] 'process raft request' (duration: 514.350946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.170619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.652123Z","time spent":"518.462843ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:58:22.166836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.207528ms","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-06-28T19:58:22.170768Z","caller":"traceutil/trace.go:171","msg":"trace[1712163389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"559.182593ms","start":"2026-06-28T19:58:21.611577Z","end":"2026-06-28T19:58:22.17076Z","steps":["trace[1712163389] 'agreement among raft nodes before linearized reading' (duration: 555.175267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.170817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.611555Z","time spent":"559.250104ms","remote":"127.0.0.1:33548","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-06-28T19:58:22.175233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.890653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T19:58:22.175278Z","caller":"traceutil/trace.go:171","msg":"trace[805781230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"309.991075ms","start":"2026-06-28T19:58:21.865274Z","end":"2026-06-28T19:58:22.175265Z","steps":["trace[805781230] 'agreement among raft nodes before linearized reading' (duration: 309.898913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:22.175303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:21.865251Z","time spent":"310.046936ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-28T19:58:28.41162Z","caller":"traceutil/trace.go:171","msg":"trace[1744764206] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"181.727522ms","start":"2026-06-28T19:58:28.229861Z","end":"2026-06-28T19:58:28.411588Z","steps":["trace[1744764206] 'process raft request' (duration: 111.109299ms)","trace[1744764206] 'compare' (duration: 70.495211ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:29.540939Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.199986697s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-28T19:58:31.193673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000338944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-28T19:58:31.193782Z","caller":"traceutil/trace.go:171","msg":"trace[750446802] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000486177s","start":"2026-06-28T19:58:29.193264Z","end":"2026-06-28T19:58:31.19375Z","steps":["trace[750446802] 'agreement among raft nodes before linearized reading' (duration: 2.000362555s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:31.193831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:29.193245Z","time spent":"2.000575329s","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-28T19:58:32.369718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22720377s","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-06-28T19:58:32.369836Z","caller":"traceutil/trace.go:171","msg":"trace[100655693] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:634; }","duration":"2.227321502s","start":"2026-06-28T19:58:30.142485Z","end":"2026-06-28T19:58:32.369807Z","steps":["trace[100655693] 'range keys from in-memory index tree' (duration: 2.22717545s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.374093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.832454542s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:58:32.374297Z","caller":"traceutil/trace.go:171","msg":"trace[56240917] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"4.141444208s","start":"2026-06-28T19:58:28.232828Z","end":"2026-06-28T19:58:32.374272Z","steps":["trace[56240917] 'process raft request' (duration: 1.308353604s)","trace[56240917] 'compare' (duration: 2.824888519s)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:32.374347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:28.232801Z","time spent":"4.141524029s","remote":"127.0.0.1:33548","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-06-28T19:58:32.374542Z","caller":"traceutil/trace.go:171","msg":"trace[54464709] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"4.081566867s","start":"2026-06-28T19:58:28.292956Z","end":"2026-06-28T19:58:32.374523Z","steps":["trace[54464709] 'process raft request' (duration: 4.081246761s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:32.374632Z","caller":"traceutil/trace.go:171","msg":"trace[2078707354] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.415263183s","start":"2026-06-28T19:58:29.959342Z","end":"2026-06-28T19:58:32.374605Z","steps":["trace[2078707354] 'process raft request' (duration: 2.41517019s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.374664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:28.292941Z","time spent":"4.081654839s","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:58:32.374757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:29.959316Z","time spent":"2.415378405s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:58:32.374896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.057298708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-28T19:58:32.374929Z","caller":"traceutil/trace.go:171","msg":"trace[506901428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"4.057332479s","start":"2026-06-28T19:58:28.317588Z","end":"2026-06-28T19:58:32.374921Z","steps":["trace[506901428] 'agreement among raft nodes before linearized reading' (duration: 4.057200497s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.374959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:28.317505Z","time spent":"4.057448092s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-28T19:58:32.375192Z","caller":"traceutil/trace.go:171","msg":"trace[1928859116] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"4.056993322s","start":"2026-06-28T19:58:28.317599Z","end":"2026-06-28T19:58:32.374592Z","steps":["trace[1928859116] 'read index received' (duration: 23.388412ms)","trace[1928859116] 'applied index is now lower than readState.Index' (duration: 4.03360275s)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:32.375881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.985800097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bkm7w\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-06-28T19:58:32.375919Z","caller":"traceutil/trace.go:171","msg":"trace[584540113] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bkm7w; range_end:; response_count:1; response_revision:637; }","duration":"3.985901059s","start":"2026-06-28T19:58:28.390006Z","end":"2026-06-28T19:58:32.375907Z","steps":["trace[584540113] 'agreement among raft nodes before linearized reading' (duration: 3.985764986s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.375944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:28.389988Z","time spent":"3.98595102s","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-bkm7w\" "} {"level":"warn","ts":"2026-06-28T19:58:32.376042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181149621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:32.376078Z","caller":"traceutil/trace.go:171","msg":"trace[1217855211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.181235263s","start":"2026-06-28T19:58:31.194829Z","end":"2026-06-28T19:58:32.376064Z","steps":["trace[1217855211] 'agreement among raft nodes before linearized reading' (duration: 1.181179202s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.376099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:31.194795Z","time spent":"1.181299974s","remote":"127.0.0.1:33296","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-06-28T19:58:32.376105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960496622s","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-06-28T19:58:32.376127Z","caller":"traceutil/trace.go:171","msg":"trace[2077158608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"1.960564414s","start":"2026-06-28T19:58:30.415555Z","end":"2026-06-28T19:58:32.376119Z","steps":["trace[2077158608] 'agreement among raft nodes before linearized reading' (duration: 1.960498113s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.376149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:30.415518Z","time spent":"1.960627884s","remote":"127.0.0.1:33548","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-06-28T19:58:32.376248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.008438367s","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-06-28T19:58:32.376271Z","caller":"traceutil/trace.go:171","msg":"trace[385552485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"3.008488607s","start":"2026-06-28T19:58:29.367776Z","end":"2026-06-28T19:58:32.376265Z","steps":["trace[385552485] 'agreement among raft nodes before linearized reading' (duration: 3.008449967s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.376289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:29.367756Z","time spent":"3.008529559s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T19:58:32.906526Z","caller":"traceutil/trace.go:171","msg":"trace[1048687265] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"528.36541ms","start":"2026-06-28T19:58:32.378104Z","end":"2026-06-28T19:58:32.906469Z","steps":["trace[1048687265] 'process raft request' (duration: 472.73939ms)","trace[1048687265] 'compare' (duration: 55.350895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:32.906983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.378085Z","time spent":"528.774948ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:58:32.908334Z","caller":"traceutil/trace.go:171","msg":"trace[336032145] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"528.052684ms","start":"2026-06-28T19:58:32.380247Z","end":"2026-06-28T19:58:32.9083Z","steps":["trace[336032145] 'process raft request' (duration: 527.766988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.908461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.380237Z","time spent":"528.162666ms","remote":"127.0.0.1:33384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:58:32.908605Z","caller":"traceutil/trace.go:171","msg":"trace[1204527584] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"528.145336ms","start":"2026-06-28T19:58:32.380436Z","end":"2026-06-28T19:58:32.908581Z","steps":["trace[1204527584] 'process raft request' (duration: 527.635607ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:32.908703Z","caller":"traceutil/trace.go:171","msg":"trace[2111036384] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"530.040352ms","start":"2026-06-28T19:58:32.378654Z","end":"2026-06-28T19:58:32.908694Z","steps":["trace[2111036384] 'process raft request' (duration: 529.279247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.908717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.380426Z","time spent":"528.240238ms","remote":"127.0.0.1:33548","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-06-28T19:58:32.908754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.378644Z","time spent":"530.078503ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:58:32.90886Z","caller":"traceutil/trace.go:171","msg":"trace[1982217158] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"522.325046ms","start":"2026-06-28T19:58:32.38652Z","end":"2026-06-28T19:58:32.908845Z","steps":["trace[1982217158] 'process raft request' (duration: 521.606083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.908944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.386475Z","time spent":"522.428388ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:58:32.922097Z","caller":"traceutil/trace.go:171","msg":"trace[331538208] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:658; }","duration":"538.34157ms","start":"2026-06-28T19:58:32.383732Z","end":"2026-06-28T19:58:32.922073Z","steps":["trace[331538208] 'read index received' (duration: 467.128914ms)","trace[331538208] 'applied index is now lower than readState.Index' (duration: 71.211475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:32.922259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.509432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:32.922292Z","caller":"traceutil/trace.go:171","msg":"trace[109185264] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:642; }","duration":"538.584253ms","start":"2026-06-28T19:58:32.383699Z","end":"2026-06-28T19:58:32.922283Z","steps":["trace[109185264] 'agreement among raft nodes before linearized reading' (duration: 538.504912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.922324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.38369Z","time spent":"538.627445ms","remote":"127.0.0.1:33522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-28T19:58:32.922445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.519173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:32.92251Z","caller":"traceutil/trace.go:171","msg":"trace[1282645294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"537.602355ms","start":"2026-06-28T19:58:32.384892Z","end":"2026-06-28T19:58:32.922495Z","steps":["trace[1282645294] 'agreement among raft nodes before linearized reading' (duration: 537.511443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:32.922555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:32.384879Z","time spent":"537.668026ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T19:58:37.587815Z","caller":"traceutil/trace.go:171","msg":"trace[91281084] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"132.910781ms","start":"2026-06-28T19:58:37.454876Z","end":"2026-06-28T19:58:37.587787Z","steps":["trace[91281084] 'process raft request' (duration: 126.919558ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:37.912797Z","caller":"traceutil/trace.go:171","msg":"trace[380810330] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"454.184688ms","start":"2026-06-28T19:58:37.458586Z","end":"2026-06-28T19:58:37.91277Z","steps":["trace[380810330] 'process raft request' (duration: 453.507295ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:37.91291Z","caller":"traceutil/trace.go:171","msg":"trace[946244649] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:738; }","duration":"387.304997ms","start":"2026-06-28T19:58:37.525582Z","end":"2026-06-28T19:58:37.912887Z","steps":["trace[946244649] 'read index received' (duration: 56.282534ms)","trace[946244649] 'applied index is now lower than readState.Index' (duration: 331.019712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:37.912966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:37.458569Z","time spent":"454.304081ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:58:37.912967Z","caller":"traceutil/trace.go:171","msg":"trace[977273742] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"378.317016ms","start":"2026-06-28T19:58:37.534586Z","end":"2026-06-28T19:58:37.912903Z","steps":["trace[977273742] 'process raft request' (duration: 378.089222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:37.913131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.537561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-28T19:58:37.913195Z","caller":"traceutil/trace.go:171","msg":"trace[1409726376] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:718; }","duration":"387.634662ms","start":"2026-06-28T19:58:37.525543Z","end":"2026-06-28T19:58:37.913178Z","steps":["trace[1409726376] 'agreement among raft nodes before linearized reading' (duration: 387.474109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:37.913222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:37.534558Z","time spent":"378.54421ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:58:37.913239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:37.525527Z","time spent":"387.699874ms","remote":"127.0.0.1:33346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-06-28T19:58:37.913499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.624283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-06-28T19:58:37.913561Z","caller":"traceutil/trace.go:171","msg":"trace[1944729427] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:718; }","duration":"205.745017ms","start":"2026-06-28T19:58:37.7078Z","end":"2026-06-28T19:58:37.913545Z","steps":["trace[1944729427] 'agreement among raft nodes before linearized reading' (duration: 205.581643ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:38.180262Z","caller":"traceutil/trace.go:171","msg":"trace[2012009715] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"168.333059ms","start":"2026-06-28T19:58:38.01188Z","end":"2026-06-28T19:58:38.180213Z","steps":["trace[2012009715] 'process raft request' (duration: 150.758736ms)","trace[2012009715] 'compare' (duration: 17.311378ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:38.385639Z","caller":"traceutil/trace.go:171","msg":"trace[1946222696] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"126.51513ms","start":"2026-06-28T19:58:38.259069Z","end":"2026-06-28T19:58:38.385584Z","steps":["trace[1946222696] 'process raft request' (duration: 125.363819ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:38.768267Z","caller":"traceutil/trace.go:171","msg":"trace[1593227259] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"317.749622ms","start":"2026-06-28T19:58:38.45048Z","end":"2026-06-28T19:58:38.76823Z","steps":["trace[1593227259] 'read index received' (duration: 313.371129ms)","trace[1593227259] 'applied index is now lower than readState.Index' (duration: 4.376523ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:38.768645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.131338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bkm7w\" ","response":"range_response_count:1 size:18329"} {"level":"info","ts":"2026-06-28T19:58:38.768358Z","caller":"traceutil/trace.go:171","msg":"trace[1943323503] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"324.940268ms","start":"2026-06-28T19:58:38.443298Z","end":"2026-06-28T19:58:38.768238Z","steps":["trace[1943323503] 'process raft request' (duration: 320.559695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:38.768739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.829265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-06-28T19:58:38.768772Z","caller":"traceutil/trace.go:171","msg":"trace[522634691] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bkm7w; range_end:; response_count:1; response_revision:726; }","duration":"318.295052ms","start":"2026-06-28T19:58:38.450438Z","end":"2026-06-28T19:58:38.768733Z","steps":["trace[522634691] 'agreement among raft nodes before linearized reading' (duration: 317.912915ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:38.768785Z","caller":"traceutil/trace.go:171","msg":"trace[1369708983] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"261.940968ms","start":"2026-06-28T19:58:38.506831Z","end":"2026-06-28T19:58:38.768772Z","steps":["trace[1369708983] 'agreement among raft nodes before linearized reading' (duration: 261.605461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:38.768833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:38.450424Z","time spent":"318.399083ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18352,"request content":"key:\"/registry/pods/kube-system/cilium-bkm7w\" "} {"level":"warn","ts":"2026-06-28T19:58:38.770019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:38.443268Z","time spent":"325.538118ms","remote":"127.0.0.1:33548","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-06-28T19:58:46.718857Z","caller":"traceutil/trace.go:171","msg":"trace[810507301] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"211.531431ms","start":"2026-06-28T19:58:46.507286Z","end":"2026-06-28T19:58:46.718817Z","steps":["trace[810507301] 'process raft request' (duration: 210.655375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:49.694171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599819458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:58:50.194313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599819458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:58:50.394502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.887694ms","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-06-28T19:58:50.394631Z","caller":"traceutil/trace.go:171","msg":"trace[384834171] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:806; }","duration":"252.027447ms","start":"2026-06-28T19:58:50.142584Z","end":"2026-06-28T19:58:50.394611Z","steps":["trace[384834171] 'range keys from in-memory index tree' (duration: 251.869804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:50.395001Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.382381416s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:58:50.39558Z","caller":"traceutil/trace.go:171","msg":"trace[990859993] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.412287001s","start":"2026-06-28T19:58:48.983262Z","end":"2026-06-28T19:58:50.395549Z","steps":["trace[990859993] 'process raft request' (duration: 1.412198969s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:50.395764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:48.983242Z","time spent":"1.412407102s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:58:50.397062Z","caller":"traceutil/trace.go:171","msg":"trace[249031935] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:834; }","duration":"1.203866419s","start":"2026-06-28T19:58:49.193177Z","end":"2026-06-28T19:58:50.397044Z","steps":["trace[249031935] 'read index received' (duration: 1.202086455s)","trace[249031935] 'applied index is now lower than readState.Index' (duration: 1.779114ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:50.396319Z","caller":"traceutil/trace.go:171","msg":"trace[2059547814] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.41433993s","start":"2026-06-28T19:58:48.981963Z","end":"2026-06-28T19:58:50.396303Z","steps":["trace[2059547814] 'process raft request' (duration: 1.413232109s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:50.39731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:48.981904Z","time spent":"1.41535494s","remote":"127.0.0.1:33548","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-06-28T19:58:50.397282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204057023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:58:50.397421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.581589ms","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-06-28T19:58:50.397532Z","caller":"traceutil/trace.go:171","msg":"trace[1678593238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"140.728562ms","start":"2026-06-28T19:58:50.25678Z","end":"2026-06-28T19:58:50.397508Z","steps":["trace[1678593238] 'agreement among raft nodes before linearized reading' (duration: 140.375306ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:50.39793Z","caller":"traceutil/trace.go:171","msg":"trace[103965217] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"872.515192ms","start":"2026-06-28T19:58:49.525393Z","end":"2026-06-28T19:58:50.397908Z","steps":["trace[103965217] 'process raft request' (duration: 871.551454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:50.398058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:49.525363Z","time spent":"872.621154ms","remote":"127.0.0.1:33522","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-06-28T19:58:50.398614Z","caller":"traceutil/trace.go:171","msg":"trace[425528954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"1.204305188s","start":"2026-06-28T19:58:49.193071Z","end":"2026-06-28T19:58:50.397376Z","steps":["trace[425528954] 'agreement among raft nodes before linearized reading' (duration: 1.204090184s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:50.398717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:49.192993Z","time spent":"1.205706455s","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T19:58:53.719863Z","caller":"traceutil/trace.go:171","msg":"trace[1758308771] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"112.631491ms","start":"2026-06-28T19:58:53.607203Z","end":"2026-06-28T19:58:53.719835Z","steps":["trace[1758308771] 'process raft request' (duration: 111.828505ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:53.890116Z","caller":"traceutil/trace.go:171","msg":"trace[2029174713] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"135.223888ms","start":"2026-06-28T19:58:53.754617Z","end":"2026-06-28T19:58:53.889841Z","steps":["trace[2029174713] 'process raft request' (duration: 127.9002ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.035293Z","caller":"traceutil/trace.go:171","msg":"trace[518193935] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"139.158112ms","start":"2026-06-28T19:58:53.896119Z","end":"2026-06-28T19:58:54.035277Z","steps":["trace[518193935] 'process raft request' (duration: 139.119151ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.035391Z","caller":"traceutil/trace.go:171","msg":"trace[830760426] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"140.022208ms","start":"2026-06-28T19:58:53.895347Z","end":"2026-06-28T19:58:54.035369Z","steps":["trace[830760426] 'process raft request' (duration: 139.302724ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.09698Z","caller":"traceutil/trace.go:171","msg":"trace[1559706816] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"116.508143ms","start":"2026-06-28T19:58:53.980446Z","end":"2026-06-28T19:58:54.096954Z","steps":["trace[1559706816] 'process raft request' (duration: 116.35251ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.097185Z","caller":"traceutil/trace.go:171","msg":"trace[1975372795] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"115.037526ms","start":"2026-06-28T19:58:53.982137Z","end":"2026-06-28T19:58:54.097175Z","steps":["trace[1975372795] 'process raft request' (duration: 114.787832ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.097409Z","caller":"traceutil/trace.go:171","msg":"trace[832520352] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"116.508584ms","start":"2026-06-28T19:58:53.980493Z","end":"2026-06-28T19:58:54.097002Z","steps":["trace[832520352] 'process raft request' (duration: 116.402192ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.244827Z","caller":"traceutil/trace.go:171","msg":"trace[939166405] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"140.417437ms","start":"2026-06-28T19:58:54.104381Z","end":"2026-06-28T19:58:54.244799Z","steps":["trace[939166405] 'read index received' (duration: 98.871881ms)","trace[939166405] 'applied index is now lower than readState.Index' (duration: 41.542626ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:54.245174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.756693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-cl64t\" ","response":"range_response_count:1 size:4495"} {"level":"info","ts":"2026-06-28T19:58:54.24495Z","caller":"traceutil/trace.go:171","msg":"trace[1178434327] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"140.760733ms","start":"2026-06-28T19:58:54.104142Z","end":"2026-06-28T19:58:54.244902Z","steps":["trace[1178434327] 'process raft request' (duration: 99.026073ms)","trace[1178434327] 'compare' (duration: 41.481424ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:54.245295Z","caller":"traceutil/trace.go:171","msg":"trace[1141522266] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-cl64t; range_end:; response_count:1; response_revision:848; }","duration":"141.578597ms","start":"2026-06-28T19:58:54.103682Z","end":"2026-06-28T19:58:54.245261Z","steps":["trace[1141522266] 'agreement among raft nodes before linearized reading' (duration: 141.252961ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.375738Z","caller":"traceutil/trace.go:171","msg":"trace[1623032941] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"271.18589ms","start":"2026-06-28T19:58:54.104495Z","end":"2026-06-28T19:58:54.37568Z","steps":["trace[1623032941] 'process raft request' (duration: 247.056664ms)","trace[1623032941] 'compare' (duration: 22.455734ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:54.376154Z","caller":"traceutil/trace.go:171","msg":"trace[996938102] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"269.078819ms","start":"2026-06-28T19:58:54.106986Z","end":"2026-06-28T19:58:54.376065Z","steps":["trace[996938102] 'process raft request' (duration: 267.284875ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.383648Z","caller":"traceutil/trace.go:171","msg":"trace[610022500] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"130.005689ms","start":"2026-06-28T19:58:54.253615Z","end":"2026-06-28T19:58:54.38362Z","steps":["trace[610022500] 'process raft request' (duration: 129.935338ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:54.383724Z","caller":"traceutil/trace.go:171","msg":"trace[1361244745] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:877; }","duration":"138.759604ms","start":"2026-06-28T19:58:54.244905Z","end":"2026-06-28T19:58:54.383664Z","steps":["trace[1361244745] 'read index received' (duration: 106.660707ms)","trace[1361244745] 'applied index is now lower than readState.Index' (duration: 32.094327ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:54.383891Z","caller":"traceutil/trace.go:171","msg":"trace[910497841] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"255.768278ms","start":"2026-06-28T19:58:54.128108Z","end":"2026-06-28T19:58:54.383877Z","steps":["trace[910497841] 'process raft request' (duration: 248.664944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:54.384042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.599614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:54.384077Z","caller":"traceutil/trace.go:171","msg":"trace[379395655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"191.699416ms","start":"2026-06-28T19:58:54.192367Z","end":"2026-06-28T19:58:54.384067Z","steps":["trace[379395655] 'agreement among raft nodes before linearized reading' (duration: 191.544524ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:00.75192Z","caller":"traceutil/trace.go:171","msg":"trace[1209230896] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"107.662676ms","start":"2026-06-28T19:59:00.644232Z","end":"2026-06-28T19:59:00.751894Z","steps":["trace[1209230896] 'process raft request' (duration: 100.720545ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:04.752107Z","caller":"traceutil/trace.go:171","msg":"trace[1780716540] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"116.260089ms","start":"2026-06-28T19:59:04.635785Z","end":"2026-06-28T19:59:04.752045Z","steps":["trace[1780716540] 'process raft request' (duration: 96.962703ms)","trace[1780716540] 'compare' (duration: 19.133603ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:04.897901Z","caller":"traceutil/trace.go:171","msg":"trace[1073866508] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"116.773389ms","start":"2026-06-28T19:59:04.781094Z","end":"2026-06-28T19:59:04.897868Z","steps":["trace[1073866508] 'read index received' (duration: 71.172087ms)","trace[1073866508] 'applied index is now lower than readState.Index' (duration: 45.599792ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:04.898044Z","caller":"traceutil/trace.go:171","msg":"trace[1561203161] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"137.565842ms","start":"2026-06-28T19:59:04.760401Z","end":"2026-06-28T19:59:04.897967Z","steps":["trace[1561203161] 'process raft request' (duration: 137.413179ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:04.898103Z","caller":"traceutil/trace.go:171","msg":"trace[576762110] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"141.466546ms","start":"2026-06-28T19:59:04.756581Z","end":"2026-06-28T19:59:04.898048Z","steps":["trace[576762110] 'process raft request' (duration: 95.738552ms)","trace[576762110] 'compare' (duration: 45.356197ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:59:04.898241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.120125ms","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-06-28T19:59:04.898281Z","caller":"traceutil/trace.go:171","msg":"trace[852196577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"117.236447ms","start":"2026-06-28T19:59:04.781033Z","end":"2026-06-28T19:59:04.898269Z","steps":["trace[852196577] 'agreement among raft nodes before linearized reading' (duration: 117.044334ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:17.047877Z","caller":"traceutil/trace.go:171","msg":"trace[782436416] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"157.363356ms","start":"2026-06-28T19:59:16.890486Z","end":"2026-06-28T19:59:17.047849Z","steps":["trace[782436416] 'process raft request' (duration: 157.141742ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:19.270259Z","caller":"traceutil/trace.go:171","msg":"trace[1681979317] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"109.258407ms","start":"2026-06-28T19:59:19.160982Z","end":"2026-06-28T19:59:19.270241Z","steps":["trace[1681979317] 'read index received' (duration: 107.620766ms)","trace[1681979317] 'applied index is now lower than readState.Index' (duration: 1.637101ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:19.270352Z","caller":"traceutil/trace.go:171","msg":"trace[182012228] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"111.072151ms","start":"2026-06-28T19:59:19.159272Z","end":"2026-06-28T19:59:19.270344Z","steps":["trace[182012228] 'process raft request' (duration: 109.250706ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:19.270437Z","caller":"traceutil/trace.go:171","msg":"trace[362695620] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"110.626682ms","start":"2026-06-28T19:59:19.159802Z","end":"2026-06-28T19:59:19.270429Z","steps":["trace[362695620] 'process raft request' (duration: 110.407778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:19.27057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.573862ms","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-06-28T19:59:19.270592Z","caller":"traceutil/trace.go:171","msg":"trace[312452928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"109.637343ms","start":"2026-06-28T19:59:19.160947Z","end":"2026-06-28T19:59:19.270584Z","steps":["trace[312452928] 'agreement among raft nodes before linearized reading' (duration: 109.531791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:19.668207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.269464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:59:19.668376Z","caller":"traceutil/trace.go:171","msg":"trace[751080616] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"185.966627ms","start":"2026-06-28T19:59:19.482391Z","end":"2026-06-28T19:59:19.668358Z","steps":["trace[751080616] 'process raft request' (duration: 185.899575ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:19.66844Z","caller":"traceutil/trace.go:171","msg":"trace[260143859] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"393.256558ms","start":"2026-06-28T19:59:19.275151Z","end":"2026-06-28T19:59:19.668407Z","steps":["trace[260143859] 'process raft request' (duration: 254.697888ms)","trace[260143859] 'compare' (duration: 137.934688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:59:19.668582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:19.275126Z","time spent":"393.38255ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:59:25.188937Z","caller":"traceutil/trace.go:171","msg":"trace[1036413189] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"101.567721ms","start":"2026-06-28T19:59:25.087336Z","end":"2026-06-28T19:59:25.188904Z","steps":["trace[1036413189] 'process raft request' (duration: 101.407958ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:26.079456Z","caller":"traceutil/trace.go:171","msg":"trace[1068358897] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"762.99255ms","start":"2026-06-28T19:59:25.316431Z","end":"2026-06-28T19:59:26.079423Z","steps":["trace[1068358897] 'process raft request' (duration: 762.829167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:26.079616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:25.316411Z","time spent":"763.120864ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:59:26.986761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.130811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:59:26.987022Z","caller":"traceutil/trace.go:171","msg":"trace[1043038338] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1055; }","duration":"1.290450018s","start":"2026-06-28T19:59:25.696544Z","end":"2026-06-28T19:59:26.986994Z","steps":["trace[1043038338] 'read index received' (duration: 382.728739ms)","trace[1043038338] 'applied index is now lower than readState.Index' (duration: 907.719679ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:26.987051Z","caller":"traceutil/trace.go:171","msg":"trace[190056135] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.294533776s","start":"2026-06-28T19:59:25.692488Z","end":"2026-06-28T19:59:26.987021Z","steps":["trace[190056135] 'process raft request' (duration: 1.294420534s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:26.987117Z","caller":"traceutil/trace.go:171","msg":"trace[2091104696] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.666124692s","start":"2026-06-28T19:59:25.320972Z","end":"2026-06-28T19:59:26.987097Z","steps":["trace[2091104696] 'process raft request' (duration: 1.030541302s)","trace[2091104696] 'compare' (duration: 634.948338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:59:26.987206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:25.692456Z","time spent":"1.294669199s","remote":"127.0.0.1:33548","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-06-28T19:59:26.987208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:25.320944Z","time spent":"1.666212243s","remote":"127.0.0.1:33548","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-06-28T19:59:26.987268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290722603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-28T19:59:26.987252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.584398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:59:26.987313Z","caller":"traceutil/trace.go:171","msg":"trace[1253457747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"1.290800025s","start":"2026-06-28T19:59:25.696499Z","end":"2026-06-28T19:59:26.987299Z","steps":["trace[1253457747] 'agreement among raft nodes before linearized reading' (duration: 1.290635392s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:26.987348Z","caller":"traceutil/trace.go:171","msg":"trace[1985022507] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1023; }","duration":"933.74038ms","start":"2026-06-28T19:59:26.053584Z","end":"2026-06-28T19:59:26.987324Z","steps":["trace[1985022507] 'agreement among raft nodes before linearized reading' (duration: 933.601568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:26.987365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:25.696482Z","time spent":"1.290874296s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T19:59:26.987397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:26.053556Z","time spent":"933.830313ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-06-28T19:59:31.322409Z","caller":"traceutil/trace.go:171","msg":"trace[1569942002] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"104.430875ms","start":"2026-06-28T19:59:31.217944Z","end":"2026-06-28T19:59:31.322375Z","steps":["trace[1569942002] 'process raft request' (duration: 104.15437ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:49.21418Z","caller":"traceutil/trace.go:171","msg":"trace[1556204926] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"100.340922ms","start":"2026-06-28T19:59:49.113808Z","end":"2026-06-28T19:59:49.214149Z","steps":["trace[1556204926] 'process raft request' (duration: 100.130338ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:51.612289Z","caller":"traceutil/trace.go:171","msg":"trace[275479409] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"366.657423ms","start":"2026-06-28T19:59:51.245609Z","end":"2026-06-28T19:59:51.612267Z","steps":["trace[275479409] 'read index received' (duration: 366.436199ms)","trace[275479409] 'applied index is now lower than readState.Index' (duration: 220.064µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:51.612378Z","caller":"traceutil/trace.go:171","msg":"trace[1261130570] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"393.152495ms","start":"2026-06-28T19:59:51.219173Z","end":"2026-06-28T19:59:51.612326Z","steps":["trace[1261130570] 'process raft request' (duration: 392.936701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:51.612495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.879037ms","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-06-28T19:59:51.612524Z","caller":"traceutil/trace.go:171","msg":"trace[188067682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"366.992479ms","start":"2026-06-28T19:59:51.245523Z","end":"2026-06-28T19:59:51.612516Z","steps":["trace[188067682] 'agreement among raft nodes before linearized reading' (duration: 366.864517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:51.612553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:51.24549Z","time spent":"367.056382ms","remote":"127.0.0.1:33548","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-06-28T19:59:51.612644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:51.219139Z","time spent":"393.353159ms","remote":"127.0.0.1:33548","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-06-28T19:59:51.612858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.897442ms","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-06-28T19:59:51.612887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.434591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T19:59:51.61296Z","caller":"traceutil/trace.go:171","msg":"trace[688568283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"344.050755ms","start":"2026-06-28T19:59:51.268885Z","end":"2026-06-28T19:59:51.612936Z","steps":["trace[688568283] 'agreement among raft nodes before linearized reading' (duration: 343.759849ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:51.612969Z","caller":"traceutil/trace.go:171","msg":"trace[1636862771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"137.577764ms","start":"2026-06-28T19:59:51.475369Z","end":"2026-06-28T19:59:51.612947Z","steps":["trace[1636862771] 'agreement among raft nodes before linearized reading' (duration: 137.335729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:51.613019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:51.268871Z","time spent":"344.137706ms","remote":"127.0.0.1:33548","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-06-28T19:59:51.8951Z","caller":"traceutil/trace.go:171","msg":"trace[856635377] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"276.806964ms","start":"2026-06-28T19:59:51.618257Z","end":"2026-06-28T19:59:51.895064Z","steps":["trace[856635377] 'process raft request' (duration: 245.957502ms)","trace[856635377] 'compare' (duration: 30.079208ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:51.895692Z","caller":"traceutil/trace.go:171","msg":"trace[534693400] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1142; }","duration":"275.43928ms","start":"2026-06-28T19:59:51.620231Z","end":"2026-06-28T19:59:51.89567Z","steps":["trace[534693400] 'read index received' (duration: 244.110998ms)","trace[534693400] 'applied index is now lower than readState.Index' (duration: 31.327332ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:51.895714Z","caller":"traceutil/trace.go:171","msg":"trace[931371448] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"277.009799ms","start":"2026-06-28T19:59:51.618651Z","end":"2026-06-28T19:59:51.895661Z","steps":["trace[931371448] 'process raft request' (duration: 275.777396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:51.895908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.112825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-28T19:59:51.895954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.698114ms","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-06-28T19:59:51.895969Z","caller":"traceutil/trace.go:171","msg":"trace[1687376117] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1105; }","duration":"144.244467ms","start":"2026-06-28T19:59:51.751709Z","end":"2026-06-28T19:59:51.895953Z","steps":["trace[1687376117] 'agreement among raft nodes before linearized reading' (duration: 144.141975ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:51.896023Z","caller":"traceutil/trace.go:171","msg":"trace[66894160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"275.791977ms","start":"2026-06-28T19:59:51.62021Z","end":"2026-06-28T19:59:51.896002Z","steps":["trace[66894160] 'agreement among raft nodes before linearized reading' (duration: 275.606823ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:51.895771Z","caller":"traceutil/trace.go:171","msg":"trace[1183157736] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"276.183403ms","start":"2026-06-28T19:59:51.619549Z","end":"2026-06-28T19:59:51.895732Z","steps":["trace[1183157736] 'process raft request' (duration: 276.03693ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:52.448529Z","caller":"traceutil/trace.go:171","msg":"trace[216451088] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"165.378328ms","start":"2026-06-28T19:59:52.283118Z","end":"2026-06-28T19:59:52.448496Z","steps":["trace[216451088] 'process raft request' (duration: 165.194524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:53.694193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:59:54.00938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446899439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:59:54.009503Z","caller":"traceutil/trace.go:171","msg":"trace[566688924] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1107; }","duration":"1.447071043s","start":"2026-06-28T19:59:52.562405Z","end":"2026-06-28T19:59:54.009476Z","steps":["trace[566688924] 'count revisions from in-memory index tree' (duration: 1.446791366s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.009565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:52.56237Z","time spent":"1.447177034s","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-28T19:59:54.195299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:59:54.420827Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.600474555s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-28T19:59:54.677857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.764125ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:59:54.719561Z","caller":"traceutil/trace.go:171","msg":"trace[556419703] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1147; }","duration":"1.525670687s","start":"2026-06-28T19:59:53.193863Z","end":"2026-06-28T19:59:54.719533Z","steps":["trace[556419703] 'read index received' (duration: 1.22726136s)","trace[556419703] 'applied index is now lower than readState.Index' (duration: 298.408137ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:54.719602Z","caller":"traceutil/trace.go:171","msg":"trace[1897418056] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"1.100635969s","start":"2026-06-28T19:59:53.61893Z","end":"2026-06-28T19:59:54.719566Z","steps":["trace[1897418056] 'process raft request' (duration: 1.100381734s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:54.719776Z","caller":"traceutil/trace.go:171","msg":"trace[788667525] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"579.384689ms","start":"2026-06-28T19:59:54.140242Z","end":"2026-06-28T19:59:54.719626Z","steps":["trace[788667525] 'process raft request' (duration: 579.238756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.719895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.038296ms","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-06-28T19:59:54.719932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.130216ms","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-06-28T19:59:54.719932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:53.618903Z","time spent":"1.100757832s","remote":"127.0.0.1:33548","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-06-28T19:59:54.719971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.102662ms","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-06-28T19:59:54.72001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:54.140212Z","time spent":"579.678694ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:59:54.720038Z","caller":"traceutil/trace.go:171","msg":"trace[67551788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1109; }","duration":"815.195383ms","start":"2026-06-28T19:59:53.904826Z","end":"2026-06-28T19:59:54.720021Z","steps":["trace[67551788] 'agreement among raft nodes before linearized reading' (duration: 814.99587ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:54.719986Z","caller":"traceutil/trace.go:171","msg":"trace[499253001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1109; }","duration":"266.187169ms","start":"2026-06-28T19:59:54.453779Z","end":"2026-06-28T19:59:54.719966Z","steps":["trace[499253001] 'agreement among raft nodes before linearized reading' (duration: 265.937945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.72009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:53.904812Z","time spent":"815.266985ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T19:59:54.719983Z","caller":"traceutil/trace.go:171","msg":"trace[2075910018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"819.216948ms","start":"2026-06-28T19:59:53.900755Z","end":"2026-06-28T19:59:54.719972Z","steps":["trace[2075910018] 'agreement among raft nodes before linearized reading' (duration: 819.108866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.720195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52633574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:59:54.720204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:53.900743Z","time spent":"819.450312ms","remote":"127.0.0.1:33548","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":"info","ts":"2026-06-28T19:59:54.720249Z","caller":"traceutil/trace.go:171","msg":"trace[2013553725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"1.526425971s","start":"2026-06-28T19:59:53.193809Z","end":"2026-06-28T19:59:54.720235Z","steps":["trace[2013553725] 'agreement among raft nodes before linearized reading' (duration: 1.525881281s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.719838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.211638ms","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-06-28T19:59:54.720287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:53.193785Z","time spent":"1.526496733s","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T19:59:54.720316Z","caller":"traceutil/trace.go:171","msg":"trace[348370826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"819.750498ms","start":"2026-06-28T19:59:53.900553Z","end":"2026-06-28T19:59:54.720304Z","steps":["trace[348370826] 'agreement among raft nodes before linearized reading' (duration: 819.140677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.720357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:53.90053Z","time spent":"819.815719ms","remote":"127.0.0.1:33548","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-06-28T19:59:54.720428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.343731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T19:59:54.720543Z","caller":"traceutil/trace.go:171","msg":"trace[929812886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"819.716907ms","start":"2026-06-28T19:59:53.900811Z","end":"2026-06-28T19:59:54.720528Z","steps":["trace[929812886] 'agreement among raft nodes before linearized reading' (duration: 818.996894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.720631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:53.90064Z","time spent":"819.961652ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-28T19:59:54.916442Z","caller":"traceutil/trace.go:171","msg":"trace[1197956070] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1150; }","duration":"185.871168ms","start":"2026-06-28T19:59:54.730545Z","end":"2026-06-28T19:59:54.916416Z","steps":["trace[1197956070] 'read index received' (duration: 47.084043ms)","trace[1197956070] 'applied index is now lower than readState.Index' (duration: 138.786245ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:59:54.916484Z","caller":"traceutil/trace.go:171","msg":"trace[558804530] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"188.912756ms","start":"2026-06-28T19:59:54.727539Z","end":"2026-06-28T19:59:54.916452Z","steps":["trace[558804530] 'process raft request' (duration: 188.739993ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:54.916525Z","caller":"traceutil/trace.go:171","msg":"trace[396473836] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"188.824113ms","start":"2026-06-28T19:59:54.727665Z","end":"2026-06-28T19:59:54.916489Z","steps":["trace[396473836] 'process raft request' (duration: 188.6557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:54.916633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.067462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:59:54.916668Z","caller":"traceutil/trace.go:171","msg":"trace[1552146774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"186.148365ms","start":"2026-06-28T19:59:54.730509Z","end":"2026-06-28T19:59:54.916657Z","steps":["trace[1552146774] 'agreement among raft nodes before linearized reading' (duration: 186.009761ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:54.916846Z","caller":"traceutil/trace.go:171","msg":"trace[1769541143] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"189.798603ms","start":"2026-06-28T19:59:54.727033Z","end":"2026-06-28T19:59:54.916831Z","steps":["trace[1769541143] 'process raft request' (duration: 189.078359ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:54.916858Z","caller":"traceutil/trace.go:171","msg":"trace[2030205223] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"189.048368ms","start":"2026-06-28T19:59:54.727754Z","end":"2026-06-28T19:59:54.916803Z","steps":["trace[2030205223] 'process raft request' (duration: 188.596699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:59:57.514031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.776062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:59:57.514325Z","caller":"traceutil/trace.go:171","msg":"trace[1025006890] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"582.263306ms","start":"2026-06-28T19:59:56.932035Z","end":"2026-06-28T19:59:57.514299Z","steps":["trace[1025006890] 'process raft request' (duration: 582.141994ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:59:57.514602Z","caller":"traceutil/trace.go:171","msg":"trace[660424286] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"582.752855ms","start":"2026-06-28T19:59:56.93182Z","end":"2026-06-28T19:59:57.514573Z","steps":["trace[660424286] 'process raft request' (duration: 100.318231ms)","trace[660424286] 'compare' (duration: 481.597198ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:59:57.514601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:56.932028Z","time spent":"582.51988ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:59:57.514743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:59:56.931795Z","time spent":"582.878218ms","remote":"127.0.0.1:33548","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-06-28T19:59:57.889688Z","caller":"traceutil/trace.go:171","msg":"trace[959049785] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"276.804787ms","start":"2026-06-28T19:59:57.612855Z","end":"2026-06-28T19:59:57.88966Z","steps":["trace[959049785] 'process raft request' (duration: 222.096192ms)","trace[959049785] 'compare' (duration: 54.608573ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:05.824519Z","caller":"traceutil/trace.go:171","msg":"trace[1273359270] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"143.88454ms","start":"2026-06-28T20:00:05.680588Z","end":"2026-06-28T20:00:05.824473Z","steps":["trace[1273359270] 'process raft request' (duration: 53.568565ms)","trace[1273359270] 'compare' (duration: 90.166942ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:06.937614Z","caller":"traceutil/trace.go:171","msg":"trace[369845050] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"110.007871ms","start":"2026-06-28T20:00:06.827577Z","end":"2026-06-28T20:00:06.937585Z","steps":["trace[369845050] 'process raft request' (duration: 109.818168ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:07.070372Z","caller":"traceutil/trace.go:171","msg":"trace[1522976883] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"129.043164ms","start":"2026-06-28T20:00:06.941295Z","end":"2026-06-28T20:00:07.070338Z","steps":["trace[1522976883] 'process raft request' (duration: 117.986839ms)","trace[1522976883] 'compare' (duration: 10.897583ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:13.992692Z","caller":"traceutil/trace.go:171","msg":"trace[668616767] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"275.786957ms","start":"2026-06-28T20:00:13.716868Z","end":"2026-06-28T20:00:13.992655Z","steps":["trace[668616767] 'process raft request' (duration: 275.486332ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:14.271353Z","caller":"traceutil/trace.go:171","msg":"trace[644526073] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"273.957195ms","start":"2026-06-28T20:00:13.997355Z","end":"2026-06-28T20:00:14.271312Z","steps":["trace[644526073] 'process raft request' (duration: 179.299237ms)","trace[644526073] 'compare' (duration: 94.530505ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:17.599578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035853712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:17.599666Z","caller":"traceutil/trace.go:171","msg":"trace[1500951451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1229; }","duration":"1.036010845s","start":"2026-06-28T20:00:16.563634Z","end":"2026-06-28T20:00:17.599644Z","steps":["trace[1500951451] 'count revisions from in-memory index tree' (duration: 1.03573154s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.599713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:16.5636Z","time spent":"1.036100907s","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:17.60007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.712517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:17.600106Z","caller":"traceutil/trace.go:171","msg":"trace[1752626177] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1229; }","duration":"828.80056ms","start":"2026-06-28T20:00:16.771292Z","end":"2026-06-28T20:00:17.600093Z","steps":["trace[1752626177] 'count revisions from in-memory index tree' (duration: 828.631226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.600145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:16.771264Z","time spent":"828.868571ms","remote":"127.0.0.1:60230","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/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:17.601618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604643338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-28T20:00:17.601655Z","caller":"traceutil/trace.go:171","msg":"trace[1521195952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"1.604707919s","start":"2026-06-28T20:00:15.996936Z","end":"2026-06-28T20:00:17.601644Z","steps":["trace[1521195952] 'range keys from in-memory index tree' (duration: 1.604477114s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.601687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:15.996914Z","time spent":"1.60476357s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T20:00:17.602163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326302091s","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-06-28T20:00:17.602208Z","caller":"traceutil/trace.go:171","msg":"trace[465524966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"1.326381453s","start":"2026-06-28T20:00:16.275815Z","end":"2026-06-28T20:00:17.602196Z","steps":["trace[465524966] 'range keys from in-memory index tree' (duration: 1.326109138s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.602239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:16.275788Z","time spent":"1.326442214s","remote":"127.0.0.1:33548","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-06-28T20:00:17.60412Z","caller":"traceutil/trace.go:171","msg":"trace[1173403434] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"492.04363ms","start":"2026-06-28T20:00:17.112043Z","end":"2026-06-28T20:00:17.604087Z","steps":["trace[1173403434] 'read index received' (duration: 491.813706ms)","trace[1173403434] 'applied index is now lower than readState.Index' (duration: 229.024µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:17.604228Z","caller":"traceutil/trace.go:171","msg":"trace[853479292] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"630.456278ms","start":"2026-06-28T20:00:16.973762Z","end":"2026-06-28T20:00:17.604218Z","steps":["trace[853479292] 'process raft request' (duration: 630.183504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.60434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:16.973723Z","time spent":"630.5298ms","remote":"127.0.0.1:33548","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-06-28T20:00:17.604609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.376413ms","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-06-28T20:00:17.604674Z","caller":"traceutil/trace.go:171","msg":"trace[1023785671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"433.470114ms","start":"2026-06-28T20:00:17.171188Z","end":"2026-06-28T20:00:17.604658Z","steps":["trace[1023785671] 'agreement among raft nodes before linearized reading' (duration: 433.205919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.604706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:17.171155Z","time spent":"433.544696ms","remote":"127.0.0.1:33548","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-06-28T20:00:17.604737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.702104ms","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-06-28T20:00:17.604772Z","caller":"traceutil/trace.go:171","msg":"trace[806533228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"492.777405ms","start":"2026-06-28T20:00:17.111983Z","end":"2026-06-28T20:00:17.60476Z","steps":["trace[806533228] 'agreement among raft nodes before linearized reading' (duration: 492.668333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.604794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:17.111946Z","time spent":"492.841976ms","remote":"127.0.0.1:33548","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-06-28T20:00:17.60489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.22469ms","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-06-28T20:00:17.60491Z","caller":"traceutil/trace.go:171","msg":"trace[1231835552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"433.263131ms","start":"2026-06-28T20:00:17.171641Z","end":"2026-06-28T20:00:17.604904Z","steps":["trace[1231835552] 'agreement among raft nodes before linearized reading' (duration: 433.20441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.604927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:17.171633Z","time spent":"433.289291ms","remote":"127.0.0.1:33548","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-06-28T20:00:17.604781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.17143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:17.605013Z","caller":"traceutil/trace.go:171","msg":"trace[245730657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"412.448364ms","start":"2026-06-28T20:00:17.192541Z","end":"2026-06-28T20:00:17.604989Z","steps":["trace[245730657] 'agreement among raft nodes before linearized reading' (duration: 412.129129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:17.605153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:17.192519Z","time spent":"412.554247ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T20:00:30.017934Z","caller":"traceutil/trace.go:171","msg":"trace[1794382288] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"301.182169ms","start":"2026-06-28T20:00:29.716701Z","end":"2026-06-28T20:00:30.017883Z","steps":["trace[1794382288] 'read index received' (duration: 300.993216ms)","trace[1794382288] 'applied index is now lower than readState.Index' (duration: 187.703µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:30.018028Z","caller":"traceutil/trace.go:171","msg":"trace[1561438793] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"317.260828ms","start":"2026-06-28T20:00:29.700724Z","end":"2026-06-28T20:00:30.017985Z","steps":["trace[1561438793] 'process raft request' (duration: 317.006213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:30.01823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:29.700694Z","time spent":"317.437082ms","remote":"127.0.0.1:33548","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-06-28T20:00:30.020102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.38588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-28T20:00:30.020171Z","caller":"traceutil/trace.go:171","msg":"trace[1301584708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"303.496283ms","start":"2026-06-28T20:00:29.716656Z","end":"2026-06-28T20:00:30.020153Z","steps":["trace[1301584708] 'agreement among raft nodes before linearized reading' (duration: 301.548067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:30.020207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:29.716638Z","time spent":"303.561313ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T20:00:30.020202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.354739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T20:00:30.020296Z","caller":"traceutil/trace.go:171","msg":"trace[2008685479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"303.473612ms","start":"2026-06-28T20:00:29.716814Z","end":"2026-06-28T20:00:30.020288Z","steps":["trace[2008685479] 'agreement among raft nodes before linearized reading' (duration: 303.289519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:30.020318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:29.716799Z","time spent":"303.514522ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-28T20:00:30.020429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.357552ms","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-06-28T20:00:30.020481Z","caller":"traceutil/trace.go:171","msg":"trace[1492106953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"301.409293ms","start":"2026-06-28T20:00:29.719047Z","end":"2026-06-28T20:00:30.020456Z","steps":["trace[1492106953] 'agreement among raft nodes before linearized reading' (duration: 301.317251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:30.020478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.658136ms","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-06-28T20:00:30.020555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:29.719039Z","time spent":"301.465594ms","remote":"127.0.0.1:33548","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-06-28T20:00:30.020564Z","caller":"traceutil/trace.go:171","msg":"trace[1515016184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"302.752008ms","start":"2026-06-28T20:00:29.717798Z","end":"2026-06-28T20:00:30.02055Z","steps":["trace[1515016184] 'agreement among raft nodes before linearized reading' (duration: 302.542554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:30.020608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:29.717594Z","time spent":"303.003662ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-28T20:00:30.021071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.28152ms","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-06-28T20:00:30.021177Z","caller":"traceutil/trace.go:171","msg":"trace[943933756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"301.429104ms","start":"2026-06-28T20:00:29.719716Z","end":"2026-06-28T20:00:30.021145Z","steps":["trace[943933756] 'agreement among raft nodes before linearized reading' (duration: 300.561578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:30.021225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:29.719688Z","time spent":"301.527796ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T20:00:36.302673Z","caller":"traceutil/trace.go:171","msg":"trace[473755014] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"765.25709ms","start":"2026-06-28T20:00:35.537387Z","end":"2026-06-28T20:00:36.302644Z","steps":["trace[473755014] 'process raft request' (duration: 765.083577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:36.302856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:35.537354Z","time spent":"765.396093ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:37.089106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.29688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T20:00:37.089237Z","caller":"traceutil/trace.go:171","msg":"trace[1276726159] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1360; }","duration":"985.517079ms","start":"2026-06-28T20:00:36.103694Z","end":"2026-06-28T20:00:37.089212Z","steps":["trace[1276726159] 'read index received' (duration: 198.794629ms)","trace[1276726159] 'applied index is now lower than readState.Index' (duration: 786.72051ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:37.089364Z","caller":"traceutil/trace.go:171","msg":"trace[1621206322] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.030807198s","start":"2026-06-28T20:00:36.058537Z","end":"2026-06-28T20:00:37.089344Z","steps":["trace[1621206322] 'process raft request' (duration: 499.151542ms)","trace[1621206322] 'compare' (duration: 531.064005ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:37.089436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.694656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-28T20:00:37.08945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.058512Z","time spent":"1.03089093s","remote":"127.0.0.1:33548","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-06-28T20:00:37.089465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.462945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:37.089481Z","caller":"traceutil/trace.go:171","msg":"trace[928148086] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1310; }","duration":"940.766277ms","start":"2026-06-28T20:00:36.1487Z","end":"2026-06-28T20:00:37.089467Z","steps":["trace[928148086] 'agreement among raft nodes before linearized reading' (duration: 940.676116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:37.089485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.784773ms","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-06-28T20:00:37.089517Z","caller":"traceutil/trace.go:171","msg":"trace[221142600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"896.565807ms","start":"2026-06-28T20:00:36.192935Z","end":"2026-06-28T20:00:37.089501Z","steps":["trace[221142600] 'agreement among raft nodes before linearized reading' (duration: 896.469985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:37.089517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.148679Z","time spent":"940.828499ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:37.089523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.207676ms","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-06-28T20:00:37.089562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.19291Z","time spent":"896.642189ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T20:00:37.089603Z","caller":"traceutil/trace.go:171","msg":"trace[1490755293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"982.397989ms","start":"2026-06-28T20:00:36.107185Z","end":"2026-06-28T20:00:37.089583Z","steps":["trace[1490755293] 'agreement among raft nodes before linearized reading' (duration: 982.198636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:37.089619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.797112ms","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-06-28T20:00:37.089644Z","caller":"traceutil/trace.go:171","msg":"trace[1229439323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"979.832112ms","start":"2026-06-28T20:00:36.109806Z","end":"2026-06-28T20:00:37.089638Z","steps":["trace[1229439323] 'agreement among raft nodes before linearized reading' (duration: 979.769091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:37.089645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.107159Z","time spent":"982.477501ms","remote":"127.0.0.1:33548","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-06-28T20:00:37.089669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.109799Z","time spent":"979.864454ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T20:00:37.089549Z","caller":"traceutil/trace.go:171","msg":"trace[2140679386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"985.898625ms","start":"2026-06-28T20:00:36.103633Z","end":"2026-06-28T20:00:37.089531Z","steps":["trace[2140679386] 'agreement among raft nodes before linearized reading' (duration: 985.745503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:37.089785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.103605Z","time spent":"986.16754ms","remote":"127.0.0.1:33548","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-06-28T20:00:37.089803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.754839ms","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-06-28T20:00:37.089815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.940954ms","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-06-28T20:00:37.089836Z","caller":"traceutil/trace.go:171","msg":"trace[723371927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"979.969695ms","start":"2026-06-28T20:00:36.109859Z","end":"2026-06-28T20:00:37.089829Z","steps":["trace[723371927] 'agreement among raft nodes before linearized reading' (duration: 979.910764ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:37.08983Z","caller":"traceutil/trace.go:171","msg":"trace[1804765521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"980.822921ms","start":"2026-06-28T20:00:36.108998Z","end":"2026-06-28T20:00:37.089821Z","steps":["trace[1804765521] 'agreement among raft nodes before linearized reading' (duration: 980.744659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:37.08986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.109856Z","time spent":"979.998585ms","remote":"127.0.0.1:33548","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-06-28T20:00:37.089865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:36.108962Z","time spent":"980.897221ms","remote":"127.0.0.1:33548","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":"info","ts":"2026-06-28T20:00:37.348818Z","caller":"traceutil/trace.go:171","msg":"trace[1733217917] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"254.124775ms","start":"2026-06-28T20:00:37.094672Z","end":"2026-06-28T20:00:37.348797Z","steps":["trace[1733217917] 'process raft request' (duration: 254.069454ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:37.348856Z","caller":"traceutil/trace.go:171","msg":"trace[1970500539] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"254.223496ms","start":"2026-06-28T20:00:37.094589Z","end":"2026-06-28T20:00:37.348812Z","steps":["trace[1970500539] 'process raft request' (duration: 254.010783ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:37.348992Z","caller":"traceutil/trace.go:171","msg":"trace[766735701] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"253.88269ms","start":"2026-06-28T20:00:37.095103Z","end":"2026-06-28T20:00:37.348986Z","steps":["trace[766735701] 'process raft request' (duration: 253.661436ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:37.349669Z","caller":"traceutil/trace.go:171","msg":"trace[1942809633] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"254.421961ms","start":"2026-06-28T20:00:37.095217Z","end":"2026-06-28T20:00:37.349639Z","steps":["trace[1942809633] 'process raft request' (duration: 253.746248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:39.635687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T20:00:40.125362Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.031071253s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T20:00:40.125701Z","caller":"traceutil/trace.go:171","msg":"trace[441283272] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"990.044024ms","start":"2026-06-28T20:00:39.135633Z","end":"2026-06-28T20:00:40.125677Z","steps":["trace[441283272] 'read index received' (duration: 989.839579ms)","trace[441283272] 'applied index is now lower than readState.Index' (duration: 203.465µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:40.125764Z","caller":"traceutil/trace.go:171","msg":"trace[1480264248] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"1.031518293s","start":"2026-06-28T20:00:39.094212Z","end":"2026-06-28T20:00:40.125731Z","steps":["trace[1480264248] 'process raft request' (duration: 1.031303198s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.125846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.391152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:40.125881Z","caller":"traceutil/trace.go:171","msg":"trace[1664771814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"932.481204ms","start":"2026-06-28T20:00:39.193389Z","end":"2026-06-28T20:00:40.12587Z","steps":["trace[1664771814] 'agreement among raft nodes before linearized reading' (duration: 932.408633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.12594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.193366Z","time spent":"932.565955ms","remote":"127.0.0.1:33300","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-06-28T20:00:40.125943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.094192Z","time spent":"1.031640994s","remote":"127.0.0.1:33548","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-06-28T20:00:40.126034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.71948ms","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-06-28T20:00:40.12608Z","caller":"traceutil/trace.go:171","msg":"trace[1407539567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"772.776331ms","start":"2026-06-28T20:00:39.35329Z","end":"2026-06-28T20:00:40.126066Z","steps":["trace[1407539567] 'agreement among raft nodes before linearized reading' (duration: 772.619208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.126083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.190778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-28T20:00:40.126114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.353287Z","time spent":"772.819411ms","remote":"127.0.0.1:33548","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-06-28T20:00:40.126103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.797071ms","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-06-28T20:00:40.126162Z","caller":"traceutil/trace.go:171","msg":"trace[523166772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"773.31856ms","start":"2026-06-28T20:00:39.352819Z","end":"2026-06-28T20:00:40.126137Z","steps":["trace[523166772] 'agreement among raft nodes before linearized reading' (duration: 773.119276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.126138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.793722ms","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-06-28T20:00:40.126179Z","caller":"traceutil/trace.go:171","msg":"trace[749952390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1318; }","duration":"772.915423ms","start":"2026-06-28T20:00:39.353243Z","end":"2026-06-28T20:00:40.126158Z","steps":["trace[749952390] 'agreement among raft nodes before linearized reading' (duration: 772.673609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.126212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.352799Z","time spent":"773.403772ms","remote":"127.0.0.1:33548","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":"info","ts":"2026-06-28T20:00:40.12624Z","caller":"traceutil/trace.go:171","msg":"trace[1023741905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1318; }","duration":"771.906234ms","start":"2026-06-28T20:00:39.354311Z","end":"2026-06-28T20:00:40.126218Z","steps":["trace[1023741905] 'agreement among raft nodes before linearized reading' (duration: 771.635139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.126237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.353227Z","time spent":"773.001844ms","remote":"127.0.0.1:33548","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-06-28T20:00:40.126288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.354293Z","time spent":"771.986385ms","remote":"127.0.0.1:33548","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-06-28T20:00:40.126553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.916529ms","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-06-28T20:00:40.12659Z","caller":"traceutil/trace.go:171","msg":"trace[497175976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"990.99102ms","start":"2026-06-28T20:00:39.135588Z","end":"2026-06-28T20:00:40.126579Z","steps":["trace[497175976] 'agreement among raft nodes before linearized reading' (duration: 990.190765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:40.126627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:39.135565Z","time spent":"991.048421ms","remote":"127.0.0.1:33548","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-06-28T20:00:40.851034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820620,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T20:00:41.026602Z","caller":"traceutil/trace.go:171","msg":"trace[115221626] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"895.172021ms","start":"2026-06-28T20:00:40.1314Z","end":"2026-06-28T20:00:41.026572Z","steps":["trace[115221626] 'process raft request' (duration: 894.962597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:41.026904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.131388Z","time spent":"895.295254ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:00:41.156851Z","caller":"traceutil/trace.go:171","msg":"trace[1187907288] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1371; }","duration":"806.386444ms","start":"2026-06-28T20:00:40.350443Z","end":"2026-06-28T20:00:41.156829Z","steps":["trace[1187907288] 'read index received' (duration: 675.966844ms)","trace[1187907288] 'applied index is now lower than readState.Index' (duration: 130.41892ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:41.156906Z","caller":"traceutil/trace.go:171","msg":"trace[311367272] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.024739976s","start":"2026-06-28T20:00:40.132133Z","end":"2026-06-28T20:00:41.156873Z","steps":["trace[311367272] 'process raft request' (duration: 1.024561593s)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:41.156951Z","caller":"traceutil/trace.go:171","msg":"trace[1353598715] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.025413738s","start":"2026-06-28T20:00:40.131522Z","end":"2026-06-28T20:00:41.156935Z","steps":["trace[1353598715] 'process raft request' (duration: 957.019939ms)","trace[1353598715] 'compare' (duration: 67.458391ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:41.156993Z","caller":"traceutil/trace.go:171","msg":"trace[677997871] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.02442408s","start":"2026-06-28T20:00:40.132564Z","end":"2026-06-28T20:00:41.156988Z","steps":["trace[677997871] 'process raft request' (duration: 1.024205386s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:41.157045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.131495Z","time spent":"1.02549624s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:41.157058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.132116Z","time spent":"1.024855639s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:41.157099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.132556Z","time spent":"1.024468311s","remote":"127.0.0.1:33548","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-06-28T20:00:41.15697Z","caller":"traceutil/trace.go:171","msg":"trace[2051007753] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.024558283s","start":"2026-06-28T20:00:40.1324Z","end":"2026-06-28T20:00:41.156958Z","steps":["trace[2051007753] 'process raft request' (duration: 1.02434189s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:41.157217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.132392Z","time spent":"1.024799908s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:00:41.157257Z","caller":"traceutil/trace.go:171","msg":"trace[587430729] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"949.441669ms","start":"2026-06-28T20:00:40.207805Z","end":"2026-06-28T20:00:41.157247Z","steps":["trace[587430729] 'process raft request' (duration: 948.98934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:41.157312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.207787Z","time spent":"949.49994ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:41.157424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.896059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T20:00:41.157457Z","caller":"traceutil/trace.go:171","msg":"trace[1723920996] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1324; }","duration":"594.972821ms","start":"2026-06-28T20:00:40.562475Z","end":"2026-06-28T20:00:41.157448Z","steps":["trace[1723920996] 'agreement among raft nodes before linearized reading' (duration: 594.913569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:41.157485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.562445Z","time spent":"595.033292ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:41.157704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.21886ms","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-06-28T20:00:41.157806Z","caller":"traceutil/trace.go:171","msg":"trace[1488544763] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1324; }","duration":"807.385403ms","start":"2026-06-28T20:00:40.350397Z","end":"2026-06-28T20:00:41.157783Z","steps":["trace[1488544763] 'agreement among raft nodes before linearized reading' (duration: 807.158199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:41.157871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:40.350384Z","time spent":"807.472534ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:44.201416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271334592s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T20:00:44.201539Z","caller":"traceutil/trace.go:171","msg":"trace[85938568] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"1.169578865s","start":"2026-06-28T20:00:43.031936Z","end":"2026-06-28T20:00:44.201515Z","steps":["trace[85938568] 'read index received' (duration: 73.251µs)","trace[85938568] 'applied index is now lower than readState.Index' (duration: 1.169503344s)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:44.201944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169959031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T20:00:44.202058Z","caller":"traceutil/trace.go:171","msg":"trace[811055327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"1.170153765s","start":"2026-06-28T20:00:43.031879Z","end":"2026-06-28T20:00:44.202033Z","steps":["trace[811055327] 'agreement among raft nodes before linearized reading' (duration: 1.16989159s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:44.202119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:43.031853Z","time spent":"1.170251256s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-28T20:00:44.336712Z","caller":"traceutil/trace.go:171","msg":"trace[1667281548] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"135.120556ms","start":"2026-06-28T20:00:44.201566Z","end":"2026-06-28T20:00:44.336686Z","steps":["trace[1667281548] 'read index received' (duration: 134.914913ms)","trace[1667281548] 'applied index is now lower than readState.Index' (duration: 204.773µs)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:44.336911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174337792s","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-06-28T20:00:44.336928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175175297s","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-06-28T20:00:44.336967Z","caller":"traceutil/trace.go:171","msg":"trace[2092500647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.174435303s","start":"2026-06-28T20:00:43.162518Z","end":"2026-06-28T20:00:44.336953Z","steps":["trace[2092500647] 'agreement among raft nodes before linearized reading' (duration: 1.17422862s)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:44.336995Z","caller":"traceutil/trace.go:171","msg":"trace[1820958210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.175261669s","start":"2026-06-28T20:00:43.161695Z","end":"2026-06-28T20:00:44.336956Z","steps":["trace[1820958210] 'agreement among raft nodes before linearized reading' (duration: 1.175085816s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:44.337009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:43.16251Z","time spent":"1.174489874s","remote":"127.0.0.1:33548","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-06-28T20:00:44.337033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:43.161669Z","time spent":"1.17535402s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T20:00:44.337177Z","caller":"traceutil/trace.go:171","msg":"trace[2044036020] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"180.016701ms","start":"2026-06-28T20:00:44.15715Z","end":"2026-06-28T20:00:44.337166Z","steps":["trace[2044036020] 'process raft request' (duration: 179.327038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:44.337173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174879643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-28T20:00:44.337174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174506585s","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-06-28T20:00:44.337263Z","caller":"traceutil/trace.go:171","msg":"trace[1814401623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"1.175037026s","start":"2026-06-28T20:00:43.1622Z","end":"2026-06-28T20:00:44.337237Z","steps":["trace[1814401623] 'agreement among raft nodes before linearized reading' (duration: 1.17475158s)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:44.337263Z","caller":"traceutil/trace.go:171","msg":"trace[1623521542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"1.174639868s","start":"2026-06-28T20:00:43.162604Z","end":"2026-06-28T20:00:44.337244Z","steps":["trace[1623521542] 'agreement among raft nodes before linearized reading' (duration: 1.174389703s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:44.337289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144250575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:44.337324Z","caller":"traceutil/trace.go:171","msg":"trace[276449944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"1.144302996s","start":"2026-06-28T20:00:43.193011Z","end":"2026-06-28T20:00:44.337314Z","steps":["trace[276449944] 'agreement among raft nodes before linearized reading' (duration: 1.144249845s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:44.337331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:43.162149Z","time spent":"1.175162389s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T20:00:44.337325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:43.16259Z","time spent":"1.17472393s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-28T20:00:44.337355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:43.192995Z","time spent":"1.144352656s","remote":"127.0.0.1:33300","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-06-28T20:00:44.77003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.669006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T20:00:44.770129Z","caller":"traceutil/trace.go:171","msg":"trace[937209704] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"430.798485ms","start":"2026-06-28T20:00:44.339311Z","end":"2026-06-28T20:00:44.77011Z","steps":["trace[937209704] 'read index received' (duration: 175.986217ms)","trace[937209704] 'applied index is now lower than readState.Index' (duration: 254.810728ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:44.770323Z","caller":"traceutil/trace.go:171","msg":"trace[315749386] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"563.707151ms","start":"2026-06-28T20:00:44.206603Z","end":"2026-06-28T20:00:44.77031Z","steps":["trace[315749386] 'process raft request' (duration: 308.666898ms)","trace[315749386] 'compare' (duration: 254.232357ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:44.770361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.050739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:44.770393Z","caller":"traceutil/trace.go:171","msg":"trace[1122354924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"431.11875ms","start":"2026-06-28T20:00:44.339266Z","end":"2026-06-28T20:00:44.770385Z","steps":["trace[1122354924] 'agreement among raft nodes before linearized reading' (duration: 431.052669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:44.770388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.206585Z","time spent":"563.770482ms","remote":"127.0.0.1:33548","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-06-28T20:00:44.770425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.339239Z","time spent":"431.173521ms","remote":"127.0.0.1:33296","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-06-28T20:00:45.27122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820649,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T20:00:45.426524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.812654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T20:00:45.427603Z","caller":"traceutil/trace.go:171","msg":"trace[1316653372] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1387; }","duration":"657.424009ms","start":"2026-06-28T20:00:44.770156Z","end":"2026-06-28T20:00:45.42758Z","steps":["trace[1316653372] 'read index received' (duration: 543.462595ms)","trace[1316653372] 'applied index is now lower than readState.Index' (duration: 113.960204ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:45.427748Z","caller":"traceutil/trace.go:171","msg":"trace[1216022522] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"1.085664176s","start":"2026-06-28T20:00:44.342069Z","end":"2026-06-28T20:00:45.427733Z","steps":["trace[1216022522] 'process raft request' (duration: 971.56533ms)","trace[1216022522] 'compare' (duration: 112.70984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:45.427849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.342046Z","time spent":"1.085763988s","remote":"127.0.0.1:33548","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-06-28T20:00:45.428Z","caller":"traceutil/trace.go:171","msg":"trace[215843124] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"1.085154418s","start":"2026-06-28T20:00:44.342834Z","end":"2026-06-28T20:00:45.427989Z","steps":["trace[215843124] 'process raft request' (duration: 1.084580726s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:45.428082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.342825Z","time spent":"1.085227589s","remote":"127.0.0.1:33548","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-06-28T20:00:45.428183Z","caller":"traceutil/trace.go:171","msg":"trace[2069713650] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"1.084801951s","start":"2026-06-28T20:00:44.343371Z","end":"2026-06-28T20:00:45.428173Z","steps":["trace[2069713650] 'process raft request' (duration: 1.084107298s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:45.428263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.343363Z","time spent":"1.084869962s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:00:45.428456Z","caller":"traceutil/trace.go:171","msg":"trace[823645355] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"1.085043096s","start":"2026-06-28T20:00:44.343397Z","end":"2026-06-28T20:00:45.42844Z","steps":["trace[823645355] 'process raft request' (duration: 1.084127238s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:45.42859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.343374Z","time spent":"1.085172938s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:45.428647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.998041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T20:00:45.428687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.961775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T20:00:45.428731Z","caller":"traceutil/trace.go:171","msg":"trace[238640782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"651.121312ms","start":"2026-06-28T20:00:44.777589Z","end":"2026-06-28T20:00:45.428711Z","steps":["trace[238640782] 'agreement among raft nodes before linearized reading' (duration: 650.97083ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:45.428769Z","caller":"traceutil/trace.go:171","msg":"trace[1761409514] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1337; }","duration":"925.101117ms","start":"2026-06-28T20:00:44.503648Z","end":"2026-06-28T20:00:45.428749Z","steps":["trace[1761409514] 'agreement among raft nodes before linearized reading' (duration: 924.935524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:45.428773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.777574Z","time spent":"651.190434ms","remote":"127.0.0.1:33300","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-06-28T20:00:45.428819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:44.503625Z","time spent":"925.183689ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:47.280382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T20:00:47.642249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.134645105s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-28T20:00:47.735866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.533172ms","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-06-28T20:00:47.73596Z","caller":"traceutil/trace.go:171","msg":"trace[2094394876] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.228162781s","start":"2026-06-28T20:00:46.507495Z","end":"2026-06-28T20:00:47.735658Z","steps":["trace[2094394876] 'process raft request' (duration: 1.13491397s)","trace[2094394876] 'compare' (duration: 92.69762ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:47.735999Z","caller":"traceutil/trace.go:171","msg":"trace[873281662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1339; }","duration":"955.716266ms","start":"2026-06-28T20:00:46.78027Z","end":"2026-06-28T20:00:47.735986Z","steps":["trace[873281662] 'agreement among raft nodes before linearized reading' (duration: 955.469801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.736117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:46.780248Z","time spent":"955.856218ms","remote":"127.0.0.1:33548","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-06-28T20:00:47.736156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:46.507469Z","time spent":"1.228582218s","remote":"127.0.0.1:33548","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-06-28T20:00:47.735708Z","caller":"traceutil/trace.go:171","msg":"trace[1382515880] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"955.155396ms","start":"2026-06-28T20:00:46.780324Z","end":"2026-06-28T20:00:47.735479Z","steps":["trace[1382515880] 'read index received' (duration: 862.067968ms)","trace[1382515880] 'applied index is now lower than readState.Index' (duration: 93.086148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:47.736484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.187469ms","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-06-28T20:00:47.736563Z","caller":"traceutil/trace.go:171","msg":"trace[1154174108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"301.270191ms","start":"2026-06-28T20:00:47.435278Z","end":"2026-06-28T20:00:47.736548Z","steps":["trace[1154174108] 'agreement among raft nodes before linearized reading' (duration: 301.134498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.736571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.577621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-06-28T20:00:47.73665Z","caller":"traceutil/trace.go:171","msg":"trace[294691023] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1339; }","duration":"589.708973ms","start":"2026-06-28T20:00:47.146923Z","end":"2026-06-28T20:00:47.736632Z","steps":["trace[294691023] 'agreement among raft nodes before linearized reading' (duration: 589.49373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.736696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.146899Z","time spent":"589.788305ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-28T20:00:47.736584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.782219ms","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-06-28T20:00:47.736679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.478973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:47.736765Z","caller":"traceutil/trace.go:171","msg":"trace[1982119057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1339; }","duration":"544.608286ms","start":"2026-06-28T20:00:47.192135Z","end":"2026-06-28T20:00:47.736743Z","steps":["trace[1982119057] 'agreement among raft nodes before linearized reading' (duration: 544.457203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.736812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.192122Z","time spent":"544.681257ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T20:00:47.736842Z","caller":"traceutil/trace.go:171","msg":"trace[2039507895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1339; }","duration":"302.066956ms","start":"2026-06-28T20:00:47.434758Z","end":"2026-06-28T20:00:47.736825Z","steps":["trace[2039507895] 'agreement among raft nodes before linearized reading' (duration: 301.676638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.736876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.2954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-06-28T20:00:47.736913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.43474Z","time spent":"302.138927ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T20:00:47.73694Z","caller":"traceutil/trace.go:171","msg":"trace[1042969684] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1339; }","duration":"198.382862ms","start":"2026-06-28T20:00:47.538542Z","end":"2026-06-28T20:00:47.736925Z","steps":["trace[1042969684] 'agreement among raft nodes before linearized reading' (duration: 198.228749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.73662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.435275Z","time spent":"301.335282ms","remote":"127.0.0.1:33548","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-06-28T20:00:47.737187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.898863ms","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-06-28T20:00:47.738352Z","caller":"traceutil/trace.go:171","msg":"trace[650133564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1339; }","duration":"302.018774ms","start":"2026-06-28T20:00:47.435228Z","end":"2026-06-28T20:00:47.737247Z","steps":["trace[650133564] 'agreement among raft nodes before linearized reading' (duration: 301.536386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.738434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.435208Z","time spent":"303.209897ms","remote":"127.0.0.1:33548","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-06-28T20:00:47.738608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.265018ms","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-06-28T20:00:47.738663Z","caller":"traceutil/trace.go:171","msg":"trace[712468695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1339; }","duration":"303.354589ms","start":"2026-06-28T20:00:47.435295Z","end":"2026-06-28T20:00:47.73865Z","steps":["trace[712468695] 'agreement among raft nodes before linearized reading' (duration: 303.060234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:47.738735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.435292Z","time spent":"303.40669ms","remote":"127.0.0.1:33548","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-06-28T20:00:47.739072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.996278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:47.739135Z","caller":"traceutil/trace.go:171","msg":"trace[1816157026] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1339; }","duration":"119.135081ms","start":"2026-06-28T20:00:47.619987Z","end":"2026-06-28T20:00:47.739122Z","steps":["trace[1816157026] 'agreement among raft nodes before linearized reading' (duration: 118.990368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.426151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.778749ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-28T20:00:48.426241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.738013Z","time spent":"688.224061ms","remote":"127.0.0.1:33346","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-28T20:00:48.6525Z","caller":"traceutil/trace.go:171","msg":"trace[484177793] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"909.831264ms","start":"2026-06-28T20:00:47.742632Z","end":"2026-06-28T20:00:48.652463Z","steps":["trace[484177793] 'process raft request' (duration: 909.524007ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:48.652719Z","caller":"traceutil/trace.go:171","msg":"trace[591948067] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"909.912825ms","start":"2026-06-28T20:00:47.742784Z","end":"2026-06-28T20:00:48.652696Z","steps":["trace[591948067] 'process raft request' (duration: 909.61114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.652899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.742772Z","time spent":"909.991896ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:00:48.653144Z","caller":"traceutil/trace.go:171","msg":"trace[1089492499] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"910.287022ms","start":"2026-06-28T20:00:47.742834Z","end":"2026-06-28T20:00:48.653121Z","steps":["trace[1089492499] 'process raft request' (duration: 910.021237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.653259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.742827Z","time spent":"910.374933ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:48.653388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.742606Z","time spent":"910.024537ms","remote":"127.0.0.1:33548","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-06-28T20:00:48.653563Z","caller":"traceutil/trace.go:171","msg":"trace[1055268037] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"910.461735ms","start":"2026-06-28T20:00:47.743082Z","end":"2026-06-28T20:00:48.653544Z","steps":["trace[1055268037] 'process raft request' (duration: 910.340923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.653666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.743077Z","time spent":"910.541706ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:00:48.653794Z","caller":"traceutil/trace.go:171","msg":"trace[2138823987] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"910.763421ms","start":"2026-06-28T20:00:47.743011Z","end":"2026-06-28T20:00:48.653774Z","steps":["trace[2138823987] 'process raft request' (duration: 909.981406ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:48.653787Z","caller":"traceutil/trace.go:171","msg":"trace[1654037648] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1393; }","duration":"555.356136ms","start":"2026-06-28T20:00:48.098395Z","end":"2026-06-28T20:00:48.653751Z","steps":["trace[1654037648] 'read index received' (duration: 225.983623ms)","trace[1654037648] 'applied index is now lower than readState.Index' (duration: 329.371083ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:48.653878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.743Z","time spent":"910.837092ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:00:48.654096Z","caller":"traceutil/trace.go:171","msg":"trace[1012017478] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"910.799682ms","start":"2026-06-28T20:00:47.743276Z","end":"2026-06-28T20:00:48.654075Z","steps":["trace[1012017478] 'process raft request' (duration: 910.21353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.654168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.771713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T20:00:48.654223Z","caller":"traceutil/trace.go:171","msg":"trace[2049658828] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1345; }","duration":"555.872755ms","start":"2026-06-28T20:00:48.098336Z","end":"2026-06-28T20:00:48.654209Z","steps":["trace[2049658828] 'agreement among raft nodes before linearized reading' (duration: 555.758933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.654265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:48.098309Z","time spent":"555.946346ms","remote":"127.0.0.1:33582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:48.654275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:47.74327Z","time spent":"910.866933ms","remote":"127.0.0.1:33548","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-06-28T20:00:48.663124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.433579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:48.663131Z","caller":"traceutil/trace.go:171","msg":"trace[1802785622] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"225.165909ms","start":"2026-06-28T20:00:48.437944Z","end":"2026-06-28T20:00:48.66311Z","steps":["trace[1802785622] 'process raft request' (duration: 225.056797ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:48.663164Z","caller":"traceutil/trace.go:171","msg":"trace[2133132741] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1347; }","duration":"491.51035ms","start":"2026-06-28T20:00:48.171645Z","end":"2026-06-28T20:00:48.663155Z","steps":["trace[2133132741] 'agreement among raft nodes before linearized reading' (duration: 491.414749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.663187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:48.171623Z","time spent":"491.558091ms","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:00:48.663237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.160825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:48.663264Z","caller":"traceutil/trace.go:171","msg":"trace[245781036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"470.230875ms","start":"2026-06-28T20:00:48.193026Z","end":"2026-06-28T20:00:48.663257Z","steps":["trace[245781036] 'agreement among raft nodes before linearized reading' (duration: 470.187345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:48.663289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:48.192993Z","time spent":"470.289927ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T20:00:48.663489Z","caller":"traceutil/trace.go:171","msg":"trace[135923790] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"235.910557ms","start":"2026-06-28T20:00:48.42722Z","end":"2026-06-28T20:00:48.663131Z","steps":["trace[135923790] 'process raft request' (duration: 235.712113ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:56.013157Z","caller":"traceutil/trace.go:171","msg":"trace[434346416] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"317.522814ms","start":"2026-06-28T20:00:55.695608Z","end":"2026-06-28T20:00:56.013131Z","steps":["trace[434346416] 'read index received' (duration: 317.237919ms)","trace[434346416] 'applied index is now lower than readState.Index' (duration: 283.885µs)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:56.013374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.748738ms","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-06-28T20:00:56.013331Z","caller":"traceutil/trace.go:171","msg":"trace[941350006] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"497.874193ms","start":"2026-06-28T20:00:55.515405Z","end":"2026-06-28T20:00:56.013279Z","steps":["trace[941350006] 'process raft request' (duration: 497.559117ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:56.013419Z","caller":"traceutil/trace.go:171","msg":"trace[1893697847] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1403; }","duration":"317.85339ms","start":"2026-06-28T20:00:55.695551Z","end":"2026-06-28T20:00:56.013404Z","steps":["trace[1893697847] 'agreement among raft nodes before linearized reading' (duration: 317.679027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:56.01347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:55.695528Z","time spent":"317.931822ms","remote":"127.0.0.1:33548","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-06-28T20:00:56.013568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:55.51539Z","time spent":"498.049917ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:56.696056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312349599820777,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T20:00:57.082649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.404076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T20:00:57.082778Z","caller":"traceutil/trace.go:171","msg":"trace[666601122] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"886.768632ms","start":"2026-06-28T20:00:56.195985Z","end":"2026-06-28T20:00:57.082754Z","steps":["trace[666601122] 'read index received' (duration: 781.210282ms)","trace[666601122] 'applied index is now lower than readState.Index' (duration: 105.55658ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:00:57.082862Z","caller":"traceutil/trace.go:171","msg":"trace[1012472641] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"1.064392159s","start":"2026-06-28T20:00:56.018457Z","end":"2026-06-28T20:00:57.082849Z","steps":["trace[1012472641] 'process raft request' (duration: 958.667366ms)","trace[1012472641] 'compare' (duration: 104.640192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:00:57.082913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:56.01843Z","time spent":"1.064460182s","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:00:57.082994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.965126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:00:57.083106Z","caller":"traceutil/trace.go:171","msg":"trace[1744357082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1404; }","duration":"887.148469ms","start":"2026-06-28T20:00:56.195928Z","end":"2026-06-28T20:00:57.083076Z","steps":["trace[1744357082] 'agreement among raft nodes before linearized reading' (duration: 886.958926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:57.083174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:00:56.195905Z","time spent":"887.255342ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T20:00:57.084836Z","caller":"traceutil/trace.go:171","msg":"trace[1450601188] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"287.208312ms","start":"2026-06-28T20:00:56.797592Z","end":"2026-06-28T20:00:57.0848Z","steps":["trace[1450601188] 'process raft request' (duration: 286.858086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:57.084977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.329003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-28T20:00:57.085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.041812ms","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-06-28T20:00:57.085022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.289122ms","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-06-28T20:00:57.085033Z","caller":"traceutil/trace.go:171","msg":"trace[365386215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1405; }","duration":"271.403985ms","start":"2026-06-28T20:00:56.813615Z","end":"2026-06-28T20:00:57.085019Z","steps":["trace[365386215] 'agreement among raft nodes before linearized reading' (duration: 271.263033ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:57.085057Z","caller":"traceutil/trace.go:171","msg":"trace[1362661853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1405; }","duration":"271.383085ms","start":"2026-06-28T20:00:56.813664Z","end":"2026-06-28T20:00:57.085048Z","steps":["trace[1362661853] 'agreement among raft nodes before linearized reading' (duration: 271.207842ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:57.085059Z","caller":"traceutil/trace.go:171","msg":"trace[1216629398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1405; }","duration":"275.168466ms","start":"2026-06-28T20:00:56.809875Z","end":"2026-06-28T20:00:57.085043Z","steps":["trace[1216629398] 'agreement among raft nodes before linearized reading' (duration: 274.713127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:00:57.085132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.729792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-28T20:00:57.085423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.427705ms","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-06-28T20:00:57.085443Z","caller":"traceutil/trace.go:171","msg":"trace[1215131747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1405; }","duration":"271.088159ms","start":"2026-06-28T20:00:56.814297Z","end":"2026-06-28T20:00:57.085385Z","steps":["trace[1215131747] 'agreement among raft nodes before linearized reading' (duration: 270.443926ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:57.085554Z","caller":"traceutil/trace.go:171","msg":"trace[1730189824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1405; }","duration":"271.637889ms","start":"2026-06-28T20:00:56.813838Z","end":"2026-06-28T20:00:57.085476Z","steps":["trace[1730189824] 'agreement among raft nodes before linearized reading' (duration: 271.295032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:01:13.168439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.583596ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T20:01:45.608695Z","caller":"traceutil/trace.go:171","msg":"trace[759871348] linearizableReadLoop","detail":"{readStateIndex:1695; appliedIndex:1694; }","duration":"168.508756ms","start":"2026-06-28T20:01:45.440165Z","end":"2026-06-28T20:01:45.608673Z","steps":["trace[759871348] 'read index received' (duration: 168.302602ms)","trace[759871348] 'applied index is now lower than readState.Index' (duration: 205.284µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:01:45.608764Z","caller":"traceutil/trace.go:171","msg":"trace[384192176] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"169.902062ms","start":"2026-06-28T20:01:45.438823Z","end":"2026-06-28T20:01:45.608725Z","steps":["trace[384192176] 'process raft request' (duration: 169.713879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:01:45.608893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.72014ms","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-06-28T20:01:45.608938Z","caller":"traceutil/trace.go:171","msg":"trace[1394298476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1629; }","duration":"168.803212ms","start":"2026-06-28T20:01:45.440121Z","end":"2026-06-28T20:01:45.608925Z","steps":["trace[1394298476] 'agreement among raft nodes before linearized reading' (duration: 168.670979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:01:45.608945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.515766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T20:01:45.608993Z","caller":"traceutil/trace.go:171","msg":"trace[1189502109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1629; }","duration":"168.601747ms","start":"2026-06-28T20:01:45.440377Z","end":"2026-06-28T20:01:45.608979Z","steps":["trace[1189502109] 'agreement among raft nodes before linearized reading' (duration: 168.483215ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:01:47.786174Z","caller":"traceutil/trace.go:171","msg":"trace[1783563877] linearizableReadLoop","detail":"{readStateIndex:1705; appliedIndex:1703; }","duration":"132.13771ms","start":"2026-06-28T20:01:47.654007Z","end":"2026-06-28T20:01:47.786145Z","steps":["trace[1783563877] 'read index received' (duration: 81.010066ms)","trace[1783563877] 'applied index is now lower than readState.Index' (duration: 51.126224ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:01:47.786223Z","caller":"traceutil/trace.go:171","msg":"trace[2109385342] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"169.562125ms","start":"2026-06-28T20:01:47.616609Z","end":"2026-06-28T20:01:47.786171Z","steps":["trace[2109385342] 'process raft request' (duration: 118.428191ms)","trace[2109385342] 'compare' (duration: 50.813768ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:01:47.786323Z","caller":"traceutil/trace.go:171","msg":"trace[1897814840] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"165.116592ms","start":"2026-06-28T20:01:47.621192Z","end":"2026-06-28T20:01:47.786309Z","steps":["trace[1897814840] 'process raft request' (duration: 164.883807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:01:47.786575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.551197ms","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-06-28T20:01:47.786624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.157799ms","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-06-28T20:01:47.786671Z","caller":"traceutil/trace.go:171","msg":"trace[1044916957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1638; }","duration":"125.24532ms","start":"2026-06-28T20:01:47.661414Z","end":"2026-06-28T20:01:47.786659Z","steps":["trace[1044916957] 'agreement among raft nodes before linearized reading' (duration: 125.032036ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:01:47.786688Z","caller":"traceutil/trace.go:171","msg":"trace[1670773503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1638; }","duration":"132.67279ms","start":"2026-06-28T20:01:47.653981Z","end":"2026-06-28T20:01:47.786654Z","steps":["trace[1670773503] 'agreement among raft nodes before linearized reading' (duration: 132.460066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:01:47.786708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.577866ms","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-06-28T20:01:47.786753Z","caller":"traceutil/trace.go:171","msg":"trace[1763386136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1638; }","duration":"125.670227ms","start":"2026-06-28T20:01:47.661066Z","end":"2026-06-28T20:01:47.786737Z","steps":["trace[1763386136] 'agreement among raft nodes before linearized reading' (duration: 125.573026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:01:47.786575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.170188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T20:01:47.786861Z","caller":"traceutil/trace.go:171","msg":"trace[1156421935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1638; }","duration":"126.537844ms","start":"2026-06-28T20:01:47.660303Z","end":"2026-06-28T20:01:47.78684Z","steps":["trace[1156421935] 'agreement among raft nodes before linearized reading' (duration: 126.055126ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:38.817782Z","caller":"traceutil/trace.go:171","msg":"trace[1337211995] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"102.7061ms","start":"2026-06-28T20:02:38.715015Z","end":"2026-06-28T20:02:38.817721Z","steps":["trace[1337211995] 'process raft request' (duration: 102.425114ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:39.737751Z","caller":"traceutil/trace.go:171","msg":"trace[1850297062] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"109.066469ms","start":"2026-06-28T20:02:39.628654Z","end":"2026-06-28T20:02:39.737721Z","steps":["trace[1850297062] 'process raft request' (duration: 108.915446ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:46.358295Z","caller":"traceutil/trace.go:171","msg":"trace[1017017824] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"111.548025ms","start":"2026-06-28T20:02:46.246718Z","end":"2026-06-28T20:02:46.358266Z","steps":["trace[1017017824] 'process raft request' (duration: 81.088652ms)","trace[1017017824] 'compare' (duration: 30.29946ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:48.551933Z","caller":"traceutil/trace.go:171","msg":"trace[1305193258] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"150.354874ms","start":"2026-06-28T20:02:48.401545Z","end":"2026-06-28T20:02:48.5519Z","steps":["trace[1305193258] 'process raft request' (duration: 89.758876ms)","trace[1305193258] 'compare' (duration: 60.444965ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:48.552087Z","caller":"traceutil/trace.go:171","msg":"trace[1469724511] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1996; }","duration":"127.159489ms","start":"2026-06-28T20:02:48.424897Z","end":"2026-06-28T20:02:48.552056Z","steps":["trace[1469724511] 'read index received' (duration: 66.423828ms)","trace[1469724511] 'applied index is now lower than readState.Index' (duration: 60.734231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:02:48.5523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.376353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:02:48.552311Z","caller":"traceutil/trace.go:171","msg":"trace[2047032190] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"150.488716ms","start":"2026-06-28T20:02:48.401802Z","end":"2026-06-28T20:02:48.552291Z","steps":["trace[2047032190] 'process raft request' (duration: 150.061528ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:48.552358Z","caller":"traceutil/trace.go:171","msg":"trace[580875311] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1919; }","duration":"127.477954ms","start":"2026-06-28T20:02:48.424865Z","end":"2026-06-28T20:02:48.552343Z","steps":["trace[580875311] 'agreement among raft nodes before linearized reading' (duration: 127.288831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:03:13.053812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.549526ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-28T20:03:22.329634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.408849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T20:03:22.329712Z","caller":"traceutil/trace.go:171","msg":"trace[475969876] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2070; }","duration":"311.550422ms","start":"2026-06-28T20:03:22.018143Z","end":"2026-06-28T20:03:22.329693Z","steps":["trace[475969876] 'count revisions from in-memory index tree' (duration: 311.286377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:03:22.329753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:03:22.018122Z","time spent":"311.619083ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-28T20:03:22.329933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.372362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:03:22.330106Z","caller":"traceutil/trace.go:171","msg":"trace[1115189116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2070; }","duration":"136.633987ms","start":"2026-06-28T20:03:22.193435Z","end":"2026-06-28T20:03:22.330069Z","steps":["trace[1115189116] 'range keys from in-memory index tree' (duration: 136.221899ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:03:22.5639Z","caller":"traceutil/trace.go:171","msg":"trace[347432166] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"199.988717ms","start":"2026-06-28T20:03:22.363893Z","end":"2026-06-28T20:03:22.563882Z","steps":["trace[347432166] 'process raft request' (duration: 199.469607ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:03:22.707863Z","caller":"traceutil/trace.go:171","msg":"trace[701854958] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"138.546522ms","start":"2026-06-28T20:03:22.569032Z","end":"2026-06-28T20:03:22.707578Z","steps":["trace[701854958] 'process raft request' (duration: 115.458838ms)","trace[701854958] 'compare' (duration: 22.833408ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:03:48.833859Z","caller":"traceutil/trace.go:171","msg":"trace[148512995] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"126.640818ms","start":"2026-06-28T20:03:48.707192Z","end":"2026-06-28T20:03:48.833833Z","steps":["trace[148512995] 'process raft request' (duration: 126.419734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:03:49.357392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.241541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-28T20:03:49.357489Z","caller":"traceutil/trace.go:171","msg":"trace[1413021610] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2197; }","duration":"101.386394ms","start":"2026-06-28T20:03:49.256077Z","end":"2026-06-28T20:03:49.357463Z","steps":["trace[1413021610] 'range keys from in-memory index tree' (duration: 101.049848ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:03:52.228359Z","caller":"traceutil/trace.go:171","msg":"trace[678033132] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"296.200664ms","start":"2026-06-28T20:03:51.932121Z","end":"2026-06-28T20:03:52.228322Z","steps":["trace[678033132] 'process raft request' (duration: 296.020881ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:03:52.462153Z","caller":"traceutil/trace.go:171","msg":"trace[744707030] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2297; }","duration":"268.83447ms","start":"2026-06-28T20:03:52.193278Z","end":"2026-06-28T20:03:52.462112Z","steps":["trace[744707030] 'read index received' (duration: 34.911686ms)","trace[744707030] 'applied index is now lower than readState.Index' (duration: 233.921424ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:03:52.462265Z","caller":"traceutil/trace.go:171","msg":"trace[631768083] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"411.571581ms","start":"2026-06-28T20:03:52.050647Z","end":"2026-06-28T20:03:52.462218Z","steps":["trace[631768083] 'process raft request' (duration: 411.201554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:03:52.462564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:03:52.050593Z","time spent":"411.818505ms","remote":"127.0.0.1:33548","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-06-28T20:03:52.462339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.042744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:03:52.462667Z","caller":"traceutil/trace.go:171","msg":"trace[1284174237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2208; }","duration":"269.44405ms","start":"2026-06-28T20:03:52.193207Z","end":"2026-06-28T20:03:52.462651Z","steps":["trace[1284174237] 'agreement among raft nodes before linearized reading' (duration: 269.044383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:03:53.331827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.241651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T20:03:53.331971Z","caller":"traceutil/trace.go:171","msg":"trace[1272208536] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"172.328257ms","start":"2026-06-28T20:03:53.159616Z","end":"2026-06-28T20:03:53.331945Z","steps":["trace[1272208536] 'process raft request' (duration: 70.876042ms)","trace[1272208536] 'compare' (duration: 101.096779ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:04:48.316292Z","caller":"traceutil/trace.go:171","msg":"trace[2133816571] linearizableReadLoop","detail":"{readStateIndex:2597; appliedIndex:2596; }","duration":"124.410261ms","start":"2026-06-28T20:04:48.191843Z","end":"2026-06-28T20:04:48.316254Z","steps":["trace[2133816571] 'read index received' (duration: 124.094345ms)","trace[2133816571] 'applied index is now lower than readState.Index' (duration: 314.786µs)"],"step_count":2} {"level":"warn","ts":"2026-06-28T20:04:48.316483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.599894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:04:48.316528Z","caller":"traceutil/trace.go:171","msg":"trace[17677919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2493; }","duration":"124.712906ms","start":"2026-06-28T20:04:48.191803Z","end":"2026-06-28T20:04:48.316515Z","steps":["trace[17677919] 'agreement among raft nodes before linearized reading' (duration: 124.593224ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:04:48.316716Z","caller":"traceutil/trace.go:171","msg":"trace[1989922090] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"327.462667ms","start":"2026-06-28T20:04:47.989183Z","end":"2026-06-28T20:04:48.316646Z","steps":["trace[1989922090] 'process raft request' (duration: 326.880646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:04:48.317042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:04:47.989158Z","time spent":"327.730391ms","remote":"127.0.0.1:33548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T20:04:48.927492Z","caller":"traceutil/trace.go:171","msg":"trace[212215997] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"177.456562ms","start":"2026-06-28T20:04:48.750005Z","end":"2026-06-28T20:04:48.927462Z","steps":["trace[212215997] 'process raft request' (duration: 177.256378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:04:49.343528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.408908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:04:49.343636Z","caller":"traceutil/trace.go:171","msg":"trace[1073348061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2494; }","duration":"152.553531ms","start":"2026-06-28T20:04:49.191053Z","end":"2026-06-28T20:04:49.343606Z","steps":["trace[1073348061] 'range keys from in-memory index tree' (duration: 152.316667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:04:49.343625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.114329ms","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:525"} {"level":"info","ts":"2026-06-28T20:04:49.343785Z","caller":"traceutil/trace.go:171","msg":"trace[1930436] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2494; }","duration":"192.319134ms","start":"2026-06-28T20:04:49.151417Z","end":"2026-06-28T20:04:49.343736Z","steps":["trace[1930436] 'range keys from in-memory index tree' (duration: 191.869844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:04:49.34399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.478848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T20:04:49.344073Z","caller":"traceutil/trace.go:171","msg":"trace[1320863777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2494; }","duration":"172.615441ms","start":"2026-06-28T20:04:49.171438Z","end":"2026-06-28T20:04:49.344053Z","steps":["trace[1320863777] 'range keys from in-memory index tree' (duration: 172.267824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:04:49.344122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.396498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T20:04:49.344222Z","caller":"traceutil/trace.go:171","msg":"trace[35365024] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2494; }","duration":"244.124701ms","start":"2026-06-28T20:04:49.100075Z","end":"2026-06-28T20:04:49.3442Z","steps":["trace[35365024] 'count revisions from in-memory index tree' (duration: 243.275916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:04:49.344566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.361699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-28T20:04:49.345327Z","caller":"traceutil/trace.go:171","msg":"trace[1317467812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2494; }","duration":"171.542601ms","start":"2026-06-28T20:04:49.173751Z","end":"2026-06-28T20:04:49.345293Z","steps":["trace[1317467812] 'range keys from in-memory index tree' (duration: 169.576124ms)"],"step_count":1}