{"level":"warn","ts":"2026-08-04T15:18:26.025507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:18:26.025624Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.60: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.19.213.60:2380","--initial-cluster=instance=https://199.19.213.60:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.60:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.60: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-08-04T15:18:26.025704Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:18:26.02572Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-08-04T15:18:26.025747Z","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-08-04T15:18:26.026472Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"]} {"level":"info","ts":"2026-08-04T15:18:26.026565Z","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.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.60: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-08-04T15:18:26.033825Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.025531ms"} {"level":"info","ts":"2026-08-04T15:18:26.043978Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3490cdb1ff3fc828","cluster-id":"872e6d60ada2e30c"} {"level":"info","ts":"2026-08-04T15:18:26.044079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:18:26.04412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:18:26.044146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3490cdb1ff3fc828 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:18:26.044164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:18:26.044219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"warn","ts":"2026-08-04T15:18:26.061474Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:18:26.062851Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:18:26.064962Z","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-08-04T15:18:26.069308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3490cdb1ff3fc828","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:18:26.069893Z","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-08-04T15:18:26.070218Z","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-08-04T15:18:26.070235Z","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-08-04T15:18:26.069736Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3490cdb1ff3fc828","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:18:26.072715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"info","ts":"2026-08-04T15:18:26.073713Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","added-peer-id":"3490cdb1ff3fc828","added-peer-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-08-04T15:18:26.075068Z","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-08-04T15:18:26.075215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-08-04T15:18:26.075278Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-08-04T15:18:26.07532Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3490cdb1ff3fc828","initial-advertise-peer-urls":["https://199.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:18:26.075413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:18:26.245764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:18:26.245826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:18:26.245868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgPreVoteResp from 3490cdb1ff3fc828 at term 1"} {"level":"info","ts":"2026-08-04T15:18:26.245888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:18:26.245905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgVoteResp from 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-08-04T15:18:26.245922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:18:26.245978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3490cdb1ff3fc828 elected leader 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-08-04T15:18:26.247476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3490cdb1ff3fc828","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.60:2379]}","request-path":"/0/members/3490cdb1ff3fc828/attributes","cluster-id":"872e6d60ada2e30c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:18:26.247563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:18:26.247845Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:18:26.248143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:18:26.248168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:18:26.251069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:18:26.251254Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:18:26.254096Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.60:2379"} {"level":"info","ts":"2026-08-04T15:18:26.257006Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:18:26.257126Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:18:26.257203Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:18:51.667762Z","caller":"traceutil/trace.go:171","msg":"trace[1666135468] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"136.139036ms","start":"2026-08-04T15:18:51.531594Z","end":"2026-08-04T15:18:51.667733Z","steps":["trace[1666135468] 'process raft request' (duration: 135.969611ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:08.541759Z","caller":"traceutil/trace.go:171","msg":"trace[1870158320] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"131.073886ms","start":"2026-08-04T15:19:08.410654Z","end":"2026-08-04T15:19:08.541728Z","steps":["trace[1870158320] 'process raft request' (duration: 129.912769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:08.542267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.118261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wvkhg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-04T15:19:08.542369Z","caller":"traceutil/trace.go:171","msg":"trace[1754166324] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wvkhg; range_end:; response_count:1; response_revision:628; }","duration":"138.255205ms","start":"2026-08-04T15:19:08.4041Z","end":"2026-08-04T15:19:08.542355Z","steps":["trace[1754166324] 'range keys from in-memory index tree' (duration: 137.292884ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:08.56186Z","caller":"traceutil/trace.go:171","msg":"trace[1697644694] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"145.562768ms","start":"2026-08-04T15:19:08.416271Z","end":"2026-08-04T15:19:08.561834Z","steps":["trace[1697644694] 'process raft request' (duration: 145.411763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:09.768074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953510994051447,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:19:09.790638Z","caller":"traceutil/trace.go:171","msg":"trace[1423603334] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"523.538233ms","start":"2026-08-04T15:19:09.26707Z","end":"2026-08-04T15:19:09.790608Z","steps":["trace[1423603334] 'read index received' (duration: 522.578632ms)","trace[1423603334] 'applied index is now lower than readState.Index' (duration: 958.641µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:19:09.790725Z","caller":"traceutil/trace.go:171","msg":"trace[383215915] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"770.108409ms","start":"2026-08-04T15:19:09.020572Z","end":"2026-08-04T15:19:09.79068Z","steps":["trace[383215915] 'process raft request' (duration: 769.401517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:09.790859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.7655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:19:09.790954Z","caller":"traceutil/trace.go:171","msg":"trace[349368950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"523.985077ms","start":"2026-08-04T15:19:09.266949Z","end":"2026-08-04T15:19:09.790934Z","steps":["trace[349368950] 'agreement among raft nodes before linearized reading' (duration: 523.811002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:09.790994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.26693Z","time spent":"524.054899ms","remote":"127.0.0.1:60082","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-08-04T15:19:09.791313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.620211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-04T15:19:09.791398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.020553Z","time spent":"770.256584ms","remote":"127.0.0.1:60188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:19:09.791425Z","caller":"traceutil/trace.go:171","msg":"trace[90181176] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:632; }","duration":"419.742325ms","start":"2026-08-04T15:19:09.371624Z","end":"2026-08-04T15:19:09.791366Z","steps":["trace[90181176] 'agreement among raft nodes before linearized reading' (duration: 419.172647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:09.791631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.371607Z","time spent":"420.003744ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.60\" "} {"level":"warn","ts":"2026-08-04T15:19:11.937151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.130728574s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-04T15:19:11.937422Z","caller":"traceutil/trace.go:171","msg":"trace[569940028] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.091867126s","start":"2026-08-04T15:19:09.845537Z","end":"2026-08-04T15:19:11.937404Z","steps":["trace[569940028] 'process raft request' (duration: 2.091827494s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:11.937498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.845512Z","time spent":"2.091944798s","remote":"127.0.0.1:60632","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-08-04T15:19:11.93778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.793044Z","time spent":"2.14473396s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-04T15:19:11.937893Z","caller":"traceutil/trace.go:171","msg":"trace[1057553503] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"2.131421016s","start":"2026-08-04T15:19:09.806425Z","end":"2026-08-04T15:19:11.937846Z","steps":["trace[1057553503] 'process raft request' (duration: 2.130824597s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:11.937917Z","caller":"traceutil/trace.go:171","msg":"trace[1941572455] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"2.140912608s","start":"2026-08-04T15:19:09.796978Z","end":"2026-08-04T15:19:11.937891Z","steps":["trace[1941572455] 'read index received' (duration: 9.183172ms)","trace[1941572455] 'applied index is now lower than readState.Index' (duration: 2.131726056s)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:19:11.938047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.806404Z","time spent":"2.13156159s","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:19:11.938254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905737215s","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-08-04T15:19:11.938259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609437343s","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-08-04T15:19:11.938302Z","caller":"traceutil/trace.go:171","msg":"trace[1724177524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"1.905830278s","start":"2026-08-04T15:19:10.032456Z","end":"2026-08-04T15:19:11.938286Z","steps":["trace[1724177524] 'agreement among raft nodes before linearized reading' (duration: 1.905650082s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:11.938334Z","caller":"traceutil/trace.go:171","msg":"trace[1405973578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.609582958s","start":"2026-08-04T15:19:10.328732Z","end":"2026-08-04T15:19:11.938315Z","steps":["trace[1405973578] 'agreement among raft nodes before linearized reading' (duration: 1.609430853s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:11.938341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:10.032426Z","time spent":"1.90590657s","remote":"127.0.0.1:60378","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-08-04T15:19:11.938378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:10.328708Z","time spent":"1.60966173s","remote":"127.0.0.1:60378","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-08-04T15:19:11.938446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671803641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:19:11.93851Z","caller":"traceutil/trace.go:171","msg":"trace[1178385146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.671922114s","start":"2026-08-04T15:19:10.266571Z","end":"2026-08-04T15:19:11.938493Z","steps":["trace[1178385146] 'agreement among raft nodes before linearized reading' (duration: 1.67179489s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:11.93856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:10.266545Z","time spent":"1.672006887s","remote":"127.0.0.1:60092","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-08-04T15:19:11.938685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.141702463s","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-08-04T15:19:11.93872Z","caller":"traceutil/trace.go:171","msg":"trace[434197457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"2.141764405s","start":"2026-08-04T15:19:09.796945Z","end":"2026-08-04T15:19:11.938709Z","steps":["trace[434197457] 'agreement among raft nodes before linearized reading' (duration: 2.141086803s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:11.938747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:09.796929Z","time spent":"2.141811817s","remote":"127.0.0.1:60378","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-08-04T15:19:13.796461Z","caller":"traceutil/trace.go:171","msg":"trace[1836197646] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"109.236031ms","start":"2026-08-04T15:19:13.687201Z","end":"2026-08-04T15:19:13.796437Z","steps":["trace[1836197646] 'process raft request' (duration: 109.053435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:24.571858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.445311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:19:24.571949Z","caller":"traceutil/trace.go:171","msg":"trace[158011782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"304.591466ms","start":"2026-08-04T15:19:24.267339Z","end":"2026-08-04T15:19:24.571931Z","steps":["trace[158011782] 'range keys from in-memory index tree' (duration: 304.358588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:24.571995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:24.267269Z","time spent":"304.71654ms","remote":"127.0.0.1:60092","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-08-04T15:19:24.572268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.955632ms","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-08-04T15:19:24.572593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.252792ms","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-08-04T15:19:24.572635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.672459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-04T15:19:24.572646Z","caller":"traceutil/trace.go:171","msg":"trace[2034819710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"500.106097ms","start":"2026-08-04T15:19:24.072225Z","end":"2026-08-04T15:19:24.572331Z","steps":["trace[2034819710] 'range keys from in-memory index tree' (duration: 499.619691ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:24.572671Z","caller":"traceutil/trace.go:171","msg":"trace[1777150808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"502.760851ms","start":"2026-08-04T15:19:24.069898Z","end":"2026-08-04T15:19:24.572658Z","steps":["trace[1777150808] 'range keys from in-memory index tree' (duration: 502.496573ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:24.572657Z","caller":"traceutil/trace.go:171","msg":"trace[285498936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"500.365555ms","start":"2026-08-04T15:19:24.072273Z","end":"2026-08-04T15:19:24.572638Z","steps":["trace[285498936] 'range keys from in-memory index tree' (duration: 500.032515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:24.572695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:24.069871Z","time spent":"502.818413ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:19:24.572714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:24.07226Z","time spent":"500.440188ms","remote":"127.0.0.1:60378","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-08-04T15:19:24.572712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:24.072201Z","time spent":"500.49511ms","remote":"127.0.0.1:60378","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-08-04T15:19:28.05919Z","caller":"traceutil/trace.go:171","msg":"trace[293092852] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"701.353319ms","start":"2026-08-04T15:19:27.357814Z","end":"2026-08-04T15:19:28.059168Z","steps":["trace[293092852] 'process raft request' (duration: 701.197894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:28.059355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:27.357786Z","time spent":"701.480563ms","remote":"127.0.0.1:60378","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-08-04T15:19:28.360022Z","caller":"traceutil/trace.go:171","msg":"trace[923294424] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:827; }","duration":"484.531699ms","start":"2026-08-04T15:19:27.87546Z","end":"2026-08-04T15:19:28.359991Z","steps":["trace[923294424] 'read index received' (duration: 183.56448ms)","trace[923294424] 'applied index is now lower than readState.Index' (duration: 300.965809ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:19:28.360223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.733805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:19:28.360402Z","caller":"traceutil/trace.go:171","msg":"trace[2123949306] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:799; }","duration":"484.921171ms","start":"2026-08-04T15:19:27.875405Z","end":"2026-08-04T15:19:28.360326Z","steps":["trace[2123949306] 'agreement among raft nodes before linearized reading' (duration: 484.717845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:28.360501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:27.875377Z","time spent":"485.102397ms","remote":"127.0.0.1:60594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:19:28.360528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.396345ms","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-08-04T15:19:28.360696Z","caller":"traceutil/trace.go:171","msg":"trace[1846995173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"361.651303ms","start":"2026-08-04T15:19:27.999029Z","end":"2026-08-04T15:19:28.360681Z","steps":["trace[1846995173] 'agreement among raft nodes before linearized reading' (duration: 361.100325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:28.361072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:27.999011Z","time spent":"361.8739ms","remote":"127.0.0.1:60378","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-08-04T15:19:28.361308Z","caller":"traceutil/trace.go:171","msg":"trace[616521026] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"823.488339ms","start":"2026-08-04T15:19:27.536544Z","end":"2026-08-04T15:19:28.360032Z","steps":["trace[616521026] 'process raft request' (duration: 823.268512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:28.361484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:27.536517Z","time spent":"824.864593ms","remote":"127.0.0.1:60336","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-08-04T15:20:02.506626Z","caller":"traceutil/trace.go:171","msg":"trace[1545185513] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"241.801285ms","start":"2026-08-04T15:20:02.2648Z","end":"2026-08-04T15:20:02.506601Z","steps":["trace[1545185513] 'read index received' (duration: 241.594548ms)","trace[1545185513] 'applied index is now lower than readState.Index' (duration: 206.207µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:20:02.50677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.946459ms","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-08-04T15:20:02.506801Z","caller":"traceutil/trace.go:171","msg":"trace[698656450] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1015; }","duration":"242.024842ms","start":"2026-08-04T15:20:02.264768Z","end":"2026-08-04T15:20:02.506793Z","steps":["trace[698656450] 'agreement among raft nodes before linearized reading' (duration: 241.934589ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:02.506764Z","caller":"traceutil/trace.go:171","msg":"trace[579419690] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"276.292273ms","start":"2026-08-04T15:20:02.230387Z","end":"2026-08-04T15:20:02.506679Z","steps":["trace[579419690] 'process raft request' (duration: 276.048976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:02.506903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.345249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:20:02.506993Z","caller":"traceutil/trace.go:171","msg":"trace[1877764671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"240.494274ms","start":"2026-08-04T15:20:02.266477Z","end":"2026-08-04T15:20:02.506971Z","steps":["trace[1877764671] 'agreement among raft nodes before linearized reading' (duration: 240.294397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:04.083294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166403306s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:20:04.083417Z","caller":"traceutil/trace.go:171","msg":"trace[500939000] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.556972992s","start":"2026-08-04T15:20:02.52642Z","end":"2026-08-04T15:20:04.083393Z","steps":["trace[500939000] 'process raft request' (duration: 390.38097ms)","trace[500939000] 'compare' (duration: 1.16591346s)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:20:04.083478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:02.526404Z","time spent":"1.557044844s","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:20:04.177435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.260608068s","expected-duration":"1s"} {"level":"info","ts":"2026-08-04T15:20:04.17785Z","caller":"traceutil/trace.go:171","msg":"trace[100376606] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"1.424461419s","start":"2026-08-04T15:20:02.753366Z","end":"2026-08-04T15:20:04.177827Z","steps":["trace[100376606] 'read index received' (duration: 163.448678ms)","trace[100376606] 'applied index is now lower than readState.Index' (duration: 1.261011741s)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:20:04.178018Z","caller":"traceutil/trace.go:171","msg":"trace[808278126] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.389180404s","start":"2026-08-04T15:20:02.788808Z","end":"2026-08-04T15:20:04.177988Z","steps":["trace[808278126] 'process raft request' (duration: 1.388979588s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:04.178119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:02.78875Z","time spent":"1.389318839s","remote":"127.0.0.1:60378","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-08-04T15:20:04.17813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424278083s","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-08-04T15:20:04.178167Z","caller":"traceutil/trace.go:171","msg":"trace[1888132815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"1.424370997s","start":"2026-08-04T15:20:02.753786Z","end":"2026-08-04T15:20:04.178157Z","steps":["trace[1888132815] 'agreement among raft nodes before linearized reading' (duration: 1.42417947s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:04.178178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.911564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:20:04.178154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424761038s","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-08-04T15:20:04.178202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:02.753756Z","time spent":"1.424437639s","remote":"127.0.0.1:60378","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-08-04T15:20:04.17822Z","caller":"traceutil/trace.go:171","msg":"trace[369894456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"911.002998ms","start":"2026-08-04T15:20:03.267202Z","end":"2026-08-04T15:20:04.178205Z","steps":["trace[369894456] 'agreement among raft nodes before linearized reading' (duration: 910.925336ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:04.178238Z","caller":"traceutil/trace.go:171","msg":"trace[1765557293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"1.424889803s","start":"2026-08-04T15:20:02.753333Z","end":"2026-08-04T15:20:04.178223Z","steps":["trace[1765557293] 'agreement among raft nodes before linearized reading' (duration: 1.424607704s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:04.178288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:02.753315Z","time spent":"1.424964695s","remote":"127.0.0.1:60378","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-08-04T15:20:04.178251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:03.267182Z","time spent":"911.063939ms","remote":"127.0.0.1:60092","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-08-04T15:20:04.178379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415817773s","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-08-04T15:20:04.178413Z","caller":"traceutil/trace.go:171","msg":"trace[580782729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"1.415869725s","start":"2026-08-04T15:20:02.762534Z","end":"2026-08-04T15:20:04.178403Z","steps":["trace[580782729] 'agreement among raft nodes before linearized reading' (duration: 1.415751612s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:04.17844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:02.762523Z","time spent":"1.415910876s","remote":"127.0.0.1:60378","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-08-04T15:20:04.178635Z","caller":"traceutil/trace.go:171","msg":"trace[942634913] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.499424357s","start":"2026-08-04T15:20:02.678446Z","end":"2026-08-04T15:20:04.17787Z","steps":["trace[942634913] 'process raft request' (duration: 1.499216151s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:04.178915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:20:02.678417Z","time spent":"1.500402499s","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:20:10.470277Z","caller":"traceutil/trace.go:171","msg":"trace[1528807759] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"212.273423ms","start":"2026-08-04T15:20:10.257972Z","end":"2026-08-04T15:20:10.470246Z","steps":["trace[1528807759] 'process raft request' (duration: 183.16587ms)","trace[1528807759] 'compare' (duration: 28.935927ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:20:10.470666Z","caller":"traceutil/trace.go:171","msg":"trace[798357240] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1079; }","duration":"205.436104ms","start":"2026-08-04T15:20:10.265074Z","end":"2026-08-04T15:20:10.47051Z","steps":["trace[798357240] 'read index received' (duration: 5.690493ms)","trace[798357240] 'applied index is now lower than readState.Index' (duration: 199.743511ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:20:10.470629Z","caller":"traceutil/trace.go:171","msg":"trace[1044135299] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"212.412217ms","start":"2026-08-04T15:20:10.258197Z","end":"2026-08-04T15:20:10.47061Z","steps":["trace[1044135299] 'process raft request' (duration: 212.003514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:10.471322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.860228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:20:10.471861Z","caller":"traceutil/trace.go:171","msg":"trace[1784647596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"206.752016ms","start":"2026-08-04T15:20:10.265043Z","end":"2026-08-04T15:20:10.471795Z","steps":["trace[1784647596] 'agreement among raft nodes before linearized reading' (duration: 205.761075ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:48.608706Z","caller":"traceutil/trace.go:171","msg":"trace[921060275] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"224.017769ms","start":"2026-08-04T15:20:48.384614Z","end":"2026-08-04T15:20:48.608632Z","steps":["trace[921060275] 'process raft request' (duration: 215.648801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:20:48.609019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.076125ms","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-08-04T15:20:48.609097Z","caller":"traceutil/trace.go:171","msg":"trace[227105141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1214; }","duration":"138.236001ms","start":"2026-08-04T15:20:48.470842Z","end":"2026-08-04T15:20:48.609078Z","steps":["trace[227105141] 'agreement among raft nodes before linearized reading' (duration: 138.045204ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:48.608624Z","caller":"traceutil/trace.go:171","msg":"trace[743247715] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"137.658821ms","start":"2026-08-04T15:20:48.470937Z","end":"2026-08-04T15:20:48.608596Z","steps":["trace[743247715] 'read index received' (duration: 129.336854ms)","trace[743247715] 'applied index is now lower than readState.Index' (duration: 8.320507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:05.239861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.175255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:05.240531Z","caller":"traceutil/trace.go:171","msg":"trace[680249662] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"398.693977ms","start":"2026-08-04T15:21:04.841805Z","end":"2026-08-04T15:21:05.240499Z","steps":["trace[680249662] 'process raft request' (duration: 241.800678ms)","trace[680249662] 'compare' (duration: 155.933568ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:05.240638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:04.841786Z","time spent":"398.80509ms","remote":"127.0.0.1:60378","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-08-04T15:21:05.241174Z","caller":"traceutil/trace.go:171","msg":"trace[1878747526] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"381.741294ms","start":"2026-08-04T15:21:04.859418Z","end":"2026-08-04T15:21:05.241159Z","steps":["trace[1878747526] 'read index received' (duration: 224.206345ms)","trace[1878747526] 'applied index is now lower than readState.Index' (duration: 157.532839ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:05.241367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.93822ms","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-08-04T15:21:05.241448Z","caller":"traceutil/trace.go:171","msg":"trace[942790621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"382.068373ms","start":"2026-08-04T15:21:04.859362Z","end":"2026-08-04T15:21:05.24143Z","steps":["trace[942790621] 'agreement among raft nodes before linearized reading' (duration: 381.864907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:05.241501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:04.859339Z","time spent":"382.148146ms","remote":"127.0.0.1:60378","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-08-04T15:21:06.139426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.143134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:06.139594Z","caller":"traceutil/trace.go:171","msg":"trace[1487084187] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"770.069429ms","start":"2026-08-04T15:21:05.369507Z","end":"2026-08-04T15:21:06.139577Z","steps":["trace[1487084187] 'process raft request' (duration: 770.005757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:06.139655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:05.369481Z","time spent":"770.142062ms","remote":"127.0.0.1:60378","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-08-04T15:21:06.139847Z","caller":"traceutil/trace.go:171","msg":"trace[309725856] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"894.358523ms","start":"2026-08-04T15:21:05.245477Z","end":"2026-08-04T15:21:06.139835Z","steps":["trace[309725856] 'process raft request' (duration: 337.720543ms)","trace[309725856] 'compare' (duration: 555.973818ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:06.139917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:05.245453Z","time spent":"894.428855ms","remote":"127.0.0.1:60378","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-08-04T15:21:06.7662Z","caller":"traceutil/trace.go:171","msg":"trace[1287496814] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"150.059189ms","start":"2026-08-04T15:21:06.616118Z","end":"2026-08-04T15:21:06.766177Z","steps":["trace[1287496814] 'process raft request' (duration: 149.896123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:07.074161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.68696ms","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-08-04T15:21:07.074263Z","caller":"traceutil/trace.go:171","msg":"trace[550832313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"243.852775ms","start":"2026-08-04T15:21:06.830384Z","end":"2026-08-04T15:21:07.074237Z","steps":["trace[550832313] 'range keys from in-memory index tree' (duration: 243.421611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:07.074278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.777288ms","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-08-04T15:21:07.074373Z","caller":"traceutil/trace.go:171","msg":"trace[2014242935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"231.923463ms","start":"2026-08-04T15:21:06.842425Z","end":"2026-08-04T15:21:07.074348Z","steps":["trace[2014242935] 'range keys from in-memory index tree' (duration: 231.496069ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:07.595069Z","caller":"traceutil/trace.go:171","msg":"trace[1246432970] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"516.06769ms","start":"2026-08-04T15:21:07.078976Z","end":"2026-08-04T15:21:07.595043Z","steps":["trace[1246432970] 'process raft request' (duration: 515.883834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:07.595231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:07.078953Z","time spent":"516.194143ms","remote":"127.0.0.1:60378","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-08-04T15:21:07.612462Z","caller":"traceutil/trace.go:171","msg":"trace[1433296169] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"367.518238ms","start":"2026-08-04T15:21:07.244919Z","end":"2026-08-04T15:21:07.612437Z","steps":["trace[1433296169] 'read index received' (duration: 349.959445ms)","trace[1433296169] 'applied index is now lower than readState.Index' (duration: 17.557673ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:07.612589Z","caller":"traceutil/trace.go:171","msg":"trace[709265285] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"533.560459ms","start":"2026-08-04T15:21:07.079016Z","end":"2026-08-04T15:21:07.612576Z","steps":["trace[709265285] 'process raft request' (duration: 533.27312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:07.612705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:07.078994Z","time spent":"533.629582ms","remote":"127.0.0.1:60378","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-08-04T15:21:07.612974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.059656ms","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-08-04T15:21:07.61334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.362368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:07.613396Z","caller":"traceutil/trace.go:171","msg":"trace[605465803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"345.41936ms","start":"2026-08-04T15:21:07.267967Z","end":"2026-08-04T15:21:07.613386Z","steps":["trace[605465803] 'agreement among raft nodes before linearized reading' (duration: 345.340778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:07.613419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:07.267927Z","time spent":"345.487332ms","remote":"127.0.0.1:60092","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-08-04T15:21:07.61303Z","caller":"traceutil/trace.go:171","msg":"trace[815264834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"368.163109ms","start":"2026-08-04T15:21:07.244853Z","end":"2026-08-04T15:21:07.613017Z","steps":["trace[815264834] 'agreement among raft nodes before linearized reading' (duration: 367.887241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:07.613901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:07.24483Z","time spent":"369.028896ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T15:21:08.92455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.016842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:08.924637Z","caller":"traceutil/trace.go:171","msg":"trace[1625598958] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"779.636765ms","start":"2026-08-04T15:21:08.144988Z","end":"2026-08-04T15:21:08.924625Z","steps":["trace[1625598958] 'read index received' (duration: 60.285852ms)","trace[1625598958] 'applied index is now lower than readState.Index' (duration: 719.349873ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:08.924657Z","caller":"traceutil/trace.go:171","msg":"trace[1858377157] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.306964844s","start":"2026-08-04T15:21:07.617672Z","end":"2026-08-04T15:21:08.924637Z","steps":["trace[1858377157] 'process raft request' (duration: 587.802588ms)","trace[1858377157] 'compare' (duration: 718.868507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:08.924763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:07.61763Z","time spent":"1.307080119s","remote":"127.0.0.1:60378","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-08-04T15:21:08.924989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.013346ms","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-08-04T15:21:08.92502Z","caller":"traceutil/trace.go:171","msg":"trace[1005090701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"780.081679ms","start":"2026-08-04T15:21:08.144926Z","end":"2026-08-04T15:21:08.925007Z","steps":["trace[1005090701] 'agreement among raft nodes before linearized reading' (duration: 779.994926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:08.925037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:08.144878Z","time spent":"780.154761ms","remote":"127.0.0.1:60378","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-08-04T15:21:09.426002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953510994052903,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:21:09.522038Z","caller":"traceutil/trace.go:171","msg":"trace[1599571071] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"721.211213ms","start":"2026-08-04T15:21:08.800796Z","end":"2026-08-04T15:21:09.522007Z","steps":["trace[1599571071] 'process raft request' (duration: 671.594853ms)","trace[1599571071] 'compare' (duration: 49.244188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:09.522198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:08.800778Z","time spent":"721.342258ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:21:09.559686Z","caller":"traceutil/trace.go:171","msg":"trace[182011945] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"630.147525ms","start":"2026-08-04T15:21:08.929518Z","end":"2026-08-04T15:21:09.559665Z","steps":["trace[182011945] 'process raft request' (duration: 629.99875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:09.559801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:08.929486Z","time spent":"630.251638ms","remote":"127.0.0.1:60378","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-08-04T15:21:09.560001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.01948ms","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-08-04T15:21:09.560122Z","caller":"traceutil/trace.go:171","msg":"trace[458549805] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"634.96674ms","start":"2026-08-04T15:21:08.924655Z","end":"2026-08-04T15:21:09.559622Z","steps":["trace[458549805] 'read index received' (duration: 547.757995ms)","trace[458549805] 'applied index is now lower than readState.Index' (duration: 87.207794ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:09.56011Z","caller":"traceutil/trace.go:171","msg":"trace[1897245248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"971.165704ms","start":"2026-08-04T15:21:08.588926Z","end":"2026-08-04T15:21:09.560091Z","steps":["trace[1897245248] 'agreement among raft nodes before linearized reading' (duration: 970.876294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:09.560224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:08.588904Z","time spent":"971.284098ms","remote":"127.0.0.1:60378","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-08-04T15:21:09.5603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.710841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:09.560372Z","caller":"traceutil/trace.go:171","msg":"trace[724544254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"292.831305ms","start":"2026-08-04T15:21:09.267525Z","end":"2026-08-04T15:21:09.560356Z","steps":["trace[724544254] 'agreement among raft nodes before linearized reading' (duration: 292.702351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:09.560412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.682285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-04T15:21:09.560482Z","caller":"traceutil/trace.go:171","msg":"trace[263693471] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:1288; }","duration":"182.766828ms","start":"2026-08-04T15:21:09.377699Z","end":"2026-08-04T15:21:09.560466Z","steps":["trace[263693471] 'agreement among raft nodes before linearized reading' (duration: 182.551241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:09.840199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.356214ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-04T15:21:09.840397Z","caller":"traceutil/trace.go:171","msg":"trace[777857378] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"241.026955ms","start":"2026-08-04T15:21:09.599354Z","end":"2026-08-04T15:21:09.840381Z","steps":["trace[777857378] 'read index received' (duration: 58.545416ms)","trace[777857378] 'applied index is now lower than readState.Index' (duration: 182.480589ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:09.840599Z","caller":"traceutil/trace.go:171","msg":"trace[1802659356] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"275.568482ms","start":"2026-08-04T15:21:09.564985Z","end":"2026-08-04T15:21:09.840553Z","steps":["trace[1802659356] 'process raft request' (duration: 275.296943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:09.840718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.821874ms","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-08-04T15:21:09.840765Z","caller":"traceutil/trace.go:171","msg":"trace[2141637368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"223.919337ms","start":"2026-08-04T15:21:09.616833Z","end":"2026-08-04T15:21:09.840752Z","steps":["trace[2141637368] 'agreement among raft nodes before linearized reading' (duration: 223.732881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:09.840731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.342374ms","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-08-04T15:21:09.840821Z","caller":"traceutil/trace.go:171","msg":"trace[1084423655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"241.498569ms","start":"2026-08-04T15:21:09.599305Z","end":"2026-08-04T15:21:09.840804Z","steps":["trace[1084423655] 'agreement among raft nodes before linearized reading' (duration: 241.20672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:10.089724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.965721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:10.090016Z","caller":"traceutil/trace.go:171","msg":"trace[1160321211] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"244.767574ms","start":"2026-08-04T15:21:09.845233Z","end":"2026-08-04T15:21:10.090001Z","steps":["trace[1160321211] 'process raft request' (duration: 244.730113ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:10.090197Z","caller":"traceutil/trace.go:171","msg":"trace[2018745111] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"248.67484ms","start":"2026-08-04T15:21:09.841494Z","end":"2026-08-04T15:21:10.090169Z","steps":["trace[2018745111] 'process raft request' (duration: 140.181493ms)","trace[2018745111] 'compare' (duration: 107.723832ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:10.090314Z","caller":"traceutil/trace.go:171","msg":"trace[1943662139] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"245.160687ms","start":"2026-08-04T15:21:09.845104Z","end":"2026-08-04T15:21:10.090265Z","steps":["trace[1943662139] 'process raft request' (duration: 244.788525ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:11.314617Z","caller":"traceutil/trace.go:171","msg":"trace[187450626] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"380.905797ms","start":"2026-08-04T15:21:10.933685Z","end":"2026-08-04T15:21:11.314591Z","steps":["trace[187450626] 'process raft request' (duration: 380.655489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:11.314777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:10.933661Z","time spent":"381.025581ms","remote":"127.0.0.1:60378","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-08-04T15:21:11.695198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.554142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:11.695416Z","caller":"traceutil/trace.go:171","msg":"trace[367634820] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"429.747683ms","start":"2026-08-04T15:21:11.265629Z","end":"2026-08-04T15:21:11.695377Z","steps":["trace[367634820] 'read index received' (duration: 48.693771ms)","trace[367634820] 'applied index is now lower than readState.Index' (duration: 381.048902ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:11.695439Z","caller":"traceutil/trace.go:171","msg":"trace[729021816] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"489.916161ms","start":"2026-08-04T15:21:11.205493Z","end":"2026-08-04T15:21:11.695409Z","steps":["trace[729021816] 'process raft request' (duration: 243.08047ms)","trace[729021816] 'compare' (duration: 246.251872ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:11.695509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.886217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:11.695544Z","caller":"traceutil/trace.go:171","msg":"trace[392314477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"429.957069ms","start":"2026-08-04T15:21:11.265577Z","end":"2026-08-04T15:21:11.695534Z","steps":["trace[392314477] 'agreement among raft nodes before linearized reading' (duration: 429.892787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:11.695574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:11.265557Z","time spent":"430.009291ms","remote":"127.0.0.1:60092","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-08-04T15:21:11.69557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:11.205476Z","time spent":"490.023615ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:21:11.705944Z","caller":"traceutil/trace.go:171","msg":"trace[764887165] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"178.401557ms","start":"2026-08-04T15:21:11.527525Z","end":"2026-08-04T15:21:11.705927Z","steps":["trace[764887165] 'process raft request' (duration: 178.282293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:11.706139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.169376ms","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-08-04T15:21:11.706233Z","caller":"traceutil/trace.go:171","msg":"trace[1491611153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"142.30444ms","start":"2026-08-04T15:21:11.563915Z","end":"2026-08-04T15:21:11.70622Z","steps":["trace[1491611153] 'agreement among raft nodes before linearized reading' (duration: 142.022341ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:14.424416Z","caller":"traceutil/trace.go:171","msg":"trace[392379946] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"315.236613ms","start":"2026-08-04T15:21:14.109134Z","end":"2026-08-04T15:21:14.42437Z","steps":["trace[392379946] 'process raft request' (duration: 314.997805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:14.424667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:14.109109Z","time spent":"315.425498ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:21:14.619966Z","caller":"traceutil/trace.go:171","msg":"trace[798293653] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"506.593135ms","start":"2026-08-04T15:21:14.113351Z","end":"2026-08-04T15:21:14.619944Z","steps":["trace[798293653] 'process raft request' (duration: 491.598905ms)","trace[798293653] 'compare' (duration: 14.718181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:14.62164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:14.11334Z","time spent":"508.182577ms","remote":"127.0.0.1:60378","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-08-04T15:21:21.6862Z","caller":"traceutil/trace.go:171","msg":"trace[875900422] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"316.740318ms","start":"2026-08-04T15:21:21.369442Z","end":"2026-08-04T15:21:21.686182Z","steps":["trace[875900422] 'process raft request' (duration: 316.47703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:21.686292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:21.369414Z","time spent":"316.829771ms","remote":"127.0.0.1:60378","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-08-04T15:21:28.809519Z","caller":"traceutil/trace.go:171","msg":"trace[770379896] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"124.537506ms","start":"2026-08-04T15:21:28.684947Z","end":"2026-08-04T15:21:28.809485Z","steps":["trace[770379896] 'process raft request' (duration: 124.36676ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:34.790176Z","caller":"traceutil/trace.go:171","msg":"trace[787462797] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"103.881072ms","start":"2026-08-04T15:21:34.686264Z","end":"2026-08-04T15:21:34.790145Z","steps":["trace[787462797] 'process raft request' (duration: 57.416887ms)","trace[787462797] 'compare' (duration: 46.083712ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:22:00.04046Z","caller":"traceutil/trace.go:171","msg":"trace[985499682] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"155.368678ms","start":"2026-08-04T15:21:59.885063Z","end":"2026-08-04T15:22:00.040432Z","steps":["trace[985499682] 'process raft request' (duration: 155.225143ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:01.336085Z","caller":"traceutil/trace.go:171","msg":"trace[1167419969] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"132.104379ms","start":"2026-08-04T15:22:01.203953Z","end":"2026-08-04T15:22:01.336058Z","steps":["trace[1167419969] 'process raft request' (duration: 131.957374ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:44.534689Z","caller":"traceutil/trace.go:171","msg":"trace[1159587251] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"188.80708ms","start":"2026-08-04T15:22:44.345859Z","end":"2026-08-04T15:22:44.534667Z","steps":["trace[1159587251] 'process raft request' (duration: 188.637505ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:44.655363Z","caller":"traceutil/trace.go:171","msg":"trace[1514385765] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"307.109904ms","start":"2026-08-04T15:22:44.348221Z","end":"2026-08-04T15:22:44.655331Z","steps":["trace[1514385765] 'process raft request' (duration: 236.513864ms)","trace[1514385765] 'compare' (duration: 70.379133ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:22:44.65555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:44.348204Z","time spent":"307.241249ms","remote":"127.0.0.1:60378","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-08-04T15:22:44.854132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.290657ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-04T15:23:37.676267Z","caller":"traceutil/trace.go:171","msg":"trace[1642862427] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"121.179764ms","start":"2026-08-04T15:23:37.555058Z","end":"2026-08-04T15:23:37.676238Z","steps":["trace[1642862427] 'process raft request' (duration: 120.977178ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:56.961652Z","caller":"traceutil/trace.go:171","msg":"trace[147229764] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2164; }","duration":"145.438782ms","start":"2026-08-04T15:23:56.816187Z","end":"2026-08-04T15:23:56.961626Z","steps":["trace[147229764] 'read index received' (duration: 145.229385ms)","trace[147229764] 'applied index is now lower than readState.Index' (duration: 207.957µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:23:56.96176Z","caller":"traceutil/trace.go:171","msg":"trace[2142678160] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"253.365761ms","start":"2026-08-04T15:23:56.70836Z","end":"2026-08-04T15:23:56.961726Z","steps":["trace[2142678160] 'process raft request' (duration: 253.122203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:56.962006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.807183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T15:23:56.962097Z","caller":"traceutil/trace.go:171","msg":"trace[728338634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2077; }","duration":"145.948658ms","start":"2026-08-04T15:23:56.816133Z","end":"2026-08-04T15:23:56.962082Z","steps":["trace[728338634] 'agreement among raft nodes before linearized reading' (duration: 145.596367ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:57.237136Z","caller":"traceutil/trace.go:171","msg":"trace[1485901865] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"160.998039ms","start":"2026-08-04T15:23:57.076105Z","end":"2026-08-04T15:23:57.237103Z","steps":["trace[1485901865] 'process raft request' (duration: 117.154615ms)","trace[1485901865] 'compare' (duration: 43.733891ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:13.259523Z","caller":"traceutil/trace.go:171","msg":"trace[1160593928] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"132.27337ms","start":"2026-08-04T15:24:13.127216Z","end":"2026-08-04T15:24:13.259489Z","steps":["trace[1160593928] 'process raft request' (duration: 54.573129ms)","trace[1160593928] 'compare' (duration: 77.517105ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:25:24.573146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.485342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-04T15:25:24.573233Z","caller":"traceutil/trace.go:171","msg":"trace[1609045831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2499; }","duration":"100.627637ms","start":"2026-08-04T15:25:24.472587Z","end":"2026-08-04T15:25:24.573214Z","steps":["trace[1609045831] 'range keys from in-memory index tree' (duration: 100.288556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:37.609918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.736002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:25:37.610147Z","caller":"traceutil/trace.go:171","msg":"trace[1096398920] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"272.20031ms","start":"2026-08-04T15:25:37.337896Z","end":"2026-08-04T15:25:37.610096Z","steps":["trace[1096398920] 'process raft request' (duration: 45.22642ms)","trace[1096398920] 'compare' (duration: 226.619808ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:37.659799Z","caller":"traceutil/trace.go:171","msg":"trace[586682544] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"185.094696ms","start":"2026-08-04T15:25:37.474682Z","end":"2026-08-04T15:25:37.659777Z","steps":["trace[586682544] 'process raft request' (duration: 184.961201ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:37.778Z","caller":"traceutil/trace.go:171","msg":"trace[1395998743] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"290.288049ms","start":"2026-08-04T15:25:37.487683Z","end":"2026-08-04T15:25:37.777971Z","steps":["trace[1395998743] 'process raft request' (duration: 223.389364ms)","trace[1395998743] 'compare' (duration: 66.74749ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:37.951423Z","caller":"traceutil/trace.go:171","msg":"trace[2070968705] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"168.670489ms","start":"2026-08-04T15:25:37.78272Z","end":"2026-08-04T15:25:37.951391Z","steps":["trace[2070968705] 'process raft request' (duration: 101.897428ms)","trace[2070968705] 'compare' (duration: 66.475891ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:37.951622Z","caller":"traceutil/trace.go:171","msg":"trace[1374986592] linearizableReadLoop","detail":"{readStateIndex:2676; appliedIndex:2674; }","duration":"112.607581ms","start":"2026-08-04T15:25:37.839005Z","end":"2026-08-04T15:25:37.951612Z","steps":["trace[1374986592] 'read index received' (duration: 45.623573ms)","trace[1374986592] 'applied index is now lower than readState.Index' (duration: 66.982878ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:37.951789Z","caller":"traceutil/trace.go:171","msg":"trace[706503184] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"169.03352ms","start":"2026-08-04T15:25:37.78274Z","end":"2026-08-04T15:25:37.951773Z","steps":["trace[706503184] 'process raft request' (duration: 168.589416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:37.952049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.026514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-04T15:25:37.952092Z","caller":"traceutil/trace.go:171","msg":"trace[1435639623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2567; }","duration":"113.117847ms","start":"2026-08-04T15:25:37.838964Z","end":"2026-08-04T15:25:37.952081Z","steps":["trace[1435639623] 'agreement among raft nodes before linearized reading' (duration: 112.922371ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:38.136426Z","caller":"traceutil/trace.go:171","msg":"trace[1663024060] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"178.547875ms","start":"2026-08-04T15:25:37.957841Z","end":"2026-08-04T15:25:38.136389Z","steps":["trace[1663024060] 'process raft request' (duration: 120.70068ms)","trace[1663024060] 'compare' (duration: 57.665009ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:38.36536Z","caller":"traceutil/trace.go:171","msg":"trace[261098612] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"124.801652ms","start":"2026-08-04T15:25:38.240526Z","end":"2026-08-04T15:25:38.365328Z","steps":["trace[261098612] 'process raft request' (duration: 77.910658ms)","trace[261098612] 'compare' (duration: 46.597115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:25:38.990779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953510994056033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:25:39.491445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953510994056033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:25:39.893239Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.467509248s","expected-duration":"1s"} {"level":"info","ts":"2026-08-04T15:25:39.893564Z","caller":"traceutil/trace.go:171","msg":"trace[41732413] linearizableReadLoop","detail":"{readStateIndex:2681; appliedIndex:2680; }","duration":"1.403112253s","start":"2026-08-04T15:25:38.490431Z","end":"2026-08-04T15:25:39.893544Z","steps":["trace[41732413] 'read index received' (duration: 1.4030414s)","trace[41732413] 'applied index is now lower than readState.Index' (duration: 69.803µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:39.893678Z","caller":"traceutil/trace.go:171","msg":"trace[532237104] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"1.468008674s","start":"2026-08-04T15:25:38.425617Z","end":"2026-08-04T15:25:39.893626Z","steps":["trace[532237104] 'process raft request' (duration: 1.467807958s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:39.893738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403296379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-04T15:25:39.893779Z","caller":"traceutil/trace.go:171","msg":"trace[1185459104] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2572; }","duration":"1.403351091s","start":"2026-08-04T15:25:38.490417Z","end":"2026-08-04T15:25:39.893768Z","steps":["trace[1185459104] 'agreement among raft nodes before linearized reading' (duration: 1.403212046s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:39.893817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:38.490371Z","time spent":"1.403433253s","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-04T15:25:39.893855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:38.425595Z","time spent":"1.468169349s","remote":"127.0.0.1:60378","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-08-04T15:25:40.271152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.803309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:25:40.271338Z","caller":"traceutil/trace.go:171","msg":"trace[27444185] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"488.586548ms","start":"2026-08-04T15:25:39.782719Z","end":"2026-08-04T15:25:40.271305Z","steps":["trace[27444185] 'process raft request' (duration: 224.564161ms)","trace[27444185] 'compare' (duration: 263.693056ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:40.271389Z","caller":"traceutil/trace.go:171","msg":"trace[437293005] linearizableReadLoop","detail":"{readStateIndex:2682; appliedIndex:2681; }","duration":"377.764544ms","start":"2026-08-04T15:25:39.89361Z","end":"2026-08-04T15:25:40.271375Z","steps":["trace[437293005] 'read index received' (duration: 113.744077ms)","trace[437293005] 'applied index is now lower than readState.Index' (duration: 264.019597ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:25:40.271522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:39.782693Z","time spent":"488.727032ms","remote":"127.0.0.1:60378","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-08-04T15:25:40.271952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00541413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:25:40.271988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.319202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T15:25:40.272044Z","caller":"traceutil/trace.go:171","msg":"trace[698523552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2574; }","duration":"315.423175ms","start":"2026-08-04T15:25:39.956609Z","end":"2026-08-04T15:25:40.272032Z","steps":["trace[698523552] 'agreement among raft nodes before linearized reading' (duration: 315.26118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.272072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:39.956587Z","time spent":"315.479987ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T15:25:40.272066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573068293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-04T15:25:40.272122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.863649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.60\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-04T15:25:40.272156Z","caller":"traceutil/trace.go:171","msg":"trace[1744293152] range","detail":"{range_begin:/registry/masterleases/199.19.213.60; range_end:; response_count:1; response_revision:2574; }","duration":"881.938011ms","start":"2026-08-04T15:25:39.390209Z","end":"2026-08-04T15:25:40.272147Z","steps":["trace[1744293152] 'agreement among raft nodes before linearized reading' (duration: 881.231508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.272186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:39.390185Z","time spent":"881.995413ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.60\" "} {"level":"warn","ts":"2026-08-04T15:25:40.272281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.255396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-04T15:25:40.2723Z","caller":"traceutil/trace.go:171","msg":"trace[1686015182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2574; }","duration":"226.295317ms","start":"2026-08-04T15:25:40.045998Z","end":"2026-08-04T15:25:40.272293Z","steps":["trace[1686015182] 'agreement among raft nodes before linearized reading' (duration: 226.255696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.272463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713222587s","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-08-04T15:25:40.2725Z","caller":"traceutil/trace.go:171","msg":"trace[1313897476] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2574; }","duration":"1.71328603s","start":"2026-08-04T15:25:38.559201Z","end":"2026-08-04T15:25:40.272487Z","steps":["trace[1313897476] 'agreement among raft nodes before linearized reading' (duration: 1.712403821s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.272529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:38.559187Z","time spent":"1.713333772s","remote":"127.0.0.1:60336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:25:40.272002Z","caller":"traceutil/trace.go:171","msg":"trace[2119808205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2574; }","duration":"1.005487532s","start":"2026-08-04T15:25:39.266499Z","end":"2026-08-04T15:25:40.271987Z","steps":["trace[2119808205] 'agreement among raft nodes before linearized reading' (duration: 1.004919514s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.272633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:39.266483Z","time spent":"1.006137654s","remote":"127.0.0.1:60092","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-08-04T15:25:40.271958Z","caller":"traceutil/trace.go:171","msg":"trace[1530005562] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"373.558159ms","start":"2026-08-04T15:25:39.898388Z","end":"2026-08-04T15:25:40.271946Z","steps":["trace[1530005562] 'process raft request' (duration: 372.923109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.273448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:39.898364Z","time spent":"375.035317ms","remote":"127.0.0.1:60378","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-08-04T15:25:40.272766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590529883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-04T15:25:40.273555Z","caller":"traceutil/trace.go:171","msg":"trace[391314097] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2574; }","duration":"1.591360059s","start":"2026-08-04T15:25:38.682185Z","end":"2026-08-04T15:25:40.273545Z","steps":["trace[391314097] 'agreement among raft nodes before linearized reading' (duration: 1.589270262s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.273572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:38.682161Z","time spent":"1.591406031s","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-04T15:25:40.272928Z","caller":"traceutil/trace.go:171","msg":"trace[308626434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2574; }","duration":"1.573200598s","start":"2026-08-04T15:25:38.698944Z","end":"2026-08-04T15:25:40.272145Z","steps":["trace[308626434] 'agreement among raft nodes before linearized reading' (duration: 1.572502445s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.273656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:38.698933Z","time spent":"1.574717616s","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:25:40.273645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.81653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-04T15:25:40.273721Z","caller":"traceutil/trace.go:171","msg":"trace[1012746142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2574; }","duration":"316.927064ms","start":"2026-08-04T15:25:39.956778Z","end":"2026-08-04T15:25:40.273705Z","steps":["trace[1012746142] 'agreement among raft nodes before linearized reading' (duration: 315.57235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.273761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:39.956771Z","time spent":"316.981675ms","remote":"127.0.0.1:60378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-04T15:25:40.424378Z","caller":"traceutil/trace.go:171","msg":"trace[130567726] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"147.794399ms","start":"2026-08-04T15:25:40.276551Z","end":"2026-08-04T15:25:40.424345Z","steps":["trace[130567726] 'process raft request' (duration: 147.645084ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.424399Z","caller":"traceutil/trace.go:171","msg":"trace[237147692] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"148.669797ms","start":"2026-08-04T15:25:40.275688Z","end":"2026-08-04T15:25:40.424358Z","steps":["trace[237147692] 'process raft request' (duration: 129.469372ms)","trace[237147692] 'compare' (duration: 18.848724ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:40.424865Z","caller":"traceutil/trace.go:171","msg":"trace[1579164535] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"148.12568ms","start":"2026-08-04T15:25:40.276718Z","end":"2026-08-04T15:25:40.424844Z","steps":["trace[1579164535] 'process raft request' (duration: 147.585943ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.424952Z","caller":"traceutil/trace.go:171","msg":"trace[1784747778] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"146.898361ms","start":"2026-08-04T15:25:40.278034Z","end":"2026-08-04T15:25:40.424933Z","steps":["trace[1784747778] 'process raft request' (duration: 146.839889ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.425086Z","caller":"traceutil/trace.go:171","msg":"trace[792886816] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"148.15813ms","start":"2026-08-04T15:25:40.276918Z","end":"2026-08-04T15:25:40.425076Z","steps":["trace[792886816] 'process raft request' (duration: 147.747027ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.425171Z","caller":"traceutil/trace.go:171","msg":"trace[337135972] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"147.572982ms","start":"2026-08-04T15:25:40.27759Z","end":"2026-08-04T15:25:40.425163Z","steps":["trace[337135972] 'process raft request' (duration: 147.220921ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.520902Z","caller":"traceutil/trace.go:171","msg":"trace[1248999319] linearizableReadLoop","detail":"{readStateIndex:2691; appliedIndex:2684; }","duration":"164.002308ms","start":"2026-08-04T15:25:40.35688Z","end":"2026-08-04T15:25:40.520882Z","steps":["trace[1248999319] 'read index received' (duration: 48.34434ms)","trace[1248999319] 'applied index is now lower than readState.Index' (duration: 115.656758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:25:40.521029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.130222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:25:40.521021Z","caller":"traceutil/trace.go:171","msg":"trace[1093522793] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"171.155038ms","start":"2026-08-04T15:25:40.349756Z","end":"2026-08-04T15:25:40.520911Z","steps":["trace[1093522793] 'process raft request' (duration: 171.003953ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.521064Z","caller":"traceutil/trace.go:171","msg":"trace[666195232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2581; }","duration":"164.204194ms","start":"2026-08-04T15:25:40.356853Z","end":"2026-08-04T15:25:40.521057Z","steps":["trace[666195232] 'agreement among raft nodes before linearized reading' (duration: 164.098291ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:00.042704Z","caller":"traceutil/trace.go:171","msg":"trace[1333339201] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"112.842358ms","start":"2026-08-04T15:25:59.92983Z","end":"2026-08-04T15:26:00.042672Z","steps":["trace[1333339201] 'process raft request' (duration: 112.376243ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:20.660629Z","caller":"traceutil/trace.go:171","msg":"trace[762174546] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2883; }","duration":"130.592757ms","start":"2026-08-04T15:26:20.530011Z","end":"2026-08-04T15:26:20.660604Z","steps":["trace[762174546] 'read index received' (duration: 43.408121ms)","trace[762174546] 'applied index is now lower than readState.Index' (duration: 87.183736ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:20.660686Z","caller":"traceutil/trace.go:171","msg":"trace[273487833] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"146.188798ms","start":"2026-08-04T15:26:20.514464Z","end":"2026-08-04T15:26:20.660653Z","steps":["trace[273487833] 'process raft request' (duration: 146.017862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:20.661114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.080413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-vbhzd\" ","response":"range_response_count:1 size:13723"} {"level":"info","ts":"2026-08-04T15:26:20.661187Z","caller":"traceutil/trace.go:171","msg":"trace[102912159] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-vbhzd; range_end:; response_count:1; response_revision:2767; }","duration":"131.204737ms","start":"2026-08-04T15:26:20.52996Z","end":"2026-08-04T15:26:20.661164Z","steps":["trace[102912159] 'agreement among raft nodes before linearized reading' (duration: 130.737612ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:20.82779Z","caller":"traceutil/trace.go:171","msg":"trace[7923238] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2885; }","duration":"157.869912ms","start":"2026-08-04T15:26:20.669892Z","end":"2026-08-04T15:26:20.827762Z","steps":["trace[7923238] 'read index received' (duration: 152.574162ms)","trace[7923238] 'applied index is now lower than readState.Index' (duration: 5.29474ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:20.827922Z","caller":"traceutil/trace.go:171","msg":"trace[860187721] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"162.573644ms","start":"2026-08-04T15:26:20.665322Z","end":"2026-08-04T15:26:20.827896Z","steps":["trace[860187721] 'process raft request' (duration: 157.221712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:20.828075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.156911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-vbhzd\" ","response":"range_response_count:1 size:13723"} {"level":"warn","ts":"2026-08-04T15:26:20.828155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.702696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-04T15:26:20.828192Z","caller":"traceutil/trace.go:171","msg":"trace[990112961] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-vbhzd; range_end:; response_count:1; response_revision:2768; }","duration":"158.297866ms","start":"2026-08-04T15:26:20.66987Z","end":"2026-08-04T15:26:20.828168Z","steps":["trace[990112961] 'agreement among raft nodes before linearized reading' (duration: 158.028978ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:20.828218Z","caller":"traceutil/trace.go:171","msg":"trace[24191058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2768; }","duration":"147.889052ms","start":"2026-08-04T15:26:20.680308Z","end":"2026-08-04T15:26:20.828197Z","steps":["trace[24191058] 'agreement among raft nodes before linearized reading' (duration: 147.605223ms)"],"step_count":1}