{"level":"warn","ts":"2026-08-12T00:25:19.816311Z","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-12T00:25:19.816411Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.61:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.61:2380","--initial-cluster=instance=https://199.204.45.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.61: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-12T00:25:19.816593Z","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-12T00:25:19.816611Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-12T00:25:19.816634Z","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-12T00:25:19.817366Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"]} {"level":"info","ts":"2026-08-12T00:25:19.817474Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.61: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-12T00:25:19.822682Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.929499ms"} {"level":"info","ts":"2026-08-12T00:25:19.831725Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca256638ea38eb43","cluster-id":"dfd7ac400ae20102"} {"level":"info","ts":"2026-08-12T00:25:19.831826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T00:25:19.831858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 0"} {"level":"info","ts":"2026-08-12T00:25:19.831877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca256638ea38eb43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T00:25:19.831893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 1"} {"level":"info","ts":"2026-08-12T00:25:19.831957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"warn","ts":"2026-08-12T00:25:19.839623Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T00:25:19.841417Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T00:25:19.842711Z","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-12T00:25:19.844945Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca256638ea38eb43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T00:25:19.845339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca256638ea38eb43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T00:25:19.845471Z","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-12T00:25:19.845533Z","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-12T00:25:19.845544Z","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-12T00:25:19.845781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"info","ts":"2026-08-12T00:25:19.845859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","added-peer-id":"ca256638ea38eb43","added-peer-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-12T00:25:19.850365Z","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-12T00:25:19.850598Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-12T00:25:19.850625Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-12T00:25:19.851189Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca256638ea38eb43","initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T00:25:19.851239Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T00:25:20.432833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T00:25:20.432901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T00:25:20.432932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgPreVoteResp from ca256638ea38eb43 at term 1"} {"level":"info","ts":"2026-08-12T00:25:20.432949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became candidate at term 2"} {"level":"info","ts":"2026-08-12T00:25:20.432954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgVoteResp from ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-12T00:25:20.432971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became leader at term 2"} {"level":"info","ts":"2026-08-12T00:25:20.432982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca256638ea38eb43 elected leader ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-12T00:25:20.435811Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:20.436361Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca256638ea38eb43","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.61:2379]}","request-path":"/0/members/ca256638ea38eb43/attributes","cluster-id":"dfd7ac400ae20102","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T00:25:20.4364Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:25:20.43651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:25:20.43696Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T00:25:20.436981Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T00:25:20.437785Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:20.437909Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:20.437956Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:20.440633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T00:25:20.440824Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.61:2379"} {"level":"warn","ts":"2026-08-12T00:25:25.044999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114269353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:25:25.045135Z","caller":"traceutil/trace.go:171","msg":"trace[1846866977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:197; }","duration":"1.114494249s","start":"2026-08-12T00:25:23.930612Z","end":"2026-08-12T00:25:25.045106Z","steps":["trace[1846866977] 'range keys from in-memory index tree' (duration: 1.11415831s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:25.045206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:23.930588Z","time spent":"1.114607001s","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T00:25:25.31981Z","caller":"traceutil/trace.go:171","msg":"trace[1834968504] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"681.110728ms","start":"2026-08-12T00:25:24.638657Z","end":"2026-08-12T00:25:25.319768Z","steps":["trace[1834968504] 'process raft request' (duration: 680.784991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:25.321054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:24.638623Z","time spent":"681.298242ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:25:25.327432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:24.824407Z","time spent":"503.016435ms","remote":"127.0.0.1:56618","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-12T00:25:25.327496Z","caller":"traceutil/trace.go:171","msg":"trace[381301517] linearizableReadLoop","detail":"{readStateIndex:205; appliedIndex:202; }","duration":"274.047943ms","start":"2026-08-12T00:25:25.053416Z","end":"2026-08-12T00:25:25.327464Z","steps":["trace[381301517] 'read index received' (duration: 266.115047ms)","trace[381301517] 'applied index is now lower than readState.Index' (duration: 7.932096ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:25:25.327597Z","caller":"traceutil/trace.go:171","msg":"trace[171838542] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"220.122295ms","start":"2026-08-12T00:25:25.107445Z","end":"2026-08-12T00:25:25.327567Z","steps":["trace[171838542] 'process raft request' (duration: 219.964992ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:25.32759Z","caller":"traceutil/trace.go:171","msg":"trace[1066499461] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"276.300902ms","start":"2026-08-12T00:25:25.051228Z","end":"2026-08-12T00:25:25.327528Z","steps":["trace[1066499461] 'process raft request' (duration: 276.082538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:25.327745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.280508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:25:25.327779Z","caller":"traceutil/trace.go:171","msg":"trace[1430306116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:200; }","duration":"274.413621ms","start":"2026-08-12T00:25:25.053354Z","end":"2026-08-12T00:25:25.327768Z","steps":["trace[1430306116] 'agreement among raft nodes before linearized reading' (duration: 274.260847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:25.327871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.128403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:25:25.328048Z","caller":"traceutil/trace.go:171","msg":"trace[1885339435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:200; }","duration":"146.359408ms","start":"2026-08-12T00:25:25.181659Z","end":"2026-08-12T00:25:25.328018Z","steps":["trace[1885339435] 'agreement among raft nodes before linearized reading' (duration: 146.071652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:38.50362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.324705ms","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-12T00:25:38.50378Z","caller":"traceutil/trace.go:171","msg":"trace[639458102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"204.607021ms","start":"2026-08-12T00:25:38.299148Z","end":"2026-08-12T00:25:38.503755Z","steps":["trace[639458102] 'range keys from in-memory index tree' (duration: 204.192563ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.525039Z","caller":"traceutil/trace.go:171","msg":"trace[1488721110] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"193.471886ms","start":"2026-08-12T00:25:59.33151Z","end":"2026-08-12T00:25:59.524982Z","steps":["trace[1488721110] 'process raft request' (duration: 193.25171ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.528017Z","caller":"traceutil/trace.go:171","msg":"trace[617953681] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"196.43695ms","start":"2026-08-12T00:25:59.331568Z","end":"2026-08-12T00:25:59.528005Z","steps":["trace[617953681] 'process raft request' (duration: 195.490358ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.528028Z","caller":"traceutil/trace.go:171","msg":"trace[462318592] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"184.099217ms","start":"2026-08-12T00:25:59.343886Z","end":"2026-08-12T00:25:59.527985Z","steps":["trace[462318592] 'process raft request' (duration: 183.454452ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.528154Z","caller":"traceutil/trace.go:171","msg":"trace[1765419234] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"196.513422ms","start":"2026-08-12T00:25:59.33163Z","end":"2026-08-12T00:25:59.528143Z","steps":["trace[1765419234] 'process raft request' (duration: 195.58189ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.529847Z","caller":"traceutil/trace.go:171","msg":"trace[149725546] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"182.47552ms","start":"2026-08-12T00:25:59.347329Z","end":"2026-08-12T00:25:59.529804Z","steps":["trace[149725546] 'process raft request' (duration: 180.058826ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.529951Z","caller":"traceutil/trace.go:171","msg":"trace[1140180159] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"177.048239ms","start":"2026-08-12T00:25:59.352545Z","end":"2026-08-12T00:25:59.529593Z","steps":["trace[1140180159] 'process raft request' (duration: 174.953863ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.52995Z","caller":"traceutil/trace.go:171","msg":"trace[2044092323] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"175.765251ms","start":"2026-08-12T00:25:59.352407Z","end":"2026-08-12T00:25:59.528172Z","steps":["trace[2044092323] 'process raft request' (duration: 175.027274ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.53028Z","caller":"traceutil/trace.go:171","msg":"trace[1564187773] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"175.044725ms","start":"2026-08-12T00:25:59.355041Z","end":"2026-08-12T00:25:59.530086Z","steps":["trace[1564187773] 'process raft request' (duration: 172.55554ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.530296Z","caller":"traceutil/trace.go:171","msg":"trace[866376157] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"177.487219ms","start":"2026-08-12T00:25:59.352579Z","end":"2026-08-12T00:25:59.530066Z","steps":["trace[866376157] 'process raft request' (duration: 174.972333ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.530745Z","caller":"traceutil/trace.go:171","msg":"trace[2054483396] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"170.547746ms","start":"2026-08-12T00:25:59.360175Z","end":"2026-08-12T00:25:59.530722Z","steps":["trace[2054483396] 'process raft request' (duration: 167.936098ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:59.534708Z","caller":"traceutil/trace.go:171","msg":"trace[1563100341] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"158.53064ms","start":"2026-08-12T00:25:59.376158Z","end":"2026-08-12T00:25:59.534689Z","steps":["trace[1563100341] 'process raft request' (duration: 154.151012ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:03.973054Z","caller":"traceutil/trace.go:171","msg":"trace[2059936258] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"364.688416ms","start":"2026-08-12T00:26:03.608319Z","end":"2026-08-12T00:26:03.973008Z","steps":["trace[2059936258] 'read index received' (duration: 364.43445ms)","trace[2059936258] 'applied index is now lower than readState.Index' (duration: 252.646µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:03.973211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.8497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-12T00:26:03.973242Z","caller":"traceutil/trace.go:171","msg":"trace[1554944042] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:613; }","duration":"364.933341ms","start":"2026-08-12T00:26:03.608299Z","end":"2026-08-12T00:26:03.973233Z","steps":["trace[1554944042] 'agreement among raft nodes before linearized reading' (duration: 364.835409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:03.973297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:03.608155Z","time spent":"365.134977ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.61\" "} {"level":"info","ts":"2026-08-12T00:26:03.973393Z","caller":"traceutil/trace.go:171","msg":"trace[936427210] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"364.90811ms","start":"2026-08-12T00:26:03.6082Z","end":"2026-08-12T00:26:03.973108Z","steps":["trace[936427210] 'process raft request' (duration: 364.536702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:03.973901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:03.608183Z","time spent":"365.399494ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:03.973321Z","caller":"traceutil/trace.go:171","msg":"trace[606327598] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"362.203507ms","start":"2026-08-12T00:26:03.611079Z","end":"2026-08-12T00:26:03.973282Z","steps":["trace[606327598] 'process raft request' (duration: 362.007732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:03.974299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:03.61107Z","time spent":"363.199511ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:06.00993Z","caller":"traceutil/trace.go:171","msg":"trace[1347817629] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"431.902242ms","start":"2026-08-12T00:26:05.577984Z","end":"2026-08-12T00:26:06.009886Z","steps":["trace[1347817629] 'process raft request' (duration: 430.946279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:06.01032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:05.577966Z","time spent":"432.231771ms","remote":"127.0.0.1:56634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:26:06.336848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.510562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:26:06.337089Z","caller":"traceutil/trace.go:171","msg":"trace[1598203207] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"256.876228ms","start":"2026-08-12T00:26:06.080194Z","end":"2026-08-12T00:26:06.33707Z","steps":["trace[1598203207] 'read index received' (duration: 175.731289ms)","trace[1598203207] 'applied index is now lower than readState.Index' (duration: 81.143699ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:06.337171Z","caller":"traceutil/trace.go:171","msg":"trace[1588956822] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"322.085667ms","start":"2026-08-12T00:26:06.015021Z","end":"2026-08-12T00:26:06.337106Z","steps":["trace[1588956822] 'process raft request' (duration: 321.943944ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:06.337366Z","caller":"traceutil/trace.go:171","msg":"trace[154922510] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"261.169122ms","start":"2026-08-12T00:26:06.076182Z","end":"2026-08-12T00:26:06.337351Z","steps":["trace[154922510] 'process raft request' (duration: 260.844374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:06.337407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:06.014973Z","time spent":"322.297872ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:06.337473Z","caller":"traceutil/trace.go:171","msg":"trace[452367261] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"322.688132ms","start":"2026-08-12T00:26:06.01475Z","end":"2026-08-12T00:26:06.337439Z","steps":["trace[452367261] 'process raft request' (duration: 57.854113ms)","trace[452367261] 'compare' (duration: 263.851486ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:06.3376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:06.014731Z","time spent":"322.806254ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:26:06.337781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.086499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T00:26:06.337894Z","caller":"traceutil/trace.go:171","msg":"trace[1529738535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"107.287175ms","start":"2026-08-12T00:26:06.23058Z","end":"2026-08-12T00:26:06.337867Z","steps":["trace[1529738535] 'agreement among raft nodes before linearized reading' (duration: 106.893975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:06.337899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.73372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5nm8q\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-12T00:26:06.33795Z","caller":"traceutil/trace.go:171","msg":"trace[2017013442] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5nm8q; range_end:; response_count:1; response_revision:628; }","duration":"257.880095ms","start":"2026-08-12T00:26:06.080057Z","end":"2026-08-12T00:26:06.337937Z","steps":["trace[2017013442] 'agreement among raft nodes before linearized reading' (duration: 257.778932ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:07.111493Z","caller":"traceutil/trace.go:171","msg":"trace[1759111695] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"758.377954ms","start":"2026-08-12T00:26:06.353092Z","end":"2026-08-12T00:26:07.11147Z","steps":["trace[1759111695] 'process raft request' (duration: 758.177369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:07.111645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:06.353056Z","time spent":"758.507757ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:26:07.11236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.357461ms","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-12T00:26:07.11243Z","caller":"traceutil/trace.go:171","msg":"trace[751670650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"347.441643ms","start":"2026-08-12T00:26:06.764973Z","end":"2026-08-12T00:26:07.112415Z","steps":["trace[751670650] 'agreement among raft nodes before linearized reading' (duration: 347.201478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:07.112464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:06.764886Z","time spent":"347.571496ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-12T00:26:07.112707Z","caller":"traceutil/trace.go:171","msg":"trace[1874963065] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:656; }","duration":"347.182476ms","start":"2026-08-12T00:26:06.764982Z","end":"2026-08-12T00:26:07.112165Z","steps":["trace[1874963065] 'read index received' (duration: 347.126465ms)","trace[1874963065] 'applied index is now lower than readState.Index' (duration: 53.371µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:07.613843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569289921846650,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T00:26:07.621968Z","caller":"traceutil/trace.go:171","msg":"trace[2056242967] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"809.864394ms","start":"2026-08-12T00:26:06.812071Z","end":"2026-08-12T00:26:07.621936Z","steps":["trace[2056242967] 'process raft request' (duration: 809.26194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:07.622132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:06.812006Z","time spent":"810.032469ms","remote":"127.0.0.1:56634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:07.637122Z","caller":"traceutil/trace.go:171","msg":"trace[1435143729] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"524.365881ms","start":"2026-08-12T00:26:07.112732Z","end":"2026-08-12T00:26:07.637098Z","steps":["trace[1435143729] 'read index received' (duration: 508.515313ms)","trace[1435143729] 'applied index is now lower than readState.Index' (duration: 15.849748ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:07.637207Z","caller":"traceutil/trace.go:171","msg":"trace[1595506195] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"518.669166ms","start":"2026-08-12T00:26:07.118516Z","end":"2026-08-12T00:26:07.637185Z","steps":["trace[1595506195] 'process raft request' (duration: 518.486641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:07.637328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.450876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5nm8q\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-08-12T00:26:07.637342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:07.118491Z","time spent":"518.778719ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:07.637356Z","caller":"traceutil/trace.go:171","msg":"trace[1404501811] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5nm8q; range_end:; response_count:1; response_revision:632; }","duration":"517.516437ms","start":"2026-08-12T00:26:07.119831Z","end":"2026-08-12T00:26:07.637348Z","steps":["trace[1404501811] 'agreement among raft nodes before linearized reading' (duration: 517.384914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:07.63733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.802573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T00:26:07.637385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:07.119817Z","time spent":"517.562739ms","remote":"127.0.0.1:56738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17169,"request content":"key:\"/registry/pods/kube-system/cilium-5nm8q\" "} {"level":"info","ts":"2026-08-12T00:26:07.637419Z","caller":"traceutil/trace.go:171","msg":"trace[1978971274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"650.915996ms","start":"2026-08-12T00:26:06.986481Z","end":"2026-08-12T00:26:07.637397Z","steps":["trace[1978971274] 'agreement among raft nodes before linearized reading' (duration: 650.729931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:07.637464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:06.986435Z","time spent":"651.023308ms","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T00:26:09.478666Z","caller":"traceutil/trace.go:171","msg":"trace[1101654942] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"202.622433ms","start":"2026-08-12T00:26:09.27599Z","end":"2026-08-12T00:26:09.478612Z","steps":["trace[1101654942] 'process raft request' (duration: 201.761682ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:20.564433Z","caller":"traceutil/trace.go:171","msg":"trace[1765577938] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"119.421514ms","start":"2026-08-12T00:26:20.444965Z","end":"2026-08-12T00:26:20.564386Z","steps":["trace[1765577938] 'process raft request' (duration: 119.24121ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:20.583232Z","caller":"traceutil/trace.go:171","msg":"trace[955073629] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"122.783254ms","start":"2026-08-12T00:26:20.460418Z","end":"2026-08-12T00:26:20.583202Z","steps":["trace[955073629] 'read index received' (duration: 103.965894ms)","trace[955073629] 'applied index is now lower than readState.Index' (duration: 18.81606ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:20.583354Z","caller":"traceutil/trace.go:171","msg":"trace[1740994732] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"136.574154ms","start":"2026-08-12T00:26:20.446754Z","end":"2026-08-12T00:26:20.583328Z","steps":["trace[1740994732] 'process raft request' (duration: 135.657942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:20.583527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.093122ms","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-12T00:26:20.583572Z","caller":"traceutil/trace.go:171","msg":"trace[1643782740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"123.218455ms","start":"2026-08-12T00:26:20.460339Z","end":"2026-08-12T00:26:20.583558Z","steps":["trace[1643782740] 'agreement among raft nodes before linearized reading' (duration: 122.967849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:23.350365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.743542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:23.350463Z","caller":"traceutil/trace.go:171","msg":"trace[1825035812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"363.873535ms","start":"2026-08-12T00:26:22.986566Z","end":"2026-08-12T00:26:23.35044Z","steps":["trace[1825035812] 'range keys from in-memory index tree' (duration: 363.6235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:23.35051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:22.986538Z","time spent":"363.961817ms","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:26:23.350775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.645077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:23.350852Z","caller":"traceutil/trace.go:171","msg":"trace[1705131494] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:803; }","duration":"416.782141ms","start":"2026-08-12T00:26:22.934053Z","end":"2026-08-12T00:26:23.350835Z","steps":["trace[1705131494] 'count revisions from in-memory index tree' (duration: 416.551445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:23.350917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:22.934025Z","time spent":"416.869803ms","remote":"127.0.0.1:56882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-12T00:26:23.351158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.187102ms","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-12T00:26:23.351306Z","caller":"traceutil/trace.go:171","msg":"trace[124955490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"271.457228ms","start":"2026-08-12T00:26:23.079826Z","end":"2026-08-12T00:26:23.351283Z","steps":["trace[124955490] 'range keys from in-memory index tree' (duration: 270.71486ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:23.506419Z","caller":"traceutil/trace.go:171","msg":"trace[2111506998] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"281.223381ms","start":"2026-08-12T00:26:23.225169Z","end":"2026-08-12T00:26:23.506393Z","steps":["trace[2111506998] 'process raft request' (duration: 281.045326ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:23.507411Z","caller":"traceutil/trace.go:171","msg":"trace[1611504471] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"155.69327ms","start":"2026-08-12T00:26:23.3517Z","end":"2026-08-12T00:26:23.507393Z","steps":["trace[1611504471] 'read index received' (duration: 154.568524ms)","trace[1611504471] 'applied index is now lower than readState.Index' (duration: 1.124066ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:23.5075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.782913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:23.507492Z","caller":"traceutil/trace.go:171","msg":"trace[1370036108] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"151.711607ms","start":"2026-08-12T00:26:23.355765Z","end":"2026-08-12T00:26:23.507477Z","steps":["trace[1370036108] 'process raft request' (duration: 151.46896ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:23.507536Z","caller":"traceutil/trace.go:171","msg":"trace[1194959832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"155.832384ms","start":"2026-08-12T00:26:23.351692Z","end":"2026-08-12T00:26:23.507525Z","steps":["trace[1194959832] 'agreement among raft nodes before linearized reading' (duration: 155.758713ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:23.507313Z","caller":"traceutil/trace.go:171","msg":"trace[1911807613] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"158.016656ms","start":"2026-08-12T00:26:23.349272Z","end":"2026-08-12T00:26:23.507288Z","steps":["trace[1911807613] 'process raft request' (duration: 157.814951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:37.974046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.268921ms","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-12T00:26:37.974145Z","caller":"traceutil/trace.go:171","msg":"trace[1590608400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"188.426454ms","start":"2026-08-12T00:26:37.785701Z","end":"2026-08-12T00:26:37.974128Z","steps":["trace[1590608400] 'range keys from in-memory index tree' (duration: 188.118467ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:44.88898Z","caller":"traceutil/trace.go:171","msg":"trace[2094519731] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"189.92895ms","start":"2026-08-12T00:26:44.699023Z","end":"2026-08-12T00:26:44.888952Z","steps":["trace[2094519731] 'read index received' (duration: 189.735725ms)","trace[2094519731] 'applied index is now lower than readState.Index' (duration: 191.915µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:44.889145Z","caller":"traceutil/trace.go:171","msg":"trace[695344052] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"201.150767ms","start":"2026-08-12T00:26:44.687956Z","end":"2026-08-12T00:26:44.889107Z","steps":["trace[695344052] 'process raft request' (duration: 200.811269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.889232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.194436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T00:26:44.889269Z","caller":"traceutil/trace.go:171","msg":"trace[1753851379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"190.307368ms","start":"2026-08-12T00:26:44.698949Z","end":"2026-08-12T00:26:44.889256Z","steps":["trace[1753851379] 'agreement among raft nodes before linearized reading' (duration: 190.142874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.889506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.569535ms","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-12T00:26:44.889656Z","caller":"traceutil/trace.go:171","msg":"trace[1446413522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"185.743678ms","start":"2026-08-12T00:26:44.703875Z","end":"2026-08-12T00:26:44.889619Z","steps":["trace[1446413522] 'agreement among raft nodes before linearized reading' (duration: 185.319118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:45.383359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.354385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:26:45.383572Z","caller":"traceutil/trace.go:171","msg":"trace[1091621187] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"488.550377ms","start":"2026-08-12T00:26:44.895004Z","end":"2026-08-12T00:26:45.383555Z","steps":["trace[1091621187] 'process raft request' (duration: 488.468365ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:45.383597Z","caller":"traceutil/trace.go:171","msg":"trace[952387601] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"488.795683ms","start":"2026-08-12T00:26:44.894748Z","end":"2026-08-12T00:26:45.383544Z","steps":["trace[952387601] 'process raft request' (duration: 276.155991ms)","trace[952387601] 'compare' (duration: 212.214611ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:45.383634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.894981Z","time spent":"488.623218ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:45.383668Z","caller":"traceutil/trace.go:171","msg":"trace[1938132525] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1007; }","duration":"397.737256ms","start":"2026-08-12T00:26:44.985907Z","end":"2026-08-12T00:26:45.383644Z","steps":["trace[1938132525] 'read index received' (duration: 185.084934ms)","trace[1938132525] 'applied index is now lower than readState.Index' (duration: 212.651462ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:45.383782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.889199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:45.383749Z","caller":"traceutil/trace.go:171","msg":"trace[1454768779] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"298.76052ms","start":"2026-08-12T00:26:45.08495Z","end":"2026-08-12T00:26:45.383711Z","steps":["trace[1454768779] 'process raft request' (duration: 298.569856ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:45.383804Z","caller":"traceutil/trace.go:171","msg":"trace[1760985872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"397.9547ms","start":"2026-08-12T00:26:44.985841Z","end":"2026-08-12T00:26:45.383796Z","steps":["trace[1760985872] 'agreement among raft nodes before linearized reading' (duration: 397.91688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:45.383777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.894722Z","time spent":"488.959696ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:26:45.383826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.985818Z","time spent":"398.003312ms","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:26:45.38384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.142165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:45.383878Z","caller":"traceutil/trace.go:171","msg":"trace[1986568511] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:973; }","duration":"113.243107ms","start":"2026-08-12T00:26:45.270621Z","end":"2026-08-12T00:26:45.383865Z","steps":["trace[1986568511] 'agreement among raft nodes before linearized reading' (duration: 113.163095ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:15.433028Z","caller":"traceutil/trace.go:171","msg":"trace[490355804] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"313.763598ms","start":"2026-08-12T00:27:15.11923Z","end":"2026-08-12T00:27:15.432994Z","steps":["trace[490355804] 'process raft request' (duration: 313.339328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:15.433448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:15.119199Z","time spent":"313.921132ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:27:15.897569Z","caller":"traceutil/trace.go:171","msg":"trace[1175340093] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"293.486025ms","start":"2026-08-12T00:27:15.604045Z","end":"2026-08-12T00:27:15.897531Z","steps":["trace[1175340093] 'read index received' (duration: 266.969661ms)","trace[1175340093] 'applied index is now lower than readState.Index' (duration: 26.503963ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:27:15.897611Z","caller":"traceutil/trace.go:171","msg":"trace[1941070990] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"304.757723ms","start":"2026-08-12T00:27:15.592805Z","end":"2026-08-12T00:27:15.897563Z","steps":["trace[1941070990] 'process raft request' (duration: 278.326582ms)","trace[1941070990] 'compare' (duration: 25.807097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:15.897993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.654438ms","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-12T00:27:15.897979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.914304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T00:27:15.898059Z","caller":"traceutil/trace.go:171","msg":"trace[380414731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"283.765842ms","start":"2026-08-12T00:27:15.614279Z","end":"2026-08-12T00:27:15.898045Z","steps":["trace[380414731] 'agreement among raft nodes before linearized reading' (duration: 283.509076ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:15.89809Z","caller":"traceutil/trace.go:171","msg":"trace[113459065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"294.094039ms","start":"2026-08-12T00:27:15.603971Z","end":"2026-08-12T00:27:15.898065Z","steps":["trace[113459065] 'agreement among raft nodes before linearized reading' (duration: 293.678829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:15.898472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:15.592774Z","time spent":"304.960938ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:27:17.555186Z","caller":"traceutil/trace.go:171","msg":"trace[1503699427] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"113.231017ms","start":"2026-08-12T00:27:17.441925Z","end":"2026-08-12T00:27:17.555156Z","steps":["trace[1503699427] 'process raft request' (duration: 113.031602ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:18.087336Z","caller":"traceutil/trace.go:171","msg":"trace[74800498] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"103.077184ms","start":"2026-08-12T00:27:17.984216Z","end":"2026-08-12T00:27:18.087293Z","steps":["trace[74800498] 'read index received' (duration: 88.340891ms)","trace[74800498] 'applied index is now lower than readState.Index' (duration: 14.734063ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:27:18.087494Z","caller":"traceutil/trace.go:171","msg":"trace[1197776787] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"123.381809ms","start":"2026-08-12T00:27:17.964077Z","end":"2026-08-12T00:27:18.087458Z","steps":["trace[1197776787] 'process raft request' (duration: 108.466062ms)","trace[1197776787] 'compare' (duration: 10.667075ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:18.088127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.903233ms","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-12T00:27:18.088861Z","caller":"traceutil/trace.go:171","msg":"trace[1756902023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"104.723563ms","start":"2026-08-12T00:27:17.984126Z","end":"2026-08-12T00:27:18.08885Z","steps":["trace[1756902023] 'agreement among raft nodes before linearized reading' (duration: 103.862342ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:34.320575Z","caller":"traceutil/trace.go:171","msg":"trace[85601001] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"100.229835ms","start":"2026-08-12T00:27:34.220314Z","end":"2026-08-12T00:27:34.320544Z","steps":["trace[85601001] 'read index received' (duration: 99.932798ms)","trace[85601001] 'applied index is now lower than readState.Index' (duration: 295.877µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:27:34.320729Z","caller":"traceutil/trace.go:171","msg":"trace[1870540339] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"123.153503ms","start":"2026-08-12T00:27:34.197561Z","end":"2026-08-12T00:27:34.320715Z","steps":["trace[1870540339] 'process raft request' (duration: 122.775833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:34.321137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.764628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T00:27:34.321233Z","caller":"traceutil/trace.go:171","msg":"trace[866974949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1185; }","duration":"100.947333ms","start":"2026-08-12T00:27:34.220255Z","end":"2026-08-12T00:27:34.321202Z","steps":["trace[866974949] 'agreement among raft nodes before linearized reading' (duration: 100.637615ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:37.329893Z","caller":"traceutil/trace.go:171","msg":"trace[1982224286] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"343.474919ms","start":"2026-08-12T00:27:36.986385Z","end":"2026-08-12T00:27:37.32986Z","steps":["trace[1982224286] 'read index received' (duration: 343.302455ms)","trace[1982224286] 'applied index is now lower than readState.Index' (duration: 171.024µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:37.33005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.638843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:27:37.330081Z","caller":"traceutil/trace.go:171","msg":"trace[1927452437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"343.739205ms","start":"2026-08-12T00:27:36.986332Z","end":"2026-08-12T00:27:37.330071Z","steps":["trace[1927452437] 'agreement among raft nodes before linearized reading' (duration: 343.635623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:37.330112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:36.98631Z","time spent":"343.794907ms","remote":"127.0.0.1:56536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T00:27:37.33042Z","caller":"traceutil/trace.go:171","msg":"trace[536074273] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"808.432141ms","start":"2026-08-12T00:27:36.521979Z","end":"2026-08-12T00:27:37.330411Z","steps":["trace[536074273] 'process raft request' (duration: 807.714734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:37.330513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:36.521968Z","time spent":"808.482762ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:27:52.894368Z","caller":"traceutil/trace.go:171","msg":"trace[1117821750] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"180.901114ms","start":"2026-08-12T00:27:52.713426Z","end":"2026-08-12T00:27:52.894327Z","steps":["trace[1117821750] 'process raft request' (duration: 95.249106ms)","trace[1117821750] 'compare' (duration: 85.405982ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:27:52.894701Z","caller":"traceutil/trace.go:171","msg":"trace[662192478] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"179.909649ms","start":"2026-08-12T00:27:52.714779Z","end":"2026-08-12T00:27:52.894688Z","steps":["trace[662192478] 'process raft request' (duration: 179.485589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:53.827701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.222081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-12T00:27:53.827802Z","caller":"traceutil/trace.go:171","msg":"trace[695708462] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:1266; }","duration":"214.380044ms","start":"2026-08-12T00:27:53.613397Z","end":"2026-08-12T00:27:53.827777Z","steps":["trace[695708462] 'range keys from in-memory index tree' (duration: 214.078317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:53.827796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.200096ms","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-12T00:27:53.827914Z","caller":"traceutil/trace.go:171","msg":"trace[183565515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"408.33748ms","start":"2026-08-12T00:27:53.419548Z","end":"2026-08-12T00:27:53.827886Z","steps":["trace[183565515] 'range keys from in-memory index tree' (duration: 407.813307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:53.828021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:53.419478Z","time spent":"408.524534ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T00:27:54.669143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.906577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:27:54.669504Z","caller":"traceutil/trace.go:171","msg":"trace[1274079660] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"836.538262ms","start":"2026-08-12T00:27:53.832926Z","end":"2026-08-12T00:27:54.669464Z","steps":["trace[1274079660] 'process raft request' (duration: 358.60768ms)","trace[1274079660] 'compare' (duration: 476.722803ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:27:54.669605Z","caller":"traceutil/trace.go:171","msg":"trace[1670504560] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"835.906037ms","start":"2026-08-12T00:27:53.833675Z","end":"2026-08-12T00:27:54.669581Z","steps":["trace[1670504560] 'process raft request' (duration: 835.632011ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:54.669526Z","caller":"traceutil/trace.go:171","msg":"trace[1693630842] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1320; }","duration":"682.118031ms","start":"2026-08-12T00:27:53.987375Z","end":"2026-08-12T00:27:54.669493Z","steps":["trace[1693630842] 'read index received' (duration: 204.175289ms)","trace[1693630842] 'applied index is now lower than readState.Index' (duration: 477.941012ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:54.669699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:53.832901Z","time spent":"836.680365ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:27:54.669715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:53.833641Z","time spent":"836.02015ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:27:54.669803Z","caller":"traceutil/trace.go:171","msg":"trace[2118209423] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"734.973485ms","start":"2026-08-12T00:27:53.934807Z","end":"2026-08-12T00:27:54.669781Z","steps":["trace[2118209423] 'process raft request' (duration: 734.615187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:54.669891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.524302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T00:27:54.669911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:53.934781Z","time spent":"735.071519ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:27:54.669936Z","caller":"traceutil/trace.go:171","msg":"trace[1843490144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"682.621324ms","start":"2026-08-12T00:27:53.9873Z","end":"2026-08-12T00:27:54.669921Z","steps":["trace[1843490144] 'agreement among raft nodes before linearized reading' (duration: 682.537932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:54.66997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:53.987269Z","time spent":"682.694506ms","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T00:27:54.690788Z","caller":"traceutil/trace.go:171","msg":"trace[1284433445] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"485.495632ms","start":"2026-08-12T00:27:54.205279Z","end":"2026-08-12T00:27:54.690775Z","steps":["trace[1284433445] 'process raft request' (duration: 485.368779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:54.690857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:54.205251Z","time spent":"485.570474ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:27:54.690964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.841599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-12T00:27:54.691029Z","caller":"traceutil/trace.go:171","msg":"trace[1747852596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"264.962383ms","start":"2026-08-12T00:27:54.42605Z","end":"2026-08-12T00:27:54.691012Z","steps":["trace[1747852596] 'agreement among raft nodes before linearized reading' (duration: 264.748398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:54.69138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.639851ms","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-12T00:27:54.691431Z","caller":"traceutil/trace.go:171","msg":"trace[1584925970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"258.716643ms","start":"2026-08-12T00:27:54.432702Z","end":"2026-08-12T00:27:54.691418Z","steps":["trace[1584925970] 'agreement among raft nodes before linearized reading' (duration: 258.505178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:54.691119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.100378ms","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-12T00:27:54.69303Z","caller":"traceutil/trace.go:171","msg":"trace[350340543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"242.095757ms","start":"2026-08-12T00:27:54.450904Z","end":"2026-08-12T00:27:54.693Z","steps":["trace[350340543] 'agreement among raft nodes before linearized reading' (duration: 239.928415ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:54.927018Z","caller":"traceutil/trace.go:171","msg":"trace[512834950] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"231.319388ms","start":"2026-08-12T00:27:54.695621Z","end":"2026-08-12T00:27:54.92694Z","steps":["trace[512834950] 'process raft request' (duration: 231.127214ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:54.952203Z","caller":"traceutil/trace.go:171","msg":"trace[517937543] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"249.128385ms","start":"2026-08-12T00:27:54.703056Z","end":"2026-08-12T00:27:54.952185Z","steps":["trace[517937543] 'process raft request' (duration: 249.089044ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:54.95322Z","caller":"traceutil/trace.go:171","msg":"trace[794568877] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"250.21906ms","start":"2026-08-12T00:27:54.702024Z","end":"2026-08-12T00:27:54.952243Z","steps":["trace[794568877] 'process raft request' (duration: 250.036276ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:55.560448Z","caller":"traceutil/trace.go:171","msg":"trace[386740869] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"116.895554ms","start":"2026-08-12T00:27:55.443509Z","end":"2026-08-12T00:27:55.560404Z","steps":["trace[386740869] 'process raft request' (duration: 79.114821ms)","trace[386740869] 'compare' (duration: 37.578158ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:55.932342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.989085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:27:55.932507Z","caller":"traceutil/trace.go:171","msg":"trace[930541964] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"366.327585ms","start":"2026-08-12T00:27:55.566159Z","end":"2026-08-12T00:27:55.932487Z","steps":["trace[930541964] 'process raft request' (duration: 366.277764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:55.932579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:55.566135Z","time spent":"366.401037ms","remote":"127.0.0.1:56618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:27:55.932605Z","caller":"traceutil/trace.go:171","msg":"trace[403080350] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"366.693724ms","start":"2026-08-12T00:27:55.565869Z","end":"2026-08-12T00:27:55.932562Z","steps":["trace[403080350] 'process raft request' (duration: 139.413482ms)","trace[403080350] 'compare' (duration: 226.845701ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:55.933112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:55.565849Z","time spent":"366.853827ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:27:56.444506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569289921848071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T00:27:56.940538Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001649239s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T00:27:56.940905Z","caller":"traceutil/trace.go:171","msg":"trace[1377575865] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"1.002149901s","start":"2026-08-12T00:27:55.938735Z","end":"2026-08-12T00:27:56.940885Z","steps":["trace[1377575865] 'process raft request' (duration: 1.002019528s)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:56.941019Z","caller":"traceutil/trace.go:171","msg":"trace[1947175967] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1334; }","duration":"1.00169711s","start":"2026-08-12T00:27:55.9393Z","end":"2026-08-12T00:27:56.940997Z","steps":["trace[1947175967] 'read index received' (duration: 1.001677229s)","trace[1947175967] 'applied index is now lower than readState.Index' (duration: 18.221µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:56.941043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:55.938705Z","time spent":"1.002272194s","remote":"127.0.0.1:56618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:27:56.941415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002077019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-08-12T00:27:56.941511Z","caller":"traceutil/trace.go:171","msg":"trace[325430057] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1280; }","duration":"1.002230272s","start":"2026-08-12T00:27:55.939257Z","end":"2026-08-12T00:27:56.941487Z","steps":["trace[325430057] 'agreement among raft nodes before linearized reading' (duration: 1.001848383s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:56.941562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:55.939237Z","time spent":"1.002316034s","remote":"127.0.0.1:56864","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-12T00:27:57.169693Z","caller":"traceutil/trace.go:171","msg":"trace[1193744921] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"228.594354ms","start":"2026-08-12T00:27:56.941062Z","end":"2026-08-12T00:27:57.169656Z","steps":["trace[1193744921] 'read index received' (duration: 148.611972ms)","trace[1193744921] 'applied index is now lower than readState.Index' (duration: 79.981052ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:27:57.169959Z","caller":"traceutil/trace.go:171","msg":"trace[1286349027] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"472.160984ms","start":"2026-08-12T00:27:56.697779Z","end":"2026-08-12T00:27:57.16994Z","steps":["trace[1286349027] 'process raft request' (duration: 391.882536ms)","trace[1286349027] 'compare' (duration: 79.788597ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:27:57.17004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073642749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T00:27:57.170125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:56.697751Z","time spent":"472.248297ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:27:57.170105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.019651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-12T00:27:57.170168Z","caller":"traceutil/trace.go:171","msg":"trace[1346088277] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1281; }","duration":"1.073868224s","start":"2026-08-12T00:27:56.096269Z","end":"2026-08-12T00:27:57.170137Z","steps":["trace[1346088277] 'agreement among raft nodes before linearized reading' (duration: 1.073596968s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.170183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.996294ms","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-12T00:27:57.170234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:56.096227Z","time spent":"1.073994657s","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-12T00:27:57.170229Z","caller":"traceutil/trace.go:171","msg":"trace[1485902352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"495.224316ms","start":"2026-08-12T00:27:56.674974Z","end":"2026-08-12T00:27:57.170198Z","steps":["trace[1485902352] 'agreement among raft nodes before linearized reading' (duration: 494.745014ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:57.170302Z","caller":"traceutil/trace.go:171","msg":"trace[1921104913] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"223.634815ms","start":"2026-08-12T00:27:56.946649Z","end":"2026-08-12T00:27:57.170284Z","steps":["trace[1921104913] 'process raft request' (duration: 223.194454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.170307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:56.674947Z","time spent":"495.343139ms","remote":"127.0.0.1:56864","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-12T00:27:57.170305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.41607ms","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-12T00:27:57.170366Z","caller":"traceutil/trace.go:171","msg":"trace[841642712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"495.517122ms","start":"2026-08-12T00:27:56.674834Z","end":"2026-08-12T00:27:57.170351Z","steps":["trace[841642712] 'agreement among raft nodes before linearized reading' (duration: 495.341058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.170402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:56.674806Z","time spent":"495.588394ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T00:27:57.170584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.767216ms","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-12T00:27:57.170627Z","caller":"traceutil/trace.go:171","msg":"trace[1685831282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"238.865528ms","start":"2026-08-12T00:27:56.931752Z","end":"2026-08-12T00:27:57.170617Z","steps":["trace[1685831282] 'agreement among raft nodes before linearized reading' (duration: 238.734355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.170658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09379658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T00:27:57.170711Z","caller":"traceutil/trace.go:171","msg":"trace[621724643] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1282; }","duration":"1.093890292s","start":"2026-08-12T00:27:56.076804Z","end":"2026-08-12T00:27:57.170694Z","steps":["trace[621724643] 'agreement among raft nodes before linearized reading' (duration: 1.093733329s)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:57.170243Z","caller":"traceutil/trace.go:171","msg":"trace[918340686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"214.090836ms","start":"2026-08-12T00:27:56.956137Z","end":"2026-08-12T00:27:57.170228Z","steps":["trace[918340686] 'agreement among raft nodes before linearized reading' (duration: 213.926522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.170779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:56.076786Z","time spent":"1.093977875s","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-08-12T00:27:57.170841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.623485ms","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-12T00:27:57.171142Z","caller":"traceutil/trace.go:171","msg":"trace[1618175904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"213.764269ms","start":"2026-08-12T00:27:56.957154Z","end":"2026-08-12T00:27:57.170918Z","steps":["trace[1618175904] 'agreement among raft nodes before linearized reading' (duration: 213.381089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.177264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191129987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:27:57.177338Z","caller":"traceutil/trace.go:171","msg":"trace[1995385276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"1.19122725s","start":"2026-08-12T00:27:55.986095Z","end":"2026-08-12T00:27:57.177323Z","steps":["trace[1995385276] 'agreement among raft nodes before linearized reading' (duration: 1.183654659s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:27:57.177392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:55.986087Z","time spent":"1.191296802s","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:27:59.248886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.523593ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T00:27:59.293654Z","caller":"traceutil/trace.go:171","msg":"trace[879674941] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"115.820158ms","start":"2026-08-12T00:27:59.177804Z","end":"2026-08-12T00:27:59.293624Z","steps":["trace[879674941] 'process raft request' (duration: 115.603663ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:59.518824Z","caller":"traceutil/trace.go:171","msg":"trace[1969184846] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"217.762724ms","start":"2026-08-12T00:27:59.301029Z","end":"2026-08-12T00:27:59.518792Z","steps":["trace[1969184846] 'process raft request' (duration: 161.677804ms)","trace[1969184846] 'compare' (duration: 55.477786ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:28:00.524758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569289921848096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T00:28:00.788915Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.297743195s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T00:28:00.791735Z","caller":"traceutil/trace.go:171","msg":"trace[785378114] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.487883409s","start":"2026-08-12T00:27:59.30382Z","end":"2026-08-12T00:28:00.791703Z","steps":["trace[785378114] 'process raft request' (duration: 1.485368539s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:28:00.791906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:59.303808Z","time spent":"1.487991281s","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:28:00.792194Z","caller":"traceutil/trace.go:171","msg":"trace[2040196624] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"1.488265918s","start":"2026-08-12T00:27:59.303899Z","end":"2026-08-12T00:28:00.792165Z","steps":["trace[2040196624] 'process raft request' (duration: 1.487751406s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:28:00.792427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:59.303892Z","time spent":"1.48837859s","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:28:00.792551Z","caller":"traceutil/trace.go:171","msg":"trace[1791652520] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"1.488492414s","start":"2026-08-12T00:27:59.304046Z","end":"2026-08-12T00:28:00.792538Z","steps":["trace[1791652520] 'process raft request' (duration: 1.488025163s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:28:00.792633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:59.304038Z","time spent":"1.488557475s","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:28:00.793004Z","caller":"traceutil/trace.go:171","msg":"trace[173080774] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"1.488293568s","start":"2026-08-12T00:27:59.304693Z","end":"2026-08-12T00:28:00.792986Z","steps":["trace[173080774] 'process raft request' (duration: 1.487791007s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:28:00.793118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:27:59.304681Z","time spent":"1.488359591s","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:28:00.795828Z","caller":"traceutil/trace.go:171","msg":"trace[1328902816] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1344; }","duration":"771.342525ms","start":"2026-08-12T00:28:00.024453Z","end":"2026-08-12T00:28:00.795795Z","steps":["trace[1328902816] 'read index received' (duration: 764.801609ms)","trace[1328902816] 'applied index is now lower than readState.Index' (duration: 6.539816ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:28:00.795877Z","caller":"traceutil/trace.go:171","msg":"trace[2011384476] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"614.756291ms","start":"2026-08-12T00:28:00.181074Z","end":"2026-08-12T00:28:00.79583Z","steps":["trace[2011384476] 'process raft request' (duration: 614.585147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:28:00.795989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.517189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:28:00.79603Z","caller":"traceutil/trace.go:171","msg":"trace[1782698616] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1295; }","duration":"771.57108ms","start":"2026-08-12T00:28:00.024446Z","end":"2026-08-12T00:28:00.796017Z","steps":["trace[1782698616] 'agreement among raft nodes before linearized reading' (duration: 771.433677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:28:00.796052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:28:00.181051Z","time spent":"614.911526ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:28:00.796065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:28:00.024403Z","time spent":"771.652722ms","remote":"127.0.0.1:53052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-12T00:28:00.79635Z","caller":"traceutil/trace.go:171","msg":"trace[1050679567] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"160.746421ms","start":"2026-08-12T00:28:00.635591Z","end":"2026-08-12T00:28:00.796338Z","steps":["trace[1050679567] 'process raft request' (duration: 160.156658ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:28:02.439349Z","caller":"traceutil/trace.go:171","msg":"trace[941020664] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"230.221052ms","start":"2026-08-12T00:28:02.209097Z","end":"2026-08-12T00:28:02.439318Z","steps":["trace[941020664] 'process raft request' (duration: 228.400988ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:28:02.439341Z","caller":"traceutil/trace.go:171","msg":"trace[49771448] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"227.952508ms","start":"2026-08-12T00:28:02.211371Z","end":"2026-08-12T00:28:02.439323Z","steps":["trace[49771448] 'process raft request' (duration: 227.926587ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:28:02.439401Z","caller":"traceutil/trace.go:171","msg":"trace[1878919847] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"228.920572ms","start":"2026-08-12T00:28:02.21044Z","end":"2026-08-12T00:28:02.439361Z","steps":["trace[1878919847] 'process raft request' (duration: 228.787999ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:28:05.469072Z","caller":"traceutil/trace.go:171","msg":"trace[1817163570] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"106.387083ms","start":"2026-08-12T00:28:05.362658Z","end":"2026-08-12T00:28:05.469045Z","steps":["trace[1817163570] 'process raft request' (duration: 106.222409ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:31:06.089523Z","caller":"traceutil/trace.go:171","msg":"trace[1791625723] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"112.276799ms","start":"2026-08-12T00:31:05.977209Z","end":"2026-08-12T00:31:06.089485Z","steps":["trace[1791625723] 'process raft request' (duration: 56.647671ms)","trace[1791625723] 'compare' (duration: 55.468104ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:31:06.285404Z","caller":"traceutil/trace.go:171","msg":"trace[1863321272] linearizableReadLoop","detail":"{readStateIndex:2283; appliedIndex:2282; }","duration":"112.572956ms","start":"2026-08-12T00:31:06.172804Z","end":"2026-08-12T00:31:06.285377Z","steps":["trace[1863321272] 'read index received' (duration: 21.490764ms)","trace[1863321272] 'applied index is now lower than readState.Index' (duration: 91.079832ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:31:06.285523Z","caller":"traceutil/trace.go:171","msg":"trace[1171615137] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"115.617878ms","start":"2026-08-12T00:31:06.169881Z","end":"2026-08-12T00:31:06.285499Z","steps":["trace[1171615137] 'process raft request' (duration: 24.517495ms)","trace[1171615137] 'compare' (duration: 90.54134ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:31:06.285847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.034447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-12T00:31:06.285904Z","caller":"traceutil/trace.go:171","msg":"trace[1729842250] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2191; }","duration":"113.104918ms","start":"2026-08-12T00:31:06.172781Z","end":"2026-08-12T00:31:06.285886Z","steps":["trace[1729842250] 'agreement among raft nodes before linearized reading' (duration: 112.73828ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:31:07.188546Z","caller":"traceutil/trace.go:171","msg":"trace[1564933502] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2287; }","duration":"200.505764ms","start":"2026-08-12T00:31:06.987993Z","end":"2026-08-12T00:31:07.188499Z","steps":["trace[1564933502] 'read index received' (duration: 200.182006ms)","trace[1564933502] 'applied index is now lower than readState.Index' (duration: 322.298µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:31:07.188737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.71797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:31:07.188798Z","caller":"traceutil/trace.go:171","msg":"trace[1009525289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"200.813612ms","start":"2026-08-12T00:31:06.987972Z","end":"2026-08-12T00:31:07.188786Z","steps":["trace[1009525289] 'agreement among raft nodes before linearized reading' (duration: 200.682779ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:31:07.188915Z","caller":"traceutil/trace.go:171","msg":"trace[1843277994] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"224.943598ms","start":"2026-08-12T00:31:06.963925Z","end":"2026-08-12T00:31:07.188869Z","steps":["trace[1843277994] 'process raft request' (duration: 224.306942ms)"],"step_count":1}