{"level":"warn","ts":"2026-05-07T13:15:43.074768Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T13:15:43.074904Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.23:2380","--initial-cluster=instance=https://199.19.213.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.23:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T13:15:43.075003Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T13:15:43.075019Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-05-07T13:15:43.075065Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T13:15:43.076012Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"]} {"level":"info","ts":"2026-05-07T13:15:43.07615Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.23:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T13:15:43.080734Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.22333ms"} {"level":"info","ts":"2026-05-07T13:15:43.087835Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1812db8446d3ac67","cluster-id":"57ff06cb872baa33"} {"level":"info","ts":"2026-05-07T13:15:43.087907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T13:15:43.087937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 0"} {"level":"info","ts":"2026-05-07T13:15:43.087955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1812db8446d3ac67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T13:15:43.087971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 1"} {"level":"info","ts":"2026-05-07T13:15:43.088019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"warn","ts":"2026-05-07T13:15:43.097242Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T13:15:43.101989Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T13:15:43.106092Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T13:15:43.110961Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1812db8446d3ac67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T13:15:43.111128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T13:15:43.111215Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T13:15:43.111237Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T13:15:43.111571Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1812db8446d3ac67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T13:15:43.112487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"info","ts":"2026-05-07T13:15:43.11278Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","added-peer-id":"1812db8446d3ac67","added-peer-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-05-07T13:15:43.114423Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T13:15:43.114523Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-05-07T13:15:43.114676Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-05-07T13:15:43.115399Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1812db8446d3ac67","initial-advertise-peer-urls":["https://199.19.213.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T13:15:43.115432Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T13:15:43.788321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T13:15:43.788378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T13:15:43.788423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgPreVoteResp from 1812db8446d3ac67 at term 1"} {"level":"info","ts":"2026-05-07T13:15:43.788446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became candidate at term 2"} {"level":"info","ts":"2026-05-07T13:15:43.788457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgVoteResp from 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-05-07T13:15:43.788473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became leader at term 2"} {"level":"info","ts":"2026-05-07T13:15:43.788486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1812db8446d3ac67 elected leader 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-05-07T13:15:43.789241Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1812db8446d3ac67","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.23:2379]}","request-path":"/0/members/1812db8446d3ac67/attributes","cluster-id":"57ff06cb872baa33","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T13:15:43.789288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:15:43.789299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:15:43.7895Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:15:43.789603Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T13:15:43.78966Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T13:15:43.790501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:15:43.790701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:15:43.790791Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:15:43.792805Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.23:2379"} {"level":"info","ts":"2026-05-07T13:15:43.793658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T13:15:51.555083Z","caller":"traceutil/trace.go:171","msg":"trace[1509917860] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"108.089761ms","start":"2026-05-07T13:15:51.446957Z","end":"2026-05-07T13:15:51.555047Z","steps":["trace[1509917860] 'process raft request' (duration: 107.736418ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:52.198205Z","caller":"traceutil/trace.go:171","msg":"trace[1878179833] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"195.072533ms","start":"2026-05-07T13:15:52.003106Z","end":"2026-05-07T13:15:52.198178Z","steps":["trace[1878179833] 'process raft request' (duration: 156.500078ms)","trace[1878179833] 'compare' (duration: 38.254461ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:15:52.198296Z","caller":"traceutil/trace.go:171","msg":"trace[2110109671] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:309; }","duration":"158.285105ms","start":"2026-05-07T13:15:52.039991Z","end":"2026-05-07T13:15:52.198277Z","steps":["trace[2110109671] 'read index received' (duration: 1.662265ms)","trace[2110109671] 'applied index is now lower than readState.Index' (duration: 156.62197ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:15:52.198583Z","caller":"traceutil/trace.go:171","msg":"trace[1300129775] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"195.159914ms","start":"2026-05-07T13:15:52.003399Z","end":"2026-05-07T13:15:52.198559Z","steps":["trace[1300129775] 'process raft request' (duration: 194.810761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:52.19866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.609438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-07T13:15:52.199655Z","caller":"traceutil/trace.go:171","msg":"trace[250592345] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:301; }","duration":"159.040801ms","start":"2026-05-07T13:15:52.039924Z","end":"2026-05-07T13:15:52.198965Z","steps":["trace[250592345] 'agreement among raft nodes before linearized reading' (duration: 158.553227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:52.199719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.210368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-07T13:15:52.200039Z","caller":"traceutil/trace.go:171","msg":"trace[1348783165] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"109.456559ms","start":"2026-05-07T13:15:52.090567Z","end":"2026-05-07T13:15:52.200024Z","steps":["trace[1348783165] 'agreement among raft nodes before linearized reading' (duration: 108.169998ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:52.315954Z","caller":"traceutil/trace.go:171","msg":"trace[1021739478] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"108.999525ms","start":"2026-05-07T13:15:52.206915Z","end":"2026-05-07T13:15:52.315915Z","steps":["trace[1021739478] 'process raft request' (duration: 108.696522ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:52.316365Z","caller":"traceutil/trace.go:171","msg":"trace[373428774] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"108.967724ms","start":"2026-05-07T13:15:52.207351Z","end":"2026-05-07T13:15:52.316319Z","steps":["trace[373428774] 'process raft request' (duration: 108.46955ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:52.316528Z","caller":"traceutil/trace.go:171","msg":"trace[737619442] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"107.609093ms","start":"2026-05-07T13:15:52.208905Z","end":"2026-05-07T13:15:52.316514Z","steps":["trace[737619442] 'process raft request' (duration: 107.415621ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:52.316593Z","caller":"traceutil/trace.go:171","msg":"trace[2135620908] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"104.581384ms","start":"2026-05-07T13:15:52.21199Z","end":"2026-05-07T13:15:52.316572Z","steps":["trace[2135620908] 'process raft request' (duration: 104.400713ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:52.316602Z","caller":"traceutil/trace.go:171","msg":"trace[194665205] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"108.53876ms","start":"2026-05-07T13:15:52.208015Z","end":"2026-05-07T13:15:52.316554Z","steps":["trace[194665205] 'process raft request' (duration: 108.116726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:55.794885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.602481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:15:55.795003Z","caller":"traceutil/trace.go:171","msg":"trace[1633753773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:352; }","duration":"190.774213ms","start":"2026-05-07T13:15:55.604201Z","end":"2026-05-07T13:15:55.794975Z","steps":["trace[1633753773] 'range keys from in-memory index tree' (duration: 190.46018ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:56.043552Z","caller":"traceutil/trace.go:171","msg":"trace[682067992] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"218.678037ms","start":"2026-05-07T13:15:55.824854Z","end":"2026-05-07T13:15:56.043532Z","steps":["trace[682067992] 'process raft request' (duration: 218.542166ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:15:56.729166Z","caller":"traceutil/trace.go:171","msg":"trace[1321120453] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"170.087084ms","start":"2026-05-07T13:15:56.559056Z","end":"2026-05-07T13:15:56.729144Z","steps":["trace[1321120453] 'read index received' (duration: 169.956543ms)","trace[1321120453] 'applied index is now lower than readState.Index' (duration: 127.531µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:15:56.729314Z","caller":"traceutil/trace.go:171","msg":"trace[1933028827] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"171.737998ms","start":"2026-05-07T13:15:56.557565Z","end":"2026-05-07T13:15:56.729303Z","steps":["trace[1933028827] 'process raft request' (duration: 171.434515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:56.729504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.436096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:15:56.729569Z","caller":"traceutil/trace.go:171","msg":"trace[1583393526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:355; }","duration":"170.548807ms","start":"2026-05-07T13:15:56.559004Z","end":"2026-05-07T13:15:56.729553Z","steps":["trace[1583393526] 'agreement among raft nodes before linearized reading' (duration: 170.437536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:57.292917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.500048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:15:57.293045Z","caller":"traceutil/trace.go:171","msg":"trace[2028519028] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"557.939977ms","start":"2026-05-07T13:15:56.735083Z","end":"2026-05-07T13:15:57.293023Z","steps":["trace[2028519028] 'process raft request' (duration: 167.642402ms)","trace[2028519028] 'compare' (duration: 389.381627ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:15:57.293135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:15:56.73506Z","time spent":"558.033338ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:15:57.306476Z","caller":"traceutil/trace.go:171","msg":"trace[1190564434] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:367; }","duration":"460.143899ms","start":"2026-05-07T13:15:56.846316Z","end":"2026-05-07T13:15:57.30646Z","steps":["trace[1190564434] 'read index received' (duration: 56.422816ms)","trace[1190564434] 'applied index is now lower than readState.Index' (duration: 403.720133ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:15:57.306713Z","caller":"traceutil/trace.go:171","msg":"trace[2059714207] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"569.923732ms","start":"2026-05-07T13:15:56.736779Z","end":"2026-05-07T13:15:57.306702Z","steps":["trace[2059714207] 'process raft request' (duration: 569.603779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:57.306831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:15:56.736755Z","time spent":"570.001532ms","remote":"127.0.0.1:60236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:15:57.307074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.751664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T13:15:57.307129Z","caller":"traceutil/trace.go:171","msg":"trace[739937575] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:357; }","duration":"460.834064ms","start":"2026-05-07T13:15:56.846282Z","end":"2026-05-07T13:15:57.307116Z","steps":["trace[739937575] 'agreement among raft nodes before linearized reading' (duration: 460.755744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:57.307164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:15:56.846266Z","time spent":"460.889265ms","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.23\" "} {"level":"info","ts":"2026-05-07T13:15:58.784983Z","caller":"traceutil/trace.go:171","msg":"trace[30950084] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"100.589866ms","start":"2026-05-07T13:15:58.684371Z","end":"2026-05-07T13:15:58.784961Z","steps":["trace[30950084] 'read index received' (duration: 100.324174ms)","trace[30950084] 'applied index is now lower than readState.Index' (duration: 264.782µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:15:58.785099Z","caller":"traceutil/trace.go:171","msg":"trace[662354941] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"114.270735ms","start":"2026-05-07T13:15:58.670819Z","end":"2026-05-07T13:15:58.78509Z","steps":["trace[662354941] 'process raft request' (duration: 113.980913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:15:58.785255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.861439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-07T13:15:58.785314Z","caller":"traceutil/trace.go:171","msg":"trace[404736122] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:361; }","duration":"100.968889ms","start":"2026-05-07T13:15:58.684333Z","end":"2026-05-07T13:15:58.785302Z","steps":["trace[404736122] 'agreement among raft nodes before linearized reading' (duration: 100.843238ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:03.514385Z","caller":"traceutil/trace.go:171","msg":"trace[727410037] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"157.184726ms","start":"2026-05-07T13:16:03.35717Z","end":"2026-05-07T13:16:03.514355Z","steps":["trace[727410037] 'read index received' (duration: 156.933097ms)","trace[727410037] 'applied index is now lower than readState.Index' (duration: 250.538µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:03.514532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.339971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T13:16:03.51458Z","caller":"traceutil/trace.go:171","msg":"trace[674669854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"157.422294ms","start":"2026-05-07T13:16:03.357143Z","end":"2026-05-07T13:16:03.514565Z","steps":["trace[674669854] 'agreement among raft nodes before linearized reading' (duration: 157.32317ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:03.514513Z","caller":"traceutil/trace.go:171","msg":"trace[1205500638] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"183.419523ms","start":"2026-05-07T13:16:03.331043Z","end":"2026-05-07T13:16:03.514463Z","steps":["trace[1205500638] 'process raft request' (duration: 183.115232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:03.803462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.849162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:03.803694Z","caller":"traceutil/trace.go:171","msg":"trace[1470491636] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"283.783463ms","start":"2026-05-07T13:16:03.519887Z","end":"2026-05-07T13:16:03.80367Z","steps":["trace[1470491636] 'process raft request' (duration: 79.564658ms)","trace[1470491636] 'compare' (duration: 203.646415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:04.064838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.888727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:16:04.064933Z","caller":"traceutil/trace.go:171","msg":"trace[197847999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:378; }","duration":"140.063473ms","start":"2026-05-07T13:16:03.92485Z","end":"2026-05-07T13:16:04.064913Z","steps":["trace[197847999] 'range keys from in-memory index tree' (duration: 139.72193ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:05.413515Z","caller":"traceutil/trace.go:171","msg":"trace[1815574865] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"138.305054ms","start":"2026-05-07T13:16:05.275182Z","end":"2026-05-07T13:16:05.413487Z","steps":["trace[1815574865] 'process raft request' (duration: 138.143518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:05.93079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.820171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T13:16:05.930884Z","caller":"traceutil/trace.go:171","msg":"trace[106788078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:382; }","duration":"121.999727ms","start":"2026-05-07T13:16:05.808864Z","end":"2026-05-07T13:16:05.930864Z","steps":["trace[106788078] 'range keys from in-memory index tree' (duration: 121.692207ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:07.544641Z","caller":"traceutil/trace.go:171","msg":"trace[1390493797] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"445.129493ms","start":"2026-05-07T13:16:07.099484Z","end":"2026-05-07T13:16:07.544613Z","steps":["trace[1390493797] 'process raft request' (duration: 444.948457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:07.544872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:07.099461Z","time spent":"445.33479ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:07.896586Z","caller":"traceutil/trace.go:171","msg":"trace[1969049409] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"224.753872ms","start":"2026-05-07T13:16:07.671812Z","end":"2026-05-07T13:16:07.896566Z","steps":["trace[1969049409] 'read index received' (duration: 224.557215ms)","trace[1969049409] 'applied index is now lower than readState.Index' (duration: 195.797µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:07.896613Z","caller":"traceutil/trace.go:171","msg":"trace[93982419] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"321.552003ms","start":"2026-05-07T13:16:07.575042Z","end":"2026-05-07T13:16:07.896594Z","steps":["trace[93982419] 'process raft request' (duration: 321.406738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:07.896717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:07.575021Z","time spent":"321.642556ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:07.896757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.935918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:16:07.897422Z","caller":"traceutil/trace.go:171","msg":"trace[1333172001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"225.624883ms","start":"2026-05-07T13:16:07.671784Z","end":"2026-05-07T13:16:07.897409Z","steps":["trace[1333172001] 'agreement among raft nodes before linearized reading' (duration: 224.917327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:09.574973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:16:09.827445Z","caller":"traceutil/trace.go:171","msg":"trace[668724115] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"931.834369ms","start":"2026-05-07T13:16:08.895569Z","end":"2026-05-07T13:16:09.827404Z","steps":["trace[668724115] 'process raft request' (duration: 931.542139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:09.82775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:08.895545Z","time spent":"932.006555ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:10.648996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.835759ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:10.64951Z","caller":"traceutil/trace.go:171","msg":"trace[1076361252] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:421; }","duration":"361.373647ms","start":"2026-05-07T13:16:10.288112Z","end":"2026-05-07T13:16:10.649486Z","steps":["trace[1076361252] 'range keys from in-memory index tree' (duration: 360.813418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:10.649982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.833804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:10.650172Z","caller":"traceutil/trace.go:171","msg":"trace[1894158916] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:435; }","duration":"1.576273556s","start":"2026-05-07T13:16:09.07388Z","end":"2026-05-07T13:16:10.650154Z","steps":["trace[1894158916] 'read index received' (duration: 753.257427ms)","trace[1894158916] 'applied index is now lower than readState.Index' (duration: 823.014519ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:10.650295Z","caller":"traceutil/trace.go:171","msg":"trace[2129751320] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.580212649s","start":"2026-05-07T13:16:09.070044Z","end":"2026-05-07T13:16:10.650256Z","steps":["trace[2129751320] 'process raft request' (duration: 1.27393856s)","trace[2129751320] 'compare' (duration: 305.198724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:10.65037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576467652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:16:10.650481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.310424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:16:10.650512Z","caller":"traceutil/trace.go:171","msg":"trace[2030829040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"1.576635088s","start":"2026-05-07T13:16:09.073824Z","end":"2026-05-07T13:16:10.650459Z","steps":["trace[2030829040] 'agreement among raft nodes before linearized reading' (duration: 1.576443952s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:10.65057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.56681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T13:16:10.650576Z","caller":"traceutil/trace.go:171","msg":"trace[1339556351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"683.432588ms","start":"2026-05-07T13:16:09.967111Z","end":"2026-05-07T13:16:10.650543Z","steps":["trace[1339556351] 'agreement among raft nodes before linearized reading' (duration: 683.21276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:10.650705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:09.967093Z","time spent":"683.601195ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:10.650577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:09.07381Z","time spent":"1.576756623s","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:16:10.650632Z","caller":"traceutil/trace.go:171","msg":"trace[1811054057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"468.672565ms","start":"2026-05-07T13:16:10.181943Z","end":"2026-05-07T13:16:10.650616Z","steps":["trace[1811054057] 'agreement among raft nodes before linearized reading' (duration: 468.54334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:10.65048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:09.070009Z","time spent":"1.580376255s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:10.65058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.030259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T13:16:10.651179Z","caller":"traceutil/trace.go:171","msg":"trace[126604908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"614.66915ms","start":"2026-05-07T13:16:10.036499Z","end":"2026-05-07T13:16:10.651168Z","steps":["trace[126604908] 'agreement among raft nodes before linearized reading' (duration: 614.018467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:10.651233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:10.036476Z","time spent":"614.746222ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:16:10.65098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:10.181923Z","time spent":"469.014206ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T13:16:14.917526Z","caller":"traceutil/trace.go:171","msg":"trace[1758387390] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"151.830722ms","start":"2026-05-07T13:16:14.765661Z","end":"2026-05-07T13:16:14.917491Z","steps":["trace[1758387390] 'process raft request' (duration: 151.680697ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:14.973498Z","caller":"traceutil/trace.go:171","msg":"trace[896368289] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"205.338311ms","start":"2026-05-07T13:16:14.768131Z","end":"2026-05-07T13:16:14.973469Z","steps":["trace[896368289] 'process raft request' (duration: 205.263389ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:14.973561Z","caller":"traceutil/trace.go:171","msg":"trace[1436038518] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"206.056254ms","start":"2026-05-07T13:16:14.767479Z","end":"2026-05-07T13:16:14.973536Z","steps":["trace[1436038518] 'process raft request' (duration: 205.816617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:15.620177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.817943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:15.620278Z","caller":"traceutil/trace.go:171","msg":"trace[664738463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"545.981379ms","start":"2026-05-07T13:16:15.074271Z","end":"2026-05-07T13:16:15.620252Z","steps":["trace[664738463] 'range keys from in-memory index tree' (duration: 545.682789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:15.620318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:15.074258Z","time spent":"546.053261ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:16:17.118068Z","caller":"traceutil/trace.go:171","msg":"trace[1765840819] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"161.638043ms","start":"2026-05-07T13:16:16.956399Z","end":"2026-05-07T13:16:17.118037Z","steps":["trace[1765840819] 'read index received' (duration: 158.220707ms)","trace[1765840819] 'applied index is now lower than readState.Index' (duration: 3.416126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:17.118274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.814039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-07T13:16:17.118298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.823859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T13:16:17.11833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.25574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:16:17.118362Z","caller":"traceutil/trace.go:171","msg":"trace[277732455] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:437; }","duration":"161.955193ms","start":"2026-05-07T13:16:16.956388Z","end":"2026-05-07T13:16:17.118343Z","steps":["trace[277732455] 'agreement among raft nodes before linearized reading' (duration: 161.765847ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:17.11837Z","caller":"traceutil/trace.go:171","msg":"trace[1972091309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:437; }","duration":"141.935692ms","start":"2026-05-07T13:16:16.976416Z","end":"2026-05-07T13:16:17.118352Z","steps":["trace[1972091309] 'agreement among raft nodes before linearized reading' (duration: 141.797418ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:17.118382Z","caller":"traceutil/trace.go:171","msg":"trace[238596602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:437; }","duration":"141.337563ms","start":"2026-05-07T13:16:16.977029Z","end":"2026-05-07T13:16:17.118367Z","steps":["trace[238596602] 'agreement among raft nodes before linearized reading' (duration: 141.25251ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:17.118211Z","caller":"traceutil/trace.go:171","msg":"trace[208710987] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"162.036606ms","start":"2026-05-07T13:16:16.956108Z","end":"2026-05-07T13:16:17.118145Z","steps":["trace[208710987] 'process raft request' (duration: 158.508656ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:17.31639Z","caller":"traceutil/trace.go:171","msg":"trace[106591368] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"193.316845ms","start":"2026-05-07T13:16:17.123051Z","end":"2026-05-07T13:16:17.316368Z","steps":["trace[106591368] 'process raft request' (duration: 124.924554ms)","trace[106591368] 'compare' (duration: 68.299978ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:17.97111Z","caller":"traceutil/trace.go:171","msg":"trace[1400146019] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"102.706681ms","start":"2026-05-07T13:16:17.868379Z","end":"2026-05-07T13:16:17.971086Z","steps":["trace[1400146019] 'process raft request' (duration: 102.539036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:19.718199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:16:20.00489Z","caller":"traceutil/trace.go:171","msg":"trace[1250076398] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"787.792426ms","start":"2026-05-07T13:16:19.217078Z","end":"2026-05-07T13:16:20.00487Z","steps":["trace[1250076398] 'read index received' (duration: 787.731404ms)","trace[1250076398] 'applied index is now lower than readState.Index' (duration: 60.392µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:20.005025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.933601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:16:20.005019Z","caller":"traceutil/trace.go:171","msg":"trace[1655162901] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"878.470675ms","start":"2026-05-07T13:16:19.126495Z","end":"2026-05-07T13:16:20.004966Z","steps":["trace[1655162901] 'process raft request' (duration: 878.261619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:20.005098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.972747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:16:20.005129Z","caller":"traceutil/trace.go:171","msg":"trace[984339866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:441; }","duration":"685.032769ms","start":"2026-05-07T13:16:19.320089Z","end":"2026-05-07T13:16:20.005121Z","steps":["trace[984339866] 'agreement among raft nodes before linearized reading' (duration: 684.981267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:20.005164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:19.320068Z","time spent":"685.08771ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:16:20.005173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:19.126479Z","time spent":"878.615949ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:20.00505Z","caller":"traceutil/trace.go:171","msg":"trace[592882120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:441; }","duration":"788.016374ms","start":"2026-05-07T13:16:19.217026Z","end":"2026-05-07T13:16:20.005043Z","steps":["trace[592882120] 'agreement among raft nodes before linearized reading' (duration: 787.947851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:20.005282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:19.217006Z","time spent":"788.26745ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:20.005523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.930677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-05-07T13:16:20.005824Z","caller":"traceutil/trace.go:171","msg":"trace[969489663] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:441; }","duration":"444.086021ms","start":"2026-05-07T13:16:19.561524Z","end":"2026-05-07T13:16:20.00561Z","steps":["trace[969489663] 'agreement among raft nodes before linearized reading' (duration: 443.764411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:20.005902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:19.561477Z","time spent":"444.407541ms","remote":"127.0.0.1:60136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12691,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-05-07T13:16:20.25654Z","caller":"traceutil/trace.go:171","msg":"trace[1741855666] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"246.742119ms","start":"2026-05-07T13:16:20.009762Z","end":"2026-05-07T13:16:20.256504Z","steps":["trace[1741855666] 'process raft request' (duration: 226.516518ms)","trace[1741855666] 'compare' (duration: 19.86391ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:20.256942Z","caller":"traceutil/trace.go:171","msg":"trace[393899172] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:458; }","duration":"180.790955ms","start":"2026-05-07T13:16:20.076122Z","end":"2026-05-07T13:16:20.256913Z","steps":["trace[393899172] 'read index received' (duration: 160.166851ms)","trace[393899172] 'applied index is now lower than readState.Index' (duration: 20.622914ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:20.257286Z","caller":"traceutil/trace.go:171","msg":"trace[665380403] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"247.295956ms","start":"2026-05-07T13:16:20.009954Z","end":"2026-05-07T13:16:20.25725Z","steps":["trace[665380403] 'process raft request' (duration: 246.708058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:20.257284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.160466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:20.257361Z","caller":"traceutil/trace.go:171","msg":"trace[1960088658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"181.433925ms","start":"2026-05-07T13:16:20.075907Z","end":"2026-05-07T13:16:20.257341Z","steps":["trace[1960088658] 'agreement among raft nodes before linearized reading' (duration: 181.294341ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:20.25729Z","caller":"traceutil/trace.go:171","msg":"trace[1339841514] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"246.910264ms","start":"2026-05-07T13:16:20.010362Z","end":"2026-05-07T13:16:20.257273Z","steps":["trace[1339841514] 'process raft request' (duration: 246.481871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:20.257794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.115853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-05-07T13:16:20.257904Z","caller":"traceutil/trace.go:171","msg":"trace[734233200] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:444; }","duration":"126.588537ms","start":"2026-05-07T13:16:20.131295Z","end":"2026-05-07T13:16:20.257884Z","steps":["trace[734233200] 'agreement among raft nodes before linearized reading' (duration: 125.986289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:21.488534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.312345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-05-07T13:16:21.488648Z","caller":"traceutil/trace.go:171","msg":"trace[1058835596] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:478; }","duration":"196.47953ms","start":"2026-05-07T13:16:21.292136Z","end":"2026-05-07T13:16:21.488616Z","steps":["trace[1058835596] 'range keys from in-memory index tree' (duration: 194.882543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.211154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.762028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-07T13:16:24.211309Z","caller":"traceutil/trace.go:171","msg":"trace[1441514992] transaction","detail":"{read_only:false; number_of_response:0; response_revision:500; }","duration":"1.209305397s","start":"2026-05-07T13:16:23.001989Z","end":"2026-05-07T13:16:24.211294Z","steps":["trace[1441514992] 'process raft request' (duration: 600.338572ms)","trace[1441514992] 'compare' (duration: 608.711837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:24.211364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.001979Z","time spent":"1.209361638s","remote":"127.0.0.1:60578","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:24.223416Z","caller":"traceutil/trace.go:171","msg":"trace[381793661] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.221343456s","start":"2026-05-07T13:16:23.002044Z","end":"2026-05-07T13:16:24.223388Z","steps":["trace[381793661] 'process raft request' (duration: 1.221075508s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:24.223417Z","caller":"traceutil/trace.go:171","msg":"trace[1021968890] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"1.220798301s","start":"2026-05-07T13:16:23.002562Z","end":"2026-05-07T13:16:24.223361Z","steps":["trace[1021968890] 'process raft request' (duration: 1.220638065s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.223554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.002026Z","time spent":"1.22144896s","remote":"127.0.0.1:60578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:24.223573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.002556Z","time spent":"1.220925814s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:24.223455Z","caller":"traceutil/trace.go:171","msg":"trace[1192340361] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.220791641s","start":"2026-05-07T13:16:23.002636Z","end":"2026-05-07T13:16:24.223427Z","steps":["trace[1192340361] 'process raft request' (duration: 1.220625635s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:24.223656Z","caller":"traceutil/trace.go:171","msg":"trace[486601038] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.214676411s","start":"2026-05-07T13:16:23.008958Z","end":"2026-05-07T13:16:24.223634Z","steps":["trace[486601038] 'process raft request' (duration: 1.214531666s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.223751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.00263Z","time spent":"1.221025367s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:24.223819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.008949Z","time spent":"1.214798004s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:24.223799Z","caller":"traceutil/trace.go:171","msg":"trace[1243934101] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.220984126s","start":"2026-05-07T13:16:23.002769Z","end":"2026-05-07T13:16:24.223753Z","steps":["trace[1243934101] 'process raft request' (duration: 1.220611075s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:24.223826Z","caller":"traceutil/trace.go:171","msg":"trace[517572566] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.22111976s","start":"2026-05-07T13:16:23.002677Z","end":"2026-05-07T13:16:24.223797Z","steps":["trace[517572566] 'process raft request' (duration: 1.220649876s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:24.22385Z","caller":"traceutil/trace.go:171","msg":"trace[1416101810] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.218536033s","start":"2026-05-07T13:16:23.005294Z","end":"2026-05-07T13:16:24.22383Z","steps":["trace[1416101810] 'process raft request' (duration: 1.218132272s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.223941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.002764Z","time spent":"1.221109169s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:24.223945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.005287Z","time spent":"1.218611465s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:24.223973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.002674Z","time spent":"1.221204483s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:24.224133Z","caller":"traceutil/trace.go:171","msg":"trace[2059366567] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.208930991s","start":"2026-05-07T13:16:23.015179Z","end":"2026-05-07T13:16:24.22411Z","steps":["trace[2059366567] 'process raft request' (duration: 1.208418256s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:24.224192Z","caller":"traceutil/trace.go:171","msg":"trace[52182566] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.198740022s","start":"2026-05-07T13:16:23.025438Z","end":"2026-05-07T13:16:24.224178Z","steps":["trace[52182566] 'process raft request' (duration: 1.198435853s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.224237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.02543Z","time spent":"1.198781973s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:24.224229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.015166Z","time spent":"1.209012103s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:24.224243Z","caller":"traceutil/trace.go:171","msg":"trace[837344598] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:518; }","duration":"973.297349ms","start":"2026-05-07T13:16:23.250917Z","end":"2026-05-07T13:16:24.224214Z","steps":["trace[837344598] 'read index received' (duration: 351.45199ms)","trace[837344598] 'applied index is now lower than readState.Index' (duration: 621.844029ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:24.224273Z","caller":"traceutil/trace.go:171","msg":"trace[2012831627] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"904.794739ms","start":"2026-05-07T13:16:23.319453Z","end":"2026-05-07T13:16:24.224248Z","steps":["trace[2012831627] 'process raft request' (duration: 904.669736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.224331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.319428Z","time spent":"904.875171ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:24.224359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.437733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:24.224392Z","caller":"traceutil/trace.go:171","msg":"trace[1426658466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"973.488265ms","start":"2026-05-07T13:16:23.250894Z","end":"2026-05-07T13:16:24.224382Z","steps":["trace[1426658466] 'agreement among raft nodes before linearized reading' (duration: 973.415373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.224407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.633535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-07T13:16:24.224423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.250877Z","time spent":"973.540386ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:16:24.224462Z","caller":"traceutil/trace.go:171","msg":"trace[394839075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"203.724308ms","start":"2026-05-07T13:16:24.020722Z","end":"2026-05-07T13:16:24.224447Z","steps":["trace[394839075] 'agreement among raft nodes before linearized reading' (duration: 203.627425ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:24.224702Z","caller":"traceutil/trace.go:171","msg":"trace[162064638] transaction","detail":"{read_only:false; number_of_response:0; response_revision:500; }","duration":"1.222685125s","start":"2026-05-07T13:16:23.002004Z","end":"2026-05-07T13:16:24.224689Z","steps":["trace[162064638] 'process raft request' (duration: 1.221037177s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:24.224751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:23.001991Z","time spent":"1.222738627s","remote":"127.0.0.1:60578","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:26.509482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.410163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:26.509684Z","caller":"traceutil/trace.go:171","msg":"trace[69693520] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"191.716141ms","start":"2026-05-07T13:16:26.317955Z","end":"2026-05-07T13:16:26.509671Z","steps":["trace[69693520] 'process raft request' (duration: 27.04812ms)","trace[69693520] 'compare' (duration: 164.324071ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:26.509784Z","caller":"traceutil/trace.go:171","msg":"trace[412549753] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"191.476625ms","start":"2026-05-07T13:16:26.318296Z","end":"2026-05-07T13:16:26.509773Z","steps":["trace[412549753] 'process raft request' (duration: 191.341681ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:26.509906Z","caller":"traceutil/trace.go:171","msg":"trace[1607075098] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"186.496962ms","start":"2026-05-07T13:16:26.323403Z","end":"2026-05-07T13:16:26.5099Z","steps":["trace[1607075098] 'process raft request' (duration: 186.338098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:31.336415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.081838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:31.336531Z","caller":"traceutil/trace.go:171","msg":"trace[1672519609] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"809.751008ms","start":"2026-05-07T13:16:30.526761Z","end":"2026-05-07T13:16:31.336512Z","steps":["trace[1672519609] 'read index received' (duration: 261.405944ms)","trace[1672519609] 'applied index is now lower than readState.Index' (duration: 548.343994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:31.337192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.410887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:16:31.337283Z","caller":"traceutil/trace.go:171","msg":"trace[1564550685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"810.51313ms","start":"2026-05-07T13:16:30.526749Z","end":"2026-05-07T13:16:31.337262Z","steps":["trace[1564550685] 'agreement among raft nodes before linearized reading' (duration: 810.291144ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:31.336714Z","caller":"traceutil/trace.go:171","msg":"trace[1492685021] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"902.166652ms","start":"2026-05-07T13:16:30.434535Z","end":"2026-05-07T13:16:31.336702Z","steps":["trace[1492685021] 'process raft request' (duration: 353.716354ms)","trace[1492685021] 'compare' (duration: 547.931694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:31.337329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:30.526696Z","time spent":"810.624962ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:16:31.337423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:30.434509Z","time spent":"902.83621ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:31.83767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:32.33885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:32.839563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:32.938053Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.857791572s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:16:32.938509Z","caller":"traceutil/trace.go:171","msg":"trace[1718064029] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.858509402s","start":"2026-05-07T13:16:31.079976Z","end":"2026-05-07T13:16:32.938486Z","steps":["trace[1718064029] 'process raft request' (duration: 1.858361287s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:32.938636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:31.079954Z","time spent":"1.858609315s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:32.953326Z","caller":"traceutil/trace.go:171","msg":"trace[1873289722] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.609525149s","start":"2026-05-07T13:16:31.343786Z","end":"2026-05-07T13:16:32.953311Z","steps":["trace[1873289722] 'process raft request' (duration: 1.608793309s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:32.953509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:31.343763Z","time spent":"1.609640811s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:33.027098Z","caller":"traceutil/trace.go:171","msg":"trace[1259750161] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"1.690475991s","start":"2026-05-07T13:16:31.336556Z","end":"2026-05-07T13:16:33.027032Z","steps":["trace[1259750161] 'read index received' (duration: 1.601826231s)","trace[1259750161] 'applied index is now lower than readState.Index' (duration: 88.64744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:33.02726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.475129132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T13:16:33.027162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952192287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:16:33.027392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.009659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T13:16:33.027443Z","caller":"traceutil/trace.go:171","msg":"trace[286129561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"743.08557ms","start":"2026-05-07T13:16:32.284342Z","end":"2026-05-07T13:16:33.027428Z","steps":["trace[286129561] 'agreement among raft nodes before linearized reading' (duration: 743.015258ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:33.027391Z","caller":"traceutil/trace.go:171","msg":"trace[1494234146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"1.952504616s","start":"2026-05-07T13:16:31.074866Z","end":"2026-05-07T13:16:33.02737Z","steps":["trace[1494234146] 'agreement among raft nodes before linearized reading' (duration: 1.952214808s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:33.027503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678462895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:33.027594Z","caller":"traceutil/trace.go:171","msg":"trace[1166953487] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:595; }","duration":"1.67865356s","start":"2026-05-07T13:16:31.348923Z","end":"2026-05-07T13:16:33.027576Z","steps":["trace[1166953487] 'agreement among raft nodes before linearized reading' (duration: 1.678430554s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:33.027159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.263806781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6g26k\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-07T13:16:33.027605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:31.074816Z","time spent":"1.952768592s","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:16:33.027188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.470585537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:16:33.027678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:31.348899Z","time spent":"1.678762243s","remote":"127.0.0.1:60292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-07T13:16:33.027882Z","caller":"traceutil/trace.go:171","msg":"trace[439667477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"2.471317957s","start":"2026-05-07T13:16:30.556524Z","end":"2026-05-07T13:16:33.027842Z","steps":["trace[439667477] 'agreement among raft nodes before linearized reading' (duration: 2.470594538s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:33.027319Z","caller":"traceutil/trace.go:171","msg":"trace[1768987816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"2.475209104s","start":"2026-05-07T13:16:30.552084Z","end":"2026-05-07T13:16:33.027293Z","steps":["trace[1768987816] 'agreement among raft nodes before linearized reading' (duration: 2.475147012s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:33.02798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:30.556502Z","time spent":"2.471442701s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:33.028016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:30.552054Z","time spent":"2.475943073s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:33.02749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:32.284325Z","time spent":"743.155053ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T13:16:33.027746Z","caller":"traceutil/trace.go:171","msg":"trace[1812023956] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6g26k; range_end:; response_count:1; response_revision:595; }","duration":"2.264371755s","start":"2026-05-07T13:16:30.763291Z","end":"2026-05-07T13:16:33.027663Z","steps":["trace[1812023956] 'agreement among raft nodes before linearized reading' (duration: 2.263755569s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:33.02832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:30.763271Z","time spent":"2.265023313s","remote":"127.0.0.1:60236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-6g26k\" "} {"level":"warn","ts":"2026-05-07T13:16:34.572802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:35.073554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:35.574774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:36.073599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000785123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-07T13:16:36.073722Z","caller":"traceutil/trace.go:171","msg":"trace[1373760630] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001307336s","start":"2026-05-07T13:16:34.072394Z","end":"2026-05-07T13:16:36.073701Z","steps":["trace[1373760630] 'agreement among raft nodes before linearized reading' (duration: 2.000793512s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:36.073915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:34.072374Z","time spent":"2.001523302s","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/07 13:16:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T13:16:36.075392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:36.576111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:37.077093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:37.578126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:37.945076Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.517228814s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:16:37.946327Z","caller":"traceutil/trace.go:171","msg":"trace[2015994924] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"4.520406386s","start":"2026-05-07T13:16:33.425894Z","end":"2026-05-07T13:16:37.946301Z","steps":["trace[2015994924] 'process raft request' (duration: 4.519808561s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:37.946571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:33.42586Z","time spent":"4.520559271s","remote":"127.0.0.1:60136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:38.015165Z","caller":"traceutil/trace.go:171","msg":"trace[2050948254] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"3.942697962s","start":"2026-05-07T13:16:34.072446Z","end":"2026-05-07T13:16:38.015144Z","steps":["trace[2050948254] 'read index received' (duration: 3.872874171s)","trace[2050948254] 'applied index is now lower than readState.Index' (duration: 69.823051ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:38.015351Z","caller":"traceutil/trace.go:171","msg":"trace[838379177] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.083967874s","start":"2026-05-07T13:16:36.931356Z","end":"2026-05-07T13:16:38.015324Z","steps":["trace[838379177] 'process raft request' (duration: 1.083665106s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.015566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:36.931313Z","time spent":"1.084105137s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:38.073945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000328557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-07T13:16:38.074052Z","caller":"traceutil/trace.go:171","msg":"trace[115262067] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000512851s","start":"2026-05-07T13:16:36.073516Z","end":"2026-05-07T13:16:38.074029Z","steps":["trace[115262067] 'agreement among raft nodes before linearized reading' (duration: 2.000372897s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.074153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:36.073427Z","time spent":"2.000714146s","remote":"127.0.0.1:46360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/07 13:16:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T13:16:38.530248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.935333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:38.530599Z","caller":"traceutil/trace.go:171","msg":"trace[1827428225] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"521.44064ms","start":"2026-05-07T13:16:38.009064Z","end":"2026-05-07T13:16:38.530505Z","steps":["trace[1827428225] 'process raft request' (duration: 187.939673ms)","trace[1827428225] 'compare' (duration: 332.354137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:38.530704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:38.009043Z","time spent":"521.620215ms","remote":"127.0.0.1:60292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:38.530848Z","caller":"traceutil/trace.go:171","msg":"trace[2130552950] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"515.5464ms","start":"2026-05-07T13:16:38.015274Z","end":"2026-05-07T13:16:38.530821Z","steps":["trace[2130552950] 'read index received' (duration: 181.726344ms)","trace[2130552950] 'applied index is now lower than readState.Index' (duration: 333.817656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:38.531221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681348725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T13:16:38.531324Z","caller":"traceutil/trace.go:171","msg":"trace[923750000] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:602; }","duration":"1.681519639s","start":"2026-05-07T13:16:36.849791Z","end":"2026-05-07T13:16:38.53131Z","steps":["trace[923750000] 'agreement among raft nodes before linearized reading' (duration: 1.681202081s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.531334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.467789399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:16:38.531394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:36.849774Z","time spent":"1.681608111s","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.23\" "} {"level":"info","ts":"2026-05-07T13:16:38.531422Z","caller":"traceutil/trace.go:171","msg":"trace[1928586598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"3.467928953s","start":"2026-05-07T13:16:35.063475Z","end":"2026-05-07T13:16:38.531404Z","steps":["trace[1928586598] 'agreement among raft nodes before linearized reading' (duration: 3.467687837s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.531468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:35.063453Z","time spent":"3.468006875s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:38.531548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.420327157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T13:16:38.531649Z","caller":"traceutil/trace.go:171","msg":"trace[1509655458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"3.420484771s","start":"2026-05-07T13:16:35.111146Z","end":"2026-05-07T13:16:38.53163Z","steps":["trace[1509655458] 'agreement among raft nodes before linearized reading' (duration: 3.420132133s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.531703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.574569114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T13:16:38.531743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:35.111132Z","time spent":"3.420560933s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T13:16:38.531768Z","caller":"traceutil/trace.go:171","msg":"trace[1668829837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"3.574666376s","start":"2026-05-07T13:16:34.95709Z","end":"2026-05-07T13:16:38.531756Z","steps":["trace[1668829837] 'agreement among raft nodes before linearized reading' (duration: 3.574500301s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.532079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:34.95707Z","time spent":"3.574994064s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:16:38.531727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.421739194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T13:16:38.532216Z","caller":"traceutil/trace.go:171","msg":"trace[955544681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"3.422203825s","start":"2026-05-07T13:16:35.109978Z","end":"2026-05-07T13:16:38.532182Z","steps":["trace[955544681] 'agreement among raft nodes before linearized reading' (duration: 3.421680792s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.53232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:35.109906Z","time spent":"3.42237974s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T13:16:38.532498Z","caller":"traceutil/trace.go:171","msg":"trace[739298857] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"454.912827ms","start":"2026-05-07T13:16:38.077574Z","end":"2026-05-07T13:16:38.532487Z","steps":["trace[739298857] 'process raft request' (duration: 454.786674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.532572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.600207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:16:38.53258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:38.077547Z","time spent":"454.981639ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:38.532614Z","caller":"traceutil/trace.go:171","msg":"trace[466578953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"450.699499ms","start":"2026-05-07T13:16:38.081903Z","end":"2026-05-07T13:16:38.532603Z","steps":["trace[466578953] 'agreement among raft nodes before linearized reading' (duration: 450.585936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:38.532679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:38.081887Z","time spent":"450.771361ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:16:40.020405Z","caller":"traceutil/trace.go:171","msg":"trace[2098702397] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"198.384801ms","start":"2026-05-07T13:16:39.821992Z","end":"2026-05-07T13:16:40.020376Z","steps":["trace[2098702397] 'process raft request' (duration: 198.169465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:41.059878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323747,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:16:41.148607Z","caller":"traceutil/trace.go:171","msg":"trace[396142363] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"589.237758ms","start":"2026-05-07T13:16:40.559347Z","end":"2026-05-07T13:16:41.148585Z","steps":["trace[396142363] 'read index received' (duration: 565.05896ms)","trace[396142363] 'applied index is now lower than readState.Index' (duration: 24.177818ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:41.148728Z","caller":"traceutil/trace.go:171","msg":"trace[179582923] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"768.185732ms","start":"2026-05-07T13:16:40.380531Z","end":"2026-05-07T13:16:41.148717Z","steps":["trace[179582923] 'process raft request' (duration: 743.928641ms)","trace[179582923] 'compare' (duration: 23.453641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:41.148833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:40.38051Z","time spent":"768.254484ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:16:41.14898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.617268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T13:16:41.149033Z","caller":"traceutil/trace.go:171","msg":"trace[376218707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"589.70451ms","start":"2026-05-07T13:16:40.559313Z","end":"2026-05-07T13:16:41.149017Z","steps":["trace[376218707] 'agreement among raft nodes before linearized reading' (duration: 589.532016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:41.149053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.191507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6g26k\" ","response":"range_response_count:1 size:17520"} {"level":"warn","ts":"2026-05-07T13:16:41.149084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:40.559296Z","time spent":"589.778611ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:41.149108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.608678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:16:41.149125Z","caller":"traceutil/trace.go:171","msg":"trace[215314859] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6g26k; range_end:; response_count:1; response_revision:623; }","duration":"337.31073ms","start":"2026-05-07T13:16:40.811796Z","end":"2026-05-07T13:16:41.149106Z","steps":["trace[215314859] 'agreement among raft nodes before linearized reading' (duration: 337.017203ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:41.149177Z","caller":"traceutil/trace.go:171","msg":"trace[1176188860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"589.72363ms","start":"2026-05-07T13:16:40.559433Z","end":"2026-05-07T13:16:41.149157Z","steps":["trace[1176188860] 'agreement among raft nodes before linearized reading' (duration: 589.429223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:41.149178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:40.811775Z","time spent":"337.395373ms","remote":"127.0.0.1:60236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17542,"request content":"key:\"/registry/pods/kube-system/cilium-6g26k\" "} {"level":"warn","ts":"2026-05-07T13:16:41.149213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:40.559401Z","time spent":"589.804403ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:16:41.149237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.032704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-07T13:16:41.149292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.836483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T13:16:41.149322Z","caller":"traceutil/trace.go:171","msg":"trace[1044235747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"589.167418ms","start":"2026-05-07T13:16:40.560133Z","end":"2026-05-07T13:16:41.149301Z","steps":["trace[1044235747] 'agreement among raft nodes before linearized reading' (duration: 588.726046ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:41.149351Z","caller":"traceutil/trace.go:171","msg":"trace[573551112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"589.936417ms","start":"2026-05-07T13:16:40.559397Z","end":"2026-05-07T13:16:41.149334Z","steps":["trace[573551112] 'agreement among raft nodes before linearized reading' (duration: 589.411843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:41.149392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:40.559382Z","time spent":"590.005088ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:16:41.149383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:40.560114Z","time spent":"589.25221ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:42.844406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.179889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:42.844497Z","caller":"traceutil/trace.go:171","msg":"trace[1076763781] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"265.896221ms","start":"2026-05-07T13:16:42.578585Z","end":"2026-05-07T13:16:42.844482Z","steps":["trace[1076763781] 'process raft request' (duration: 104.530027ms)","trace[1076763781] 'compare' (duration: 161.060137ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:42.910067Z","caller":"traceutil/trace.go:171","msg":"trace[89461839] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"225.490758ms","start":"2026-05-07T13:16:42.684555Z","end":"2026-05-07T13:16:42.910046Z","steps":["trace[89461839] 'process raft request' (duration: 225.131429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.367146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.813453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:43.367331Z","caller":"traceutil/trace.go:171","msg":"trace[1767080760] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"524.789841ms","start":"2026-05-07T13:16:42.842519Z","end":"2026-05-07T13:16:43.367309Z","steps":["trace[1767080760] 'process raft request' (duration: 391.729102ms)","trace[1767080760] 'compare' (duration: 132.581088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:43.367399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:42.842505Z","time spent":"524.867734ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:43.36778Z","caller":"traceutil/trace.go:171","msg":"trace[166515115] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"485.506667ms","start":"2026-05-07T13:16:42.882262Z","end":"2026-05-07T13:16:43.367769Z","steps":["trace[166515115] 'process raft request' (duration: 485.455586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.367843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:42.882245Z","time spent":"485.571788ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:43.368047Z","caller":"traceutil/trace.go:171","msg":"trace[510574578] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"519.526562ms","start":"2026-05-07T13:16:42.848335Z","end":"2026-05-07T13:16:43.367862Z","steps":["trace[510574578] 'process raft request' (duration: 518.935608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.368234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:42.84832Z","time spent":"519.80486ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:43.861154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.407214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:43.861499Z","caller":"traceutil/trace.go:171","msg":"trace[2029558133] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"542.525519ms","start":"2026-05-07T13:16:43.318956Z","end":"2026-05-07T13:16:43.861481Z","steps":["trace[2029558133] 'process raft request' (duration: 542.473328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.861704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.318936Z","time spent":"542.615191ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:43.861739Z","caller":"traceutil/trace.go:171","msg":"trace[1997138686] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"936.205641ms","start":"2026-05-07T13:16:42.925511Z","end":"2026-05-07T13:16:43.861716Z","steps":["trace[1997138686] 'process raft request' (duration: 935.73876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.861857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:42.92548Z","time spent":"936.324023ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:43.861878Z","caller":"traceutil/trace.go:171","msg":"trace[1029944991] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"936.97319ms","start":"2026-05-07T13:16:42.924875Z","end":"2026-05-07T13:16:43.861848Z","steps":["trace[1029944991] 'process raft request' (duration: 649.797438ms)","trace[1029944991] 'compare' (duration: 286.29375ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:43.861979Z","caller":"traceutil/trace.go:171","msg":"trace[1762255783] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:687; }","duration":"787.447242ms","start":"2026-05-07T13:16:43.074273Z","end":"2026-05-07T13:16:43.86172Z","steps":["trace[1762255783] 'read index received' (duration: 160.021986ms)","trace[1762255783] 'applied index is now lower than readState.Index' (duration: 627.422906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:43.86203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:42.924853Z","time spent":"937.091543ms","remote":"127.0.0.1:60236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18048,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:43.863383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.535611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:43.863542Z","caller":"traceutil/trace.go:171","msg":"trace[1375912208] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"784.726885ms","start":"2026-05-07T13:16:43.078796Z","end":"2026-05-07T13:16:43.863523Z","steps":["trace[1375912208] 'agreement among raft nodes before linearized reading' (duration: 784.551681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.863582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.427759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T13:16:43.862161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.889212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:43.863698Z","caller":"traceutil/trace.go:171","msg":"trace[2137892583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"789.46356ms","start":"2026-05-07T13:16:43.074218Z","end":"2026-05-07T13:16:43.863682Z","steps":["trace[2137892583] 'agreement among raft nodes before linearized reading' (duration: 787.909193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.86363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.078778Z","time spent":"784.839199ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T13:16:43.863778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.305096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-07T13:16:43.863839Z","caller":"traceutil/trace.go:171","msg":"trace[367739458] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:667; }","duration":"184.364388ms","start":"2026-05-07T13:16:43.679463Z","end":"2026-05-07T13:16:43.863827Z","steps":["trace[367739458] 'agreement among raft nodes before linearized reading' (duration: 184.208664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.863975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.40001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-07T13:16:43.864014Z","caller":"traceutil/trace.go:171","msg":"trace[1320509118] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:667; }","duration":"384.472571ms","start":"2026-05-07T13:16:43.47953Z","end":"2026-05-07T13:16:43.864002Z","steps":["trace[1320509118] 'agreement among raft nodes before linearized reading' (duration: 384.317757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.864041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.47951Z","time spent":"384.524683ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-05-07T13:16:43.864134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.967128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:16:43.864166Z","caller":"traceutil/trace.go:171","msg":"trace[451529360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"701.015919ms","start":"2026-05-07T13:16:43.163141Z","end":"2026-05-07T13:16:43.864157Z","steps":["trace[451529360] 'agreement among raft nodes before linearized reading' (duration: 700.929837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.864193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.163134Z","time spent":"701.052799ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:43.864314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.745097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:43.864341Z","caller":"traceutil/trace.go:171","msg":"trace[1912418773] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"584.807599ms","start":"2026-05-07T13:16:43.279525Z","end":"2026-05-07T13:16:43.864332Z","steps":["trace[1912418773] 'agreement among raft nodes before linearized reading' (duration: 584.764398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.86438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.279503Z","time spent":"584.871601ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T13:16:43.864529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.828273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T13:16:43.864531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.51964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:16:43.864554Z","caller":"traceutil/trace.go:171","msg":"trace[1697525145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"700.875634ms","start":"2026-05-07T13:16:43.16367Z","end":"2026-05-07T13:16:43.864546Z","steps":["trace[1697525145] 'agreement among raft nodes before linearized reading' (duration: 700.801622ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:43.86457Z","caller":"traceutil/trace.go:171","msg":"trace[2108837885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"701.585332ms","start":"2026-05-07T13:16:43.162974Z","end":"2026-05-07T13:16:43.86456Z","steps":["trace[2108837885] 'agreement among raft nodes before linearized reading' (duration: 701.312246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.864584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.163632Z","time spent":"700.943746ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:16:43.864604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.162963Z","time spent":"701.632813ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:16:43.864734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.314119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T13:16:43.864757Z","caller":"traceutil/trace.go:171","msg":"trace[1583716735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"702.379392ms","start":"2026-05-07T13:16:43.162371Z","end":"2026-05-07T13:16:43.86475Z","steps":["trace[1583716735] 'agreement among raft nodes before linearized reading' (duration: 702.30684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.864785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.162349Z","time spent":"702.431243ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:43.863744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.074194Z","time spent":"789.535872ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:16:43.86365Z","caller":"traceutil/trace.go:171","msg":"trace[604058058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:667; }","duration":"575.534972ms","start":"2026-05-07T13:16:43.288102Z","end":"2026-05-07T13:16:43.863637Z","steps":["trace[604058058] 'agreement among raft nodes before linearized reading' (duration: 575.349128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:43.865365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:43.288083Z","time spent":"577.266265ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T13:16:44.046288Z","caller":"traceutil/trace.go:171","msg":"trace[30381744] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"173.405714ms","start":"2026-05-07T13:16:43.872858Z","end":"2026-05-07T13:16:44.046264Z","steps":["trace[30381744] 'process raft request' (duration: 171.432646ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.046377Z","caller":"traceutil/trace.go:171","msg":"trace[400382162] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"173.413563ms","start":"2026-05-07T13:16:43.872918Z","end":"2026-05-07T13:16:44.046331Z","steps":["trace[400382162] 'process raft request' (duration: 173.174118ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.046752Z","caller":"traceutil/trace.go:171","msg":"trace[2030169718] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"173.773592ms","start":"2026-05-07T13:16:43.872957Z","end":"2026-05-07T13:16:44.046731Z","steps":["trace[2030169718] 'process raft request' (duration: 173.237849ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.047174Z","caller":"traceutil/trace.go:171","msg":"trace[1696590204] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"173.784733ms","start":"2026-05-07T13:16:43.873359Z","end":"2026-05-07T13:16:44.047144Z","steps":["trace[1696590204] 'process raft request' (duration: 172.936712ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.047628Z","caller":"traceutil/trace.go:171","msg":"trace[2124489562] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:693; }","duration":"169.739774ms","start":"2026-05-07T13:16:43.877871Z","end":"2026-05-07T13:16:44.047611Z","steps":["trace[2124489562] 'read index received' (duration: 45.436687ms)","trace[2124489562] 'applied index is now lower than readState.Index' (duration: 124.302087ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:44.04765Z","caller":"traceutil/trace.go:171","msg":"trace[1393873291] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"169.687512ms","start":"2026-05-07T13:16:43.877947Z","end":"2026-05-07T13:16:44.047634Z","steps":["trace[1393873291] 'process raft request' (duration: 169.406106ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.047741Z","caller":"traceutil/trace.go:171","msg":"trace[1358513375] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"171.136447ms","start":"2026-05-07T13:16:43.876587Z","end":"2026-05-07T13:16:44.047723Z","steps":["trace[1358513375] 'process raft request' (duration: 170.444381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:44.047825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.928388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-07T13:16:44.047884Z","caller":"traceutil/trace.go:171","msg":"trace[1616950354] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:674; }","duration":"170.01421ms","start":"2026-05-07T13:16:43.877852Z","end":"2026-05-07T13:16:44.047867Z","steps":["trace[1616950354] 'agreement among raft nodes before linearized reading' (duration: 169.827175ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.261929Z","caller":"traceutil/trace.go:171","msg":"trace[103518974] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"185.506009ms","start":"2026-05-07T13:16:44.076402Z","end":"2026-05-07T13:16:44.261908Z","steps":["trace[103518974] 'read index received' (duration: 175.63411ms)","trace[103518974] 'applied index is now lower than readState.Index' (duration: 9.870999ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:44.26207Z","caller":"traceutil/trace.go:171","msg":"trace[400966650] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"201.121294ms","start":"2026-05-07T13:16:44.060933Z","end":"2026-05-07T13:16:44.262054Z","steps":["trace[400966650] 'process raft request' (duration: 191.179384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:44.262082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.660122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:44.26213Z","caller":"traceutil/trace.go:171","msg":"trace[648076934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"185.749274ms","start":"2026-05-07T13:16:44.076368Z","end":"2026-05-07T13:16:44.262117Z","steps":["trace[648076934] 'agreement among raft nodes before linearized reading' (duration: 185.656751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:44.262248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.611232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-07T13:16:44.262348Z","caller":"traceutil/trace.go:171","msg":"trace[648325662] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"183.775077ms","start":"2026-05-07T13:16:44.078549Z","end":"2026-05-07T13:16:44.262324Z","steps":["trace[648325662] 'agreement among raft nodes before linearized reading' (duration: 183.4844ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:44.601323Z","caller":"traceutil/trace.go:171","msg":"trace[1957214140] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"122.444045ms","start":"2026-05-07T13:16:44.478857Z","end":"2026-05-07T13:16:44.601301Z","steps":["trace[1957214140] 'read index received' (duration: 122.275481ms)","trace[1957214140] 'applied index is now lower than readState.Index' (duration: 167.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:44.601544Z","caller":"traceutil/trace.go:171","msg":"trace[1362838759] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"142.332935ms","start":"2026-05-07T13:16:44.459192Z","end":"2026-05-07T13:16:44.601525Z","steps":["trace[1362838759] 'process raft request' (duration: 142.006698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:44.601657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.760513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-07T13:16:44.601698Z","caller":"traceutil/trace.go:171","msg":"trace[1512588256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:677; }","duration":"122.865615ms","start":"2026-05-07T13:16:44.478822Z","end":"2026-05-07T13:16:44.601687Z","steps":["trace[1512588256] 'agreement among raft nodes before linearized reading' (duration: 122.568058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:45.348148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.196665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:45.348244Z","caller":"traceutil/trace.go:171","msg":"trace[1001409424] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"468.285541ms","start":"2026-05-07T13:16:44.879939Z","end":"2026-05-07T13:16:45.348225Z","steps":["trace[1001409424] 'read index received' (duration: 74.300634ms)","trace[1001409424] 'applied index is now lower than readState.Index' (duration: 393.983857ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:45.348342Z","caller":"traceutil/trace.go:171","msg":"trace[925117624] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"482.851263ms","start":"2026-05-07T13:16:44.86544Z","end":"2026-05-07T13:16:45.348291Z","steps":["trace[925117624] 'process raft request' (duration: 329.440963ms)","trace[925117624] 'compare' (duration: 153.099242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:45.348438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:44.865434Z","time spent":"482.937235ms","remote":"127.0.0.1:60236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:45.348819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.859936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-07T13:16:45.349495Z","caller":"traceutil/trace.go:171","msg":"trace[2121987728] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:679; }","duration":"469.572023ms","start":"2026-05-07T13:16:44.879902Z","end":"2026-05-07T13:16:45.349474Z","steps":["trace[2121987728] 'agreement among raft nodes before linearized reading' (duration: 468.713392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:45.349556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:44.879887Z","time spent":"469.653055ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-07T13:16:45.634088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.886595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:16:45.634213Z","caller":"traceutil/trace.go:171","msg":"trace[805775452] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"526.898998ms","start":"2026-05-07T13:16:45.1073Z","end":"2026-05-07T13:16:45.634199Z","steps":["trace[805775452] 'process raft request' (duration: 366.858389ms)","trace[805775452] 'compare' (duration: 159.726942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:45.634258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:45.10727Z","time spent":"526.97511ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:45.649555Z","caller":"traceutil/trace.go:171","msg":"trace[89382182] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"301.236339ms","start":"2026-05-07T13:16:45.348268Z","end":"2026-05-07T13:16:45.649504Z","steps":["trace[89382182] 'read index received' (duration: 125.951784ms)","trace[89382182] 'applied index is now lower than readState.Index' (duration: 175.283165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:16:45.64996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.779092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:45.649981Z","caller":"traceutil/trace.go:171","msg":"trace[1854288740] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"340.123608ms","start":"2026-05-07T13:16:45.309824Z","end":"2026-05-07T13:16:45.649948Z","steps":["trace[1854288740] 'process raft request' (duration: 339.300669ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:45.650023Z","caller":"traceutil/trace.go:171","msg":"trace[1021452950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"576.885794ms","start":"2026-05-07T13:16:45.073123Z","end":"2026-05-07T13:16:45.650009Z","steps":["trace[1021452950] 'agreement among raft nodes before linearized reading' (duration: 576.613598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:45.650092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.719065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-05-07T13:16:45.650125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:45.309792Z","time spent":"340.265522ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:45.650173Z","caller":"traceutil/trace.go:171","msg":"trace[1403022574] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"170.833728ms","start":"2026-05-07T13:16:45.479309Z","end":"2026-05-07T13:16:45.650143Z","steps":["trace[1403022574] 'agreement among raft nodes before linearized reading' (duration: 170.577242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:45.650167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:45.073106Z","time spent":"577.003897ms","remote":"127.0.0.1:46360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:16:46.427255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042323839,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:16:46.654622Z","caller":"traceutil/trace.go:171","msg":"trace[1392780935] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"752.400693ms","start":"2026-05-07T13:16:45.902192Z","end":"2026-05-07T13:16:46.654593Z","steps":["trace[1392780935] 'process raft request' (duration: 752.039844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.654805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:45.902171Z","time spent":"752.538016ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:46.744011Z","caller":"traceutil/trace.go:171","msg":"trace[1525308318] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:709; }","duration":"817.306257ms","start":"2026-05-07T13:16:45.926683Z","end":"2026-05-07T13:16:46.743989Z","steps":["trace[1525308318] 'read index received' (duration: 727.459375ms)","trace[1525308318] 'applied index is now lower than readState.Index' (duration: 89.845972ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:46.744061Z","caller":"traceutil/trace.go:171","msg":"trace[2112527521] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"834.768925ms","start":"2026-05-07T13:16:45.909268Z","end":"2026-05-07T13:16:46.744037Z","steps":["trace[2112527521] 'process raft request' (duration: 834.463078ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:46.744105Z","caller":"traceutil/trace.go:171","msg":"trace[1750261467] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"672.807813ms","start":"2026-05-07T13:16:46.071267Z","end":"2026-05-07T13:16:46.744075Z","steps":["trace[1750261467] 'process raft request' (duration: 672.67681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.744203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:45.909245Z","time spent":"834.871727ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:46.744246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.557344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T13:16:46.744251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.071249Z","time spent":"672.941367ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:16:46.744284Z","caller":"traceutil/trace.go:171","msg":"trace[618056622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"817.619725ms","start":"2026-05-07T13:16:45.926652Z","end":"2026-05-07T13:16:46.744272Z","steps":["trace[618056622] 'agreement among raft nodes before linearized reading' (duration: 817.460821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.744317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:45.926639Z","time spent":"817.669795ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:16:46.744615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.559304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"warn","ts":"2026-05-07T13:16:46.744621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.520114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T13:16:46.744648Z","caller":"traceutil/trace.go:171","msg":"trace[36691776] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:686; }","duration":"564.619606ms","start":"2026-05-07T13:16:46.180017Z","end":"2026-05-07T13:16:46.744637Z","steps":["trace[36691776] 'agreement among raft nodes before linearized reading' (duration: 564.492573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.74467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.18Z","time spent":"564.665067ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188265,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-07T13:16:46.744665Z","caller":"traceutil/trace.go:171","msg":"trace[2018557375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"693.598886ms","start":"2026-05-07T13:16:46.051054Z","end":"2026-05-07T13:16:46.744653Z","steps":["trace[2018557375] 'agreement among raft nodes before linearized reading' (duration: 693.462933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.744701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.051032Z","time spent":"693.660117ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:16:46.74489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.989661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-07T13:16:46.744931Z","caller":"traceutil/trace.go:171","msg":"trace[1119461883] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:686; }","duration":"583.049283ms","start":"2026-05-07T13:16:46.161867Z","end":"2026-05-07T13:16:46.744917Z","steps":["trace[1119461883] 'agreement among raft nodes before linearized reading' (duration: 582.915059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.744965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.161851Z","time spent":"583.105794ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188517,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T13:16:46.744972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.798617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:16:46.74503Z","caller":"traceutil/trace.go:171","msg":"trace[1148377792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"673.886959ms","start":"2026-05-07T13:16:46.071128Z","end":"2026-05-07T13:16:46.745015Z","steps":["trace[1148377792] 'agreement among raft nodes before linearized reading' (duration: 673.801807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.745041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.716891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T13:16:46.745063Z","caller":"traceutil/trace.go:171","msg":"trace[156593246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"691.777233ms","start":"2026-05-07T13:16:46.05328Z","end":"2026-05-07T13:16:46.745057Z","steps":["trace[156593246] 'agreement among raft nodes before linearized reading' (duration: 691.728642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.74507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.071112Z","time spent":"673.94757ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:16:46.74508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.053261Z","time spent":"691.815643ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:46.745171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.915665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T13:16:46.744989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.140057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T13:16:46.745212Z","caller":"traceutil/trace.go:171","msg":"trace[81025647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"691.978217ms","start":"2026-05-07T13:16:46.053222Z","end":"2026-05-07T13:16:46.7452Z","steps":["trace[81025647] 'agreement among raft nodes before linearized reading' (duration: 691.869865ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:46.745223Z","caller":"traceutil/trace.go:171","msg":"trace[1738437214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"691.411374ms","start":"2026-05-07T13:16:46.053805Z","end":"2026-05-07T13:16:46.745217Z","steps":["trace[1738437214] 'agreement among raft nodes before linearized reading' (duration: 691.110607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.745245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.053785Z","time spent":"691.454535ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:16:46.745248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:46.053213Z","time spent":"692.026258ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T13:16:46.961906Z","caller":"traceutil/trace.go:171","msg":"trace[2105274319] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"111.31337ms","start":"2026-05-07T13:16:46.850568Z","end":"2026-05-07T13:16:46.961881Z","steps":["trace[2105274319] 'read index received' (duration: 111.025623ms)","trace[2105274319] 'applied index is now lower than readState.Index' (duration: 286.807µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:46.962079Z","caller":"traceutil/trace.go:171","msg":"trace[1108838298] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"139.932529ms","start":"2026-05-07T13:16:46.822126Z","end":"2026-05-07T13:16:46.962058Z","steps":["trace[1108838298] 'process raft request' (duration: 139.54927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:46.962267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.68976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T13:16:46.962311Z","caller":"traceutil/trace.go:171","msg":"trace[612725864] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:692; }","duration":"111.785973ms","start":"2026-05-07T13:16:46.850513Z","end":"2026-05-07T13:16:46.962299Z","steps":["trace[612725864] 'agreement among raft nodes before linearized reading' (duration: 111.485685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:58.487328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042324100,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:58.987579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042324100,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:16:59.174164Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.233915557s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:16:59.174772Z","caller":"traceutil/trace.go:171","msg":"trace[157150868] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.234585871s","start":"2026-05-07T13:16:57.940162Z","end":"2026-05-07T13:16:59.174748Z","steps":["trace[157150868] 'process raft request' (duration: 1.234227533s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.175037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:57.940144Z","time spent":"1.234684823s","remote":"127.0.0.1:60292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:16:59.175752Z","caller":"traceutil/trace.go:171","msg":"trace[1665813227] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"1.188869729s","start":"2026-05-07T13:16:57.98687Z","end":"2026-05-07T13:16:59.175739Z","steps":["trace[1665813227] 'read index received' (duration: 1.187603292s)","trace[1665813227] 'applied index is now lower than readState.Index' (duration: 1.265667ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:16:59.175819Z","caller":"traceutil/trace.go:171","msg":"trace[188645185] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"380.900945ms","start":"2026-05-07T13:16:58.79491Z","end":"2026-05-07T13:16:59.175811Z","steps":["trace[188645185] 'process raft request' (duration: 380.776992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.175871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:58.794891Z","time spent":"380.949357ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:16:59.176038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127595348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:16:59.176088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101771882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:16:59.17613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.952387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:16:59.17616Z","caller":"traceutil/trace.go:171","msg":"trace[913213418] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:769; }","duration":"1.127777222s","start":"2026-05-07T13:16:58.048355Z","end":"2026-05-07T13:16:59.176132Z","steps":["trace[913213418] 'agreement among raft nodes before linearized reading' (duration: 1.127499416s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:16:59.176207Z","caller":"traceutil/trace.go:171","msg":"trace[899196829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"236.07267ms","start":"2026-05-07T13:16:58.940117Z","end":"2026-05-07T13:16:59.17619Z","steps":["trace[899196829] 'agreement among raft nodes before linearized reading' (duration: 235.795743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.176232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:58.048299Z","time spent":"1.127915976s","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:16:59.176169Z","caller":"traceutil/trace.go:171","msg":"trace[1825476518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"1.101859016s","start":"2026-05-07T13:16:58.074283Z","end":"2026-05-07T13:16:59.176142Z","steps":["trace[1825476518] 'agreement among raft nodes before linearized reading' (duration: 1.101730702s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.176346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:58.074275Z","time spent":"1.10205321s","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:16:59.176425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.066521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T13:16:59.176469Z","caller":"traceutil/trace.go:171","msg":"trace[1093001677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"236.145552ms","start":"2026-05-07T13:16:58.940313Z","end":"2026-05-07T13:16:59.176459Z","steps":["trace[1093001677] 'agreement among raft nodes before linearized reading' (duration: 236.030149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.176137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.094405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T13:16:59.176564Z","caller":"traceutil/trace.go:171","msg":"trace[29795891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"272.548885ms","start":"2026-05-07T13:16:58.903999Z","end":"2026-05-07T13:16:59.176548Z","steps":["trace[29795891] 'agreement among raft nodes before linearized reading' (duration: 271.974612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.17658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.645536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:16:59.176663Z","caller":"traceutil/trace.go:171","msg":"trace[758715476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"258.756088ms","start":"2026-05-07T13:16:58.91789Z","end":"2026-05-07T13:16:59.176646Z","steps":["trace[758715476] 'agreement among raft nodes before linearized reading' (duration: 258.37218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.176718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18984788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4mk2s\" ","response":"range_response_count:1 size:5046"} {"level":"info","ts":"2026-05-07T13:16:59.176745Z","caller":"traceutil/trace.go:171","msg":"trace[15679876] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-4mk2s; range_end:; response_count:1; response_revision:769; }","duration":"1.189909442s","start":"2026-05-07T13:16:57.986827Z","end":"2026-05-07T13:16:59.176736Z","steps":["trace[15679876] 'agreement among raft nodes before linearized reading' (duration: 1.18983414s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:16:59.176779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:16:57.986801Z","time spent":"1.189971633s","remote":"127.0.0.1:60236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5068,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4mk2s\" "} {"level":"warn","ts":"2026-05-07T13:16:59.177207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.078569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T13:16:59.177278Z","caller":"traceutil/trace.go:171","msg":"trace[1195282319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"235.884235ms","start":"2026-05-07T13:16:58.941373Z","end":"2026-05-07T13:16:59.177257Z","steps":["trace[1195282319] 'agreement among raft nodes before linearized reading' (duration: 234.636338ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:00.070917Z","caller":"traceutil/trace.go:171","msg":"trace[1785772236] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"130.138386ms","start":"2026-05-07T13:16:59.940756Z","end":"2026-05-07T13:17:00.070894Z","steps":["trace[1785772236] 'process raft request' (duration: 129.971323ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:00.329341Z","caller":"traceutil/trace.go:171","msg":"trace[1706745605] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"145.338849ms","start":"2026-05-07T13:17:00.183971Z","end":"2026-05-07T13:17:00.32931Z","steps":["trace[1706745605] 'process raft request' (duration: 145.085113ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:06.276942Z","caller":"traceutil/trace.go:171","msg":"trace[628202578] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"111.026507ms","start":"2026-05-07T13:17:06.165889Z","end":"2026-05-07T13:17:06.276916Z","steps":["trace[628202578] 'process raft request' (duration: 110.887095ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:06.805545Z","caller":"traceutil/trace.go:171","msg":"trace[1613249451] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"271.234733ms","start":"2026-05-07T13:17:06.534278Z","end":"2026-05-07T13:17:06.805513Z","steps":["trace[1613249451] 'process raft request' (duration: 271.045119ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:06.967407Z","caller":"traceutil/trace.go:171","msg":"trace[294168285] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"154.781496ms","start":"2026-05-07T13:17:06.812596Z","end":"2026-05-07T13:17:06.967378Z","steps":["trace[294168285] 'process raft request' (duration: 154.569921ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.022499Z","caller":"traceutil/trace.go:171","msg":"trace[419630789] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"207.902508ms","start":"2026-05-07T13:17:06.814566Z","end":"2026-05-07T13:17:07.022468Z","steps":["trace[419630789] 'process raft request' (duration: 207.649243ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.022642Z","caller":"traceutil/trace.go:171","msg":"trace[1596717325] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:876; }","duration":"170.52809ms","start":"2026-05-07T13:17:06.852085Z","end":"2026-05-07T13:17:07.022613Z","steps":["trace[1596717325] 'read index received' (duration: 115.148332ms)","trace[1596717325] 'applied index is now lower than readState.Index' (duration: 55.377998ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:07.022831Z","caller":"traceutil/trace.go:171","msg":"trace[790653442] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"206.978989ms","start":"2026-05-07T13:17:06.815825Z","end":"2026-05-07T13:17:07.022804Z","steps":["trace[790653442] 'process raft request' (duration: 206.5741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:07.023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.898198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T13:17:07.023054Z","caller":"traceutil/trace.go:171","msg":"trace[348510692] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:847; }","duration":"171.0075ms","start":"2026-05-07T13:17:06.852033Z","end":"2026-05-07T13:17:07.02304Z","steps":["trace[348510692] 'agreement among raft nodes before linearized reading' (duration: 170.762925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:07.258073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.599831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:17:07.258336Z","caller":"traceutil/trace.go:171","msg":"trace[1215370538] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"227.454014ms","start":"2026-05-07T13:17:07.030856Z","end":"2026-05-07T13:17:07.25831Z","steps":["trace[1215370538] 'process raft request' (duration: 50.472625ms)","trace[1215370538] 'compare' (duration: 176.420037ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:07.303418Z","caller":"traceutil/trace.go:171","msg":"trace[1957650443] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"267.623952ms","start":"2026-05-07T13:17:07.03576Z","end":"2026-05-07T13:17:07.303384Z","steps":["trace[1957650443] 'process raft request' (duration: 267.405997ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.303716Z","caller":"traceutil/trace.go:171","msg":"trace[1132113957] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"267.5497ms","start":"2026-05-07T13:17:07.036156Z","end":"2026-05-07T13:17:07.303706Z","steps":["trace[1132113957] 'process raft request' (duration: 267.179773ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.425594Z","caller":"traceutil/trace.go:171","msg":"trace[1329719279] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"115.136835ms","start":"2026-05-07T13:17:07.310395Z","end":"2026-05-07T13:17:07.425532Z","steps":["trace[1329719279] 'process raft request' (duration: 114.347469ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.432242Z","caller":"traceutil/trace.go:171","msg":"trace[1168211716] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"120.384041ms","start":"2026-05-07T13:17:07.311828Z","end":"2026-05-07T13:17:07.432212Z","steps":["trace[1168211716] 'process raft request' (duration: 120.282979ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.432393Z","caller":"traceutil/trace.go:171","msg":"trace[1234611075] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"117.259457ms","start":"2026-05-07T13:17:07.315123Z","end":"2026-05-07T13:17:07.432383Z","steps":["trace[1234611075] 'process raft request' (duration: 117.042383ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.432222Z","caller":"traceutil/trace.go:171","msg":"trace[316890169] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"120.790699ms","start":"2026-05-07T13:17:07.311415Z","end":"2026-05-07T13:17:07.432205Z","steps":["trace[316890169] 'process raft request' (duration: 120.515413ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.432568Z","caller":"traceutil/trace.go:171","msg":"trace[73658008] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"116.123996ms","start":"2026-05-07T13:17:07.31643Z","end":"2026-05-07T13:17:07.432554Z","steps":["trace[73658008] 'process raft request' (duration: 115.83169ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:07.432713Z","caller":"traceutil/trace.go:171","msg":"trace[1944538343] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"113.001723ms","start":"2026-05-07T13:17:07.319615Z","end":"2026-05-07T13:17:07.432617Z","steps":["trace[1944538343] 'process raft request' (duration: 112.775458ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:08.219716Z","caller":"traceutil/trace.go:171","msg":"trace[228881918] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"147.317482ms","start":"2026-05-07T13:17:08.072362Z","end":"2026-05-07T13:17:08.21968Z","steps":["trace[228881918] 'read index received' (duration: 144.499975ms)","trace[228881918] 'applied index is now lower than readState.Index' (duration: 2.816266ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:08.21977Z","caller":"traceutil/trace.go:171","msg":"trace[1656982345] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"149.662958ms","start":"2026-05-07T13:17:08.07007Z","end":"2026-05-07T13:17:08.219733Z","steps":["trace[1656982345] 'process raft request' (duration: 146.77645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:08.219886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.491935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:17:08.220508Z","caller":"traceutil/trace.go:171","msg":"trace[2015278401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"148.102107ms","start":"2026-05-07T13:17:08.072357Z","end":"2026-05-07T13:17:08.220459Z","steps":["trace[2015278401] 'agreement among raft nodes before linearized reading' (duration: 147.454174ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:15.255069Z","caller":"traceutil/trace.go:171","msg":"trace[2123451102] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"110.149854ms","start":"2026-05-07T13:17:15.144865Z","end":"2026-05-07T13:17:15.255015Z","steps":["trace[2123451102] 'read index received' (duration: 71.083961ms)","trace[2123451102] 'applied index is now lower than readState.Index' (duration: 39.064683ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:15.255126Z","caller":"traceutil/trace.go:171","msg":"trace[1848530011] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"157.144888ms","start":"2026-05-07T13:17:15.097947Z","end":"2026-05-07T13:17:15.255092Z","steps":["trace[1848530011] 'process raft request' (duration: 118.118756ms)","trace[1848530011] 'compare' (duration: 38.805668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:17:15.255258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.348687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:17:15.255314Z","caller":"traceutil/trace.go:171","msg":"trace[1905322565] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:954; }","duration":"110.48809ms","start":"2026-05-07T13:17:15.144812Z","end":"2026-05-07T13:17:15.2553Z","steps":["trace[1905322565] 'agreement among raft nodes before linearized reading' (duration: 110.357777ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:15.417319Z","caller":"traceutil/trace.go:171","msg":"trace[926463127] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"155.353983ms","start":"2026-05-07T13:17:15.261931Z","end":"2026-05-07T13:17:15.417285Z","steps":["trace[926463127] 'process raft request' (duration: 120.083932ms)","trace[926463127] 'compare' (duration: 35.147269ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:15.847963Z","caller":"traceutil/trace.go:171","msg":"trace[564033074] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"354.332297ms","start":"2026-05-07T13:17:15.493603Z","end":"2026-05-07T13:17:15.847935Z","steps":["trace[564033074] 'process raft request' (duration: 354.185615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:15.84812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:15.493578Z","time spent":"354.46342ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:15.849055Z","caller":"traceutil/trace.go:171","msg":"trace[665880254] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"352.823419ms","start":"2026-05-07T13:17:15.496218Z","end":"2026-05-07T13:17:15.849041Z","steps":["trace[665880254] 'process raft request' (duration: 352.788098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:15.849144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:15.496209Z","time spent":"352.89452ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:15.849143Z","caller":"traceutil/trace.go:171","msg":"trace[1623122963] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"353.885888ms","start":"2026-05-07T13:17:15.495239Z","end":"2026-05-07T13:17:15.849125Z","steps":["trace[1623122963] 'process raft request' (duration: 353.667614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:15.84923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:15.495217Z","time spent":"353.97102ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:15.849331Z","caller":"traceutil/trace.go:171","msg":"trace[1841647502] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"353.758516ms","start":"2026-05-07T13:17:15.495558Z","end":"2026-05-07T13:17:15.849317Z","steps":["trace[1841647502] 'process raft request' (duration: 353.41426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:15.849394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:15.495545Z","time spent":"353.817927ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:17.628769Z","caller":"traceutil/trace.go:171","msg":"trace[694922514] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"130.297655ms","start":"2026-05-07T13:17:17.498441Z","end":"2026-05-07T13:17:17.628739Z","steps":["trace[694922514] 'process raft request' (duration: 130.133441ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:18.062299Z","caller":"traceutil/trace.go:171","msg":"trace[1528658740] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"206.246372ms","start":"2026-05-07T13:17:17.856028Z","end":"2026-05-07T13:17:18.062274Z","steps":["trace[1528658740] 'process raft request' (duration: 206.037678ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:18.07307Z","caller":"traceutil/trace.go:171","msg":"trace[642291939] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1000; }","duration":"169.529652ms","start":"2026-05-07T13:17:17.903521Z","end":"2026-05-07T13:17:18.07305Z","steps":["trace[642291939] 'read index received' (duration: 158.834732ms)","trace[642291939] 'applied index is now lower than readState.Index' (duration: 10.69431ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:18.073305Z","caller":"traceutil/trace.go:171","msg":"trace[1495720436] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"213.70439ms","start":"2026-05-07T13:17:17.859591Z","end":"2026-05-07T13:17:18.073295Z","steps":["trace[1495720436] 'process raft request' (duration: 213.436515ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:18.073271Z","caller":"traceutil/trace.go:171","msg":"trace[2082250080] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"213.756341ms","start":"2026-05-07T13:17:17.859395Z","end":"2026-05-07T13:17:18.073152Z","steps":["trace[2082250080] 'process raft request' (duration: 213.599739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:18.073652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.112152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:17:18.073789Z","caller":"traceutil/trace.go:171","msg":"trace[494964979] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:967; }","duration":"170.310225ms","start":"2026-05-07T13:17:17.903434Z","end":"2026-05-07T13:17:18.073744Z","steps":["trace[494964979] 'agreement among raft nodes before linearized reading' (duration: 170.136542ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:18.073089Z","caller":"traceutil/trace.go:171","msg":"trace[1068353262] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"214.179189ms","start":"2026-05-07T13:17:17.858888Z","end":"2026-05-07T13:17:18.073067Z","steps":["trace[1068353262] 'process raft request' (duration: 214.009756ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:19.777992Z","caller":"traceutil/trace.go:171","msg":"trace[529268242] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"144.837203ms","start":"2026-05-07T13:17:19.633126Z","end":"2026-05-07T13:17:19.777963Z","steps":["trace[529268242] 'read index received' (duration: 144.725621ms)","trace[529268242] 'applied index is now lower than readState.Index' (duration: 110.622µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:19.778046Z","caller":"traceutil/trace.go:171","msg":"trace[1526714103] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"160.201156ms","start":"2026-05-07T13:17:19.617812Z","end":"2026-05-07T13:17:19.778013Z","steps":["trace[1526714103] 'process raft request' (duration: 159.992553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:19.778219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.054766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:17:19.778257Z","caller":"traceutil/trace.go:171","msg":"trace[1993867474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"145.174299ms","start":"2026-05-07T13:17:19.633072Z","end":"2026-05-07T13:17:19.778247Z","steps":["trace[1993867474] 'agreement among raft nodes before linearized reading' (duration: 144.991125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:22.729855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.421271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:17:22.730231Z","caller":"traceutil/trace.go:171","msg":"trace[1703749419] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"590.649278ms","start":"2026-05-07T13:17:22.13956Z","end":"2026-05-07T13:17:22.730209Z","steps":["trace[1703749419] 'process raft request' (duration: 590.570067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:22.730331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:22.139549Z","time spent":"590.73598ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:22.730401Z","caller":"traceutil/trace.go:171","msg":"trace[1670967221] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"591.094186ms","start":"2026-05-07T13:17:22.139296Z","end":"2026-05-07T13:17:22.73039Z","steps":["trace[1670967221] 'process raft request' (duration: 590.696158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:22.730474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:22.139266Z","time spent":"591.176258ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:22.730469Z","caller":"traceutil/trace.go:171","msg":"trace[1063031533] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"590.939474ms","start":"2026-05-07T13:17:22.139249Z","end":"2026-05-07T13:17:22.730189Z","steps":["trace[1063031533] 'process raft request' (duration: 483.082084ms)","trace[1063031533] 'compare' (duration: 106.952973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:17:22.730697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:22.139218Z","time spent":"591.381562ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:17:22.954488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.284524ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-07T13:17:22.95529Z","caller":"traceutil/trace.go:171","msg":"trace[1649900650] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"458.246534ms","start":"2026-05-07T13:17:22.497023Z","end":"2026-05-07T13:17:22.955269Z","steps":["trace[1649900650] 'process raft request' (duration: 457.606062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:22.95561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:22.49699Z","time spent":"458.365036ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:38.95644Z","caller":"traceutil/trace.go:171","msg":"trace[329262362] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"148.978374ms","start":"2026-05-07T13:17:38.807434Z","end":"2026-05-07T13:17:38.956412Z","steps":["trace[329262362] 'read index received' (duration: 148.850222ms)","trace[329262362] 'applied index is now lower than readState.Index' (duration: 127.212µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:38.956556Z","caller":"traceutil/trace.go:171","msg":"trace[24477627] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"152.9856ms","start":"2026-05-07T13:17:38.803528Z","end":"2026-05-07T13:17:38.956514Z","steps":["trace[24477627] 'process raft request' (duration: 152.741386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:38.956706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.240468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T13:17:38.956746Z","caller":"traceutil/trace.go:171","msg":"trace[1653378129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"149.3232ms","start":"2026-05-07T13:17:38.807409Z","end":"2026-05-07T13:17:38.956732Z","steps":["trace[1653378129] 'agreement among raft nodes before linearized reading' (duration: 149.160677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:38.957023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.297393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T13:17:38.957088Z","caller":"traceutil/trace.go:171","msg":"trace[867833422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"148.403984ms","start":"2026-05-07T13:17:38.808672Z","end":"2026-05-07T13:17:38.957076Z","steps":["trace[867833422] 'agreement among raft nodes before linearized reading' (duration: 148.1284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:39.412735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.703053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:17:39.413181Z","caller":"traceutil/trace.go:171","msg":"trace[1386441814] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1076; }","duration":"369.723303ms","start":"2026-05-07T13:17:39.043409Z","end":"2026-05-07T13:17:39.413132Z","steps":["trace[1386441814] 'read index received' (duration: 247.553052ms)","trace[1386441814] 'applied index is now lower than readState.Index' (duration: 122.169221ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:39.413209Z","caller":"traceutil/trace.go:171","msg":"trace[420098705] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"451.524222ms","start":"2026-05-07T13:17:38.96158Z","end":"2026-05-07T13:17:39.413104Z","steps":["trace[420098705] 'process raft request' (duration: 329.366672ms)","trace[420098705] 'compare' (duration: 121.5168ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:17:39.413255Z","caller":"traceutil/trace.go:171","msg":"trace[1511328069] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"451.299868ms","start":"2026-05-07T13:17:38.961913Z","end":"2026-05-07T13:17:39.413212Z","steps":["trace[1511328069] 'process raft request' (duration: 451.120575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:39.41341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.989997ms","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":"warn","ts":"2026-05-07T13:17:39.413427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:38.961548Z","time spent":"451.768286ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:39.413454Z","caller":"traceutil/trace.go:171","msg":"trace[963311945] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1039; }","duration":"370.082179ms","start":"2026-05-07T13:17:39.043361Z","end":"2026-05-07T13:17:39.413443Z","steps":["trace[963311945] 'agreement among raft nodes before linearized reading' (duration: 369.978507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:39.413467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:38.961886Z","time spent":"451.474542ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:39.413311Z","caller":"traceutil/trace.go:171","msg":"trace[392444434] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"400.56391ms","start":"2026-05-07T13:17:39.01273Z","end":"2026-05-07T13:17:39.413294Z","steps":["trace[392444434] 'process raft request' (duration: 400.366177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:39.413495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:39.04334Z","time spent":"370.1453ms","remote":"127.0.0.1:60350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:17:39.413978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:17:39.012709Z","time spent":"401.120249ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:17:42.116297Z","caller":"traceutil/trace.go:171","msg":"trace[1696370101] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"149.349772ms","start":"2026-05-07T13:17:41.966917Z","end":"2026-05-07T13:17:42.116267Z","steps":["trace[1696370101] 'process raft request' (duration: 149.198909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:17:42.333184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.187157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-07T13:17:42.333331Z","caller":"traceutil/trace.go:171","msg":"trace[2117857338] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"259.761626ms","start":"2026-05-07T13:17:42.073541Z","end":"2026-05-07T13:17:42.333303Z","steps":["trace[2117857338] 'read index received' (duration: 42.63062ms)","trace[2117857338] 'applied index is now lower than readState.Index' (duration: 217.128706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:17:42.333476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.917818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:17:42.333567Z","caller":"traceutil/trace.go:171","msg":"trace[333995837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"260.03971ms","start":"2026-05-07T13:17:42.073505Z","end":"2026-05-07T13:17:42.333545Z","steps":["trace[333995837] 'agreement among raft nodes before linearized reading' (duration: 259.897378ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:44.349951Z","caller":"traceutil/trace.go:171","msg":"trace[268383142] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"225.764288ms","start":"2026-05-07T13:17:44.124155Z","end":"2026-05-07T13:17:44.34992Z","steps":["trace[268383142] 'process raft request' (duration: 225.612486ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:47.647339Z","caller":"traceutil/trace.go:171","msg":"trace[907218] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"135.245605ms","start":"2026-05-07T13:17:47.512055Z","end":"2026-05-07T13:17:47.6473Z","steps":["trace[907218] 'process raft request' (duration: 133.982785ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:17:47.650608Z","caller":"traceutil/trace.go:171","msg":"trace[531483047] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"137.41799ms","start":"2026-05-07T13:17:47.513164Z","end":"2026-05-07T13:17:47.650582Z","steps":["trace[531483047] 'process raft request' (duration: 136.951952ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:09.969322Z","caller":"traceutil/trace.go:171","msg":"trace[1252947126] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"101.616659ms","start":"2026-05-07T13:18:09.867671Z","end":"2026-05-07T13:18:09.969288Z","steps":["trace[1252947126] 'process raft request' (duration: 38.384911ms)","trace[1252947126] 'compare' (duration: 62.644269ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:18:17.814893Z","caller":"traceutil/trace.go:171","msg":"trace[440697434] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"149.350591ms","start":"2026-05-07T13:18:17.665117Z","end":"2026-05-07T13:18:17.814468Z","steps":["trace[440697434] 'process raft request' (duration: 149.112978ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:18.174637Z","caller":"traceutil/trace.go:171","msg":"trace[766177840] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"159.244613ms","start":"2026-05-07T13:18:18.015338Z","end":"2026-05-07T13:18:18.174583Z","steps":["trace[766177840] 'process raft request' (duration: 127.278288ms)","trace[766177840] 'compare' (duration: 31.69647ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:18:19.986081Z","caller":"traceutil/trace.go:171","msg":"trace[965331167] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"164.100122ms","start":"2026-05-07T13:18:19.821941Z","end":"2026-05-07T13:18:19.986041Z","steps":["trace[965331167] 'process raft request' (duration: 163.863088ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:26.448995Z","caller":"traceutil/trace.go:171","msg":"trace[2108436702] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"211.517052ms","start":"2026-05-07T13:18:26.237448Z","end":"2026-05-07T13:18:26.448965Z","steps":["trace[2108436702] 'process raft request' (duration: 144.10929ms)","trace[2108436702] 'compare' (duration: 67.28399ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:18:41.050912Z","caller":"traceutil/trace.go:171","msg":"trace[925064524] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"183.579077ms","start":"2026-05-07T13:18:40.86731Z","end":"2026-05-07T13:18:41.050889Z","steps":["trace[925064524] 'read index received' (duration: 183.372524ms)","trace[925064524] 'applied index is now lower than readState.Index' (duration: 205.603µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:18:41.05104Z","caller":"traceutil/trace.go:171","msg":"trace[1514287304] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"186.776918ms","start":"2026-05-07T13:18:40.86422Z","end":"2026-05-07T13:18:41.050997Z","steps":["trace[1514287304] 'process raft request' (duration: 186.517515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:18:41.05115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.8124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T13:18:41.051202Z","caller":"traceutil/trace.go:171","msg":"trace[683022642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"183.90197ms","start":"2026-05-07T13:18:40.867284Z","end":"2026-05-07T13:18:41.051186Z","steps":["trace[683022642] 'agreement among raft nodes before linearized reading' (duration: 183.714628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:18:42.467985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.277535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T13:18:42.468091Z","caller":"traceutil/trace.go:171","msg":"trace[1366729649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"143.478998ms","start":"2026-05-07T13:18:42.324587Z","end":"2026-05-07T13:18:42.468066Z","steps":["trace[1366729649] 'range keys from in-memory index tree' (duration: 142.858609ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:42.667932Z","caller":"traceutil/trace.go:171","msg":"trace[1223172514] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"193.586935ms","start":"2026-05-07T13:18:42.4743Z","end":"2026-05-07T13:18:42.667887Z","steps":["trace[1223172514] 'process raft request' (duration: 192.920667ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:52.395264Z","caller":"traceutil/trace.go:171","msg":"trace[903115001] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"745.148409ms","start":"2026-05-07T13:18:51.650084Z","end":"2026-05-07T13:18:52.395233Z","steps":["trace[903115001] 'process raft request' (duration: 744.922436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:18:52.395588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:18:51.650056Z","time spent":"745.421132ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:18:52.417724Z","caller":"traceutil/trace.go:171","msg":"trace[997266392] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"591.124976ms","start":"2026-05-07T13:18:51.82657Z","end":"2026-05-07T13:18:52.417695Z","steps":["trace[997266392] 'process raft request' (duration: 590.892243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:18:52.417937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:18:51.826534Z","time spent":"591.282998ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:18:52.421321Z","caller":"traceutil/trace.go:171","msg":"trace[570846968] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"192.338781ms","start":"2026-05-07T13:18:52.228963Z","end":"2026-05-07T13:18:52.421302Z","steps":["trace[570846968] 'process raft request' (duration: 192.083358ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:18:57.249361Z","caller":"traceutil/trace.go:171","msg":"trace[37701409] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"107.788838ms","start":"2026-05-07T13:18:57.141539Z","end":"2026-05-07T13:18:57.249328Z","steps":["trace[37701409] 'process raft request' (duration: 107.468304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:00.961117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423071831042325442,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:19:01.068577Z","caller":"traceutil/trace.go:171","msg":"trace[288372595] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"608.440263ms","start":"2026-05-07T13:19:00.460102Z","end":"2026-05-07T13:19:01.068542Z","steps":["trace[288372595] 'read index received' (duration: 608.21551ms)","trace[288372595] 'applied index is now lower than readState.Index' (duration: 223.093µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:01.068693Z","caller":"traceutil/trace.go:171","msg":"trace[1920584602] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"608.594376ms","start":"2026-05-07T13:19:00.460051Z","end":"2026-05-07T13:19:01.068645Z","steps":["trace[1920584602] 'process raft request' (duration: 608.265021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:01.068883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.061212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:19:01.068951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.815058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:19:01.068998Z","caller":"traceutil/trace.go:171","msg":"trace[849766679] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1338; }","duration":"354.230933ms","start":"2026-05-07T13:19:00.714734Z","end":"2026-05-07T13:19:01.068965Z","steps":["trace[849766679] 'agreement among raft nodes before linearized reading' (duration: 354.046671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:01.069021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.012998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T13:19:01.06906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:00.71471Z","time spent":"354.336774ms","remote":"127.0.0.1:42494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:19:01.069074Z","caller":"traceutil/trace.go:171","msg":"trace[608680865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"255.089669ms","start":"2026-05-07T13:19:00.813966Z","end":"2026-05-07T13:19:01.069056Z","steps":["trace[608680865] 'agreement among raft nodes before linearized reading' (duration: 254.877887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:01.069071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.419218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T13:19:01.069065Z","caller":"traceutil/trace.go:171","msg":"trace[1987828037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1338; }","duration":"608.990621ms","start":"2026-05-07T13:19:00.460044Z","end":"2026-05-07T13:19:01.069034Z","steps":["trace[1987828037] 'agreement among raft nodes before linearized reading' (duration: 608.640187ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:01.069123Z","caller":"traceutil/trace.go:171","msg":"trace[1196473642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1338; }","duration":"306.53115ms","start":"2026-05-07T13:19:00.762577Z","end":"2026-05-07T13:19:01.069108Z","steps":["trace[1196473642] 'agreement among raft nodes before linearized reading' (duration: 306.285817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:01.06914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:00.460014Z","time spent":"609.110862ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:19:01.069149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:00.762544Z","time spent":"306.59753ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:19:01.069314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:00.46002Z","time spent":"608.747128ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:19:03.298896Z","caller":"traceutil/trace.go:171","msg":"trace[1277835014] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"149.66707ms","start":"2026-05-07T13:19:03.149199Z","end":"2026-05-07T13:19:03.298866Z","steps":["trace[1277835014] 'process raft request' (duration: 147.427002ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:03.29918Z","caller":"traceutil/trace.go:171","msg":"trace[1899209875] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"149.854892ms","start":"2026-05-07T13:19:03.149283Z","end":"2026-05-07T13:19:03.299138Z","steps":["trace[1899209875] 'process raft request' (duration: 149.530788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:03.299425Z","caller":"traceutil/trace.go:171","msg":"trace[183794750] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"150.026755ms","start":"2026-05-07T13:19:03.149311Z","end":"2026-05-07T13:19:03.299338Z","steps":["trace[183794750] 'process raft request' (duration: 149.750551ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:03.299461Z","caller":"traceutil/trace.go:171","msg":"trace[1875368326] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1407; }","duration":"132.526288ms","start":"2026-05-07T13:19:03.166765Z","end":"2026-05-07T13:19:03.299291Z","steps":["trace[1875368326] 'read index received' (duration: 129.757144ms)","trace[1875368326] 'applied index is now lower than readState.Index' (duration: 2.767154ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:19:03.299674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.887033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:19:03.299734Z","caller":"traceutil/trace.go:171","msg":"trace[1350297114] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1352; }","duration":"133.018504ms","start":"2026-05-07T13:19:03.166698Z","end":"2026-05-07T13:19:03.299717Z","steps":["trace[1350297114] 'agreement among raft nodes before linearized reading' (duration: 132.883993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:03.299799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.322862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:19:03.299971Z","caller":"traceutil/trace.go:171","msg":"trace[1117433134] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1352; }","duration":"122.571325ms","start":"2026-05-07T13:19:03.177379Z","end":"2026-05-07T13:19:03.29995Z","steps":["trace[1117433134] 'agreement among raft nodes before linearized reading' (duration: 122.288132ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:03.483766Z","caller":"traceutil/trace.go:171","msg":"trace[1544107611] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"139.221271ms","start":"2026-05-07T13:19:03.344509Z","end":"2026-05-07T13:19:03.48373Z","steps":["trace[1544107611] 'process raft request' (duration: 118.819598ms)","trace[1544107611] 'compare' (duration: 20.19724ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:07.48314Z","caller":"traceutil/trace.go:171","msg":"trace[2087372646] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"134.985646ms","start":"2026-05-07T13:19:07.348107Z","end":"2026-05-07T13:19:07.483093Z","steps":["trace[2087372646] 'process raft request' (duration: 59.57805ms)","trace[2087372646] 'compare' (duration: 75.270744ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:07.489359Z","caller":"traceutil/trace.go:171","msg":"trace[356204479] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"141.103832ms","start":"2026-05-07T13:19:07.348215Z","end":"2026-05-07T13:19:07.489319Z","steps":["trace[356204479] 'process raft request' (duration: 140.860188ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:07.489578Z","caller":"traceutil/trace.go:171","msg":"trace[172558375] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1422; }","duration":"139.752225ms","start":"2026-05-07T13:19:07.349796Z","end":"2026-05-07T13:19:07.489548Z","steps":["trace[172558375] 'read index received' (duration: 57.989851ms)","trace[172558375] 'applied index is now lower than readState.Index' (duration: 81.760574ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:07.489742Z","caller":"traceutil/trace.go:171","msg":"trace[1212133172] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"141.454286ms","start":"2026-05-07T13:19:07.348267Z","end":"2026-05-07T13:19:07.489721Z","steps":["trace[1212133172] 'process raft request' (duration: 140.998289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:07.489941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.097929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:19:07.490029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.50077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:19:07.490086Z","caller":"traceutil/trace.go:171","msg":"trace[1761147648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"138.574891ms","start":"2026-05-07T13:19:07.351496Z","end":"2026-05-07T13:19:07.490071Z","steps":["trace[1761147648] 'agreement among raft nodes before linearized reading' (duration: 138.396979ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:07.490057Z","caller":"traceutil/trace.go:171","msg":"trace[1856041992] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1366; }","duration":"140.260021ms","start":"2026-05-07T13:19:07.349772Z","end":"2026-05-07T13:19:07.490032Z","steps":["trace[1856041992] 'agreement among raft nodes before linearized reading' (duration: 140.068858ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:09.696802Z","caller":"traceutil/trace.go:171","msg":"trace[798709936] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"202.219273ms","start":"2026-05-07T13:19:09.494556Z","end":"2026-05-07T13:19:09.696775Z","steps":["trace[798709936] 'read index received' (duration: 201.981889ms)","trace[798709936] 'applied index is now lower than readState.Index' (duration: 236.254µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:09.696923Z","caller":"traceutil/trace.go:171","msg":"trace[1904340177] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"204.393279ms","start":"2026-05-07T13:19:09.492488Z","end":"2026-05-07T13:19:09.696881Z","steps":["trace[1904340177] 'process raft request' (duration: 204.108295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:09.697118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.528096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:19:09.697151Z","caller":"traceutil/trace.go:171","msg":"trace[211508892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"202.608798ms","start":"2026-05-07T13:19:09.494535Z","end":"2026-05-07T13:19:09.697143Z","steps":["trace[211508892] 'agreement among raft nodes before linearized reading' (duration: 202.431146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:09.697262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.140133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T13:19:09.697307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.327941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-05-07T13:19:09.697322Z","caller":"traceutil/trace.go:171","msg":"trace[128613703] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1370; }","duration":"111.373242ms","start":"2026-05-07T13:19:09.585943Z","end":"2026-05-07T13:19:09.697317Z","steps":["trace[128613703] 'agreement among raft nodes before linearized reading' (duration: 111.317912ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:09.69732Z","caller":"traceutil/trace.go:171","msg":"trace[2004738371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"122.238735ms","start":"2026-05-07T13:19:09.575063Z","end":"2026-05-07T13:19:09.697302Z","steps":["trace[2004738371] 'agreement among raft nodes before linearized reading' (duration: 122.099923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:09.697337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.56632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-05-07T13:19:09.697439Z","caller":"traceutil/trace.go:171","msg":"trace[1874839635] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1370; }","duration":"127.755302ms","start":"2026-05-07T13:19:09.569649Z","end":"2026-05-07T13:19:09.697404Z","steps":["trace[1874839635] 'agreement among raft nodes before linearized reading' (duration: 127.519439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:09.697461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.363984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:19:09.69755Z","caller":"traceutil/trace.go:171","msg":"trace[183018769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"197.493495ms","start":"2026-05-07T13:19:09.500033Z","end":"2026-05-07T13:19:09.697527Z","steps":["trace[183018769] 'agreement among raft nodes before linearized reading' (duration: 197.226292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:09.697573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.986062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T13:19:09.69761Z","caller":"traceutil/trace.go:171","msg":"trace[1040321261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"203.035073ms","start":"2026-05-07T13:19:09.494566Z","end":"2026-05-07T13:19:09.697601Z","steps":["trace[1040321261] 'agreement among raft nodes before linearized reading' (duration: 202.935511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:11.753867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.982276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-07T13:19:11.754064Z","caller":"traceutil/trace.go:171","msg":"trace[253025858] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:1375; }","duration":"319.25299ms","start":"2026-05-07T13:19:11.434783Z","end":"2026-05-07T13:19:11.754036Z","steps":["trace[253025858] 'range keys from in-memory index tree' (duration: 318.867525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:11.754148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.434757Z","time spent":"319.376711ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":704,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" "} {"level":"warn","ts":"2026-05-07T13:19:11.755016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.222193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-05-07T13:19:11.755119Z","caller":"traceutil/trace.go:171","msg":"trace[770604405] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1375; }","duration":"285.591039ms","start":"2026-05-07T13:19:11.469513Z","end":"2026-05-07T13:19:11.755104Z","steps":["trace[770604405] 'range keys from in-memory index tree' (duration: 283.967001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.415055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.318101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:19:12.415443Z","caller":"traceutil/trace.go:171","msg":"trace[9404122] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"654.723189ms","start":"2026-05-07T13:19:11.760691Z","end":"2026-05-07T13:19:12.415414Z","steps":["trace[9404122] 'read index received' (duration: 90.528664ms)","trace[9404122] 'applied index is now lower than readState.Index' (duration: 564.191925ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:12.415505Z","caller":"traceutil/trace.go:171","msg":"trace[1822972130] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"654.874821ms","start":"2026-05-07T13:19:11.760592Z","end":"2026-05-07T13:19:12.415467Z","steps":["trace[1822972130] 'process raft request' (duration: 447.055474ms)","trace[1822972130] 'compare' (duration: 207.178509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:19:12.415832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.760566Z","time spent":"655.106414ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:19:12.457884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.175636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-07T13:19:12.457973Z","caller":"traceutil/trace.go:171","msg":"trace[1833789680] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:1377; }","duration":"697.316107ms","start":"2026-05-07T13:19:11.760624Z","end":"2026-05-07T13:19:12.45794Z","steps":["trace[1833789680] 'agreement among raft nodes before linearized reading' (duration: 655.018292ms)","trace[1833789680] 'range keys from in-memory index tree' (duration: 42.139632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:19:12.457982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.009946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T13:19:12.458092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.086937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T13:19:12.458149Z","caller":"traceutil/trace.go:171","msg":"trace[606113279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1377; }","duration":"671.162178ms","start":"2026-05-07T13:19:11.786971Z","end":"2026-05-07T13:19:12.458133Z","steps":["trace[606113279] 'agreement among raft nodes before linearized reading' (duration: 670.981776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.458187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.786963Z","time spent":"671.216468ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T13:19:12.458167Z","caller":"traceutil/trace.go:171","msg":"trace[121176496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1377; }","duration":"671.216269ms","start":"2026-05-07T13:19:11.786891Z","end":"2026-05-07T13:19:12.458108Z","steps":["trace[121176496] 'agreement among raft nodes before linearized reading' (duration: 670.921295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.458207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.200088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:19:12.458029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.760595Z","time spent":"697.420359ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":704,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" "} {"level":"warn","ts":"2026-05-07T13:19:12.458324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.786869Z","time spent":"671.411901ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:19:12.458043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.441921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-05-07T13:19:12.45852Z","caller":"traceutil/trace.go:171","msg":"trace[1733996330] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1377; }","duration":"562.006368ms","start":"2026-05-07T13:19:11.896499Z","end":"2026-05-07T13:19:12.458506Z","steps":["trace[1733996330] 'agreement among raft nodes before linearized reading' (duration: 561.411701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.458563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.896469Z","time spent":"562.080489ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":701,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" "} {"level":"info","ts":"2026-05-07T13:19:12.4583Z","caller":"traceutil/trace.go:171","msg":"trace[83163909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1377; }","duration":"671.354061ms","start":"2026-05-07T13:19:11.786926Z","end":"2026-05-07T13:19:12.458281Z","steps":["trace[83163909] 'agreement among raft nodes before linearized reading' (duration: 671.044307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.458679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:11.786909Z","time spent":"671.751655ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T13:19:12.676062Z","caller":"traceutil/trace.go:171","msg":"trace[961934892] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"211.504662ms","start":"2026-05-07T13:19:12.464531Z","end":"2026-05-07T13:19:12.676036Z","steps":["trace[961934892] 'process raft request' (duration: 211.32813ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:12.834489Z","caller":"traceutil/trace.go:171","msg":"trace[282129359] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"369.850058ms","start":"2026-05-07T13:19:12.464595Z","end":"2026-05-07T13:19:12.834445Z","steps":["trace[282129359] 'process raft request' (duration: 309.307382ms)","trace[282129359] 'compare' (duration: 60.278562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:19:12.83469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:12.464568Z","time spent":"370.024ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:19:12.834873Z","caller":"traceutil/trace.go:171","msg":"trace[427889002] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"369.935769ms","start":"2026-05-07T13:19:12.464916Z","end":"2026-05-07T13:19:12.834851Z","steps":["trace[427889002] 'process raft request' (duration: 369.483244ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:12.83492Z","caller":"traceutil/trace.go:171","msg":"trace[1334629383] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1437; }","duration":"285.146429ms","start":"2026-05-07T13:19:12.549738Z","end":"2026-05-07T13:19:12.834885Z","steps":["trace[1334629383] 'read index received' (duration: 126.118354ms)","trace[1334629383] 'applied index is now lower than readState.Index' (duration: 159.026785ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:12.834964Z","caller":"traceutil/trace.go:171","msg":"trace[1645902966] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"261.944426ms","start":"2026-05-07T13:19:12.572983Z","end":"2026-05-07T13:19:12.834927Z","steps":["trace[1645902966] 'process raft request' (duration: 261.728664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.83504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:12.464901Z","time spent":"370.05356ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:19:12.835226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.453612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-05-07T13:19:12.835283Z","caller":"traceutil/trace.go:171","msg":"trace[1238484827] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1381; }","duration":"285.585993ms","start":"2026-05-07T13:19:12.54968Z","end":"2026-05-07T13:19:12.835266Z","steps":["trace[1238484827] 'agreement among raft nodes before linearized reading' (duration: 285.373611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:12.835296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.779471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:950"} {"level":"info","ts":"2026-05-07T13:19:12.835345Z","caller":"traceutil/trace.go:171","msg":"trace[1874246914] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1381; }","duration":"283.867782ms","start":"2026-05-07T13:19:12.551463Z","end":"2026-05-07T13:19:12.835331Z","steps":["trace[1874246914] 'agreement among raft nodes before linearized reading' (duration: 283.653099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:13.100193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.593545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-05-07T13:19:13.100293Z","caller":"traceutil/trace.go:171","msg":"trace[715061616] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1381; }","duration":"134.745867ms","start":"2026-05-07T13:19:12.965524Z","end":"2026-05-07T13:19:13.10027Z","steps":["trace[715061616] 'range keys from in-memory index tree' (duration: 134.403373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:13.100406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.083924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:950"} {"level":"info","ts":"2026-05-07T13:19:13.100479Z","caller":"traceutil/trace.go:171","msg":"trace[71740371] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1381; }","duration":"150.188315ms","start":"2026-05-07T13:19:12.950261Z","end":"2026-05-07T13:19:13.10045Z","steps":["trace[71740371] 'range keys from in-memory index tree' (duration: 149.732859ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:16.214267Z","caller":"traceutil/trace.go:171","msg":"trace[1271844333] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"211.823221ms","start":"2026-05-07T13:19:16.002399Z","end":"2026-05-07T13:19:16.214223Z","steps":["trace[1271844333] 'read index received' (duration: 211.460177ms)","trace[1271844333] 'applied index is now lower than readState.Index' (duration: 361.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:16.21445Z","caller":"traceutil/trace.go:171","msg":"trace[467525070] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"270.069645ms","start":"2026-05-07T13:19:15.944361Z","end":"2026-05-07T13:19:16.21443Z","steps":["trace[467525070] 'process raft request' (duration: 269.62007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:16.214757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.656748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:19:16.214716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.724017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-05-07T13:19:16.214823Z","caller":"traceutil/trace.go:171","msg":"trace[1355786426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1389; }","duration":"139.747919ms","start":"2026-05-07T13:19:16.075059Z","end":"2026-05-07T13:19:16.214807Z","steps":["trace[1355786426] 'agreement among raft nodes before linearized reading' (duration: 139.618438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:16.214851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.414078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:coredns\" ","response":"range_response_count:1 size:388"} {"level":"info","ts":"2026-05-07T13:19:16.214964Z","caller":"traceutil/trace.go:171","msg":"trace[1344384836] range","detail":"{range_begin:/registry/clusterroles/system:coredns; range_end:; response_count:1; response_revision:1389; }","duration":"212.60587ms","start":"2026-05-07T13:19:16.002336Z","end":"2026-05-07T13:19:16.214942Z","steps":["trace[1344384836] 'agreement among raft nodes before linearized reading' (duration: 212.355447ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:16.214889Z","caller":"traceutil/trace.go:171","msg":"trace[1999353036] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1389; }","duration":"191.94365ms","start":"2026-05-07T13:19:16.022893Z","end":"2026-05-07T13:19:16.214837Z","steps":["trace[1999353036] 'agreement among raft nodes before linearized reading' (duration: 191.583075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:17.152014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.892698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:19:17.152183Z","caller":"traceutil/trace.go:171","msg":"trace[821043656] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"164.315894ms","start":"2026-05-07T13:19:16.98785Z","end":"2026-05-07T13:19:17.152165Z","steps":["trace[821043656] 'read index received' (duration: 29.279564ms)","trace[821043656] 'applied index is now lower than readState.Index' (duration: 135.03494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:19:17.152408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.517716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T13:19:17.152441Z","caller":"traceutil/trace.go:171","msg":"trace[1565212673] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"165.640949ms","start":"2026-05-07T13:19:16.98655Z","end":"2026-05-07T13:19:17.152191Z","steps":["trace[1565212673] 'process raft request' (duration: 30.473618ms)","trace[1565212673] 'compare' (duration: 134.740016ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:17.152516Z","caller":"traceutil/trace.go:171","msg":"trace[478819174] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1393; }","duration":"164.701758ms","start":"2026-05-07T13:19:16.987789Z","end":"2026-05-07T13:19:17.152491Z","steps":["trace[478819174] 'agreement among raft nodes before linearized reading' (duration: 164.446665ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:17.152483Z","caller":"traceutil/trace.go:171","msg":"trace[1256955269] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"164.596257ms","start":"2026-05-07T13:19:16.987871Z","end":"2026-05-07T13:19:17.152467Z","steps":["trace[1256955269] 'process raft request' (duration: 164.262793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:17.152733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.576125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1381"} {"level":"info","ts":"2026-05-07T13:19:17.152795Z","caller":"traceutil/trace.go:171","msg":"trace[19745410] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1394; }","duration":"154.727497ms","start":"2026-05-07T13:19:16.998053Z","end":"2026-05-07T13:19:17.15278Z","steps":["trace[19745410] 'agreement among raft nodes before linearized reading' (duration: 154.580306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:17.153132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.957236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-07T13:19:17.153222Z","caller":"traceutil/trace.go:171","msg":"trace[1820169525] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1394; }","duration":"129.126778ms","start":"2026-05-07T13:19:17.024074Z","end":"2026-05-07T13:19:17.1532Z","steps":["trace[1820169525] 'agreement among raft nodes before linearized reading' (duration: 128.389439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:17.453561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.459559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-05-07T13:19:17.453691Z","caller":"traceutil/trace.go:171","msg":"trace[1625972177] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:1395; }","duration":"201.629682ms","start":"2026-05-07T13:19:17.252028Z","end":"2026-05-07T13:19:17.453658Z","steps":["trace[1625972177] 'range keys from in-memory index tree' (duration: 201.337748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:17.45377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.463724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:1 size:1486"} {"level":"info","ts":"2026-05-07T13:19:17.453858Z","caller":"traceutil/trace.go:171","msg":"trace[1689795427] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:1395; }","duration":"130.639546ms","start":"2026-05-07T13:19:17.323205Z","end":"2026-05-07T13:19:17.453844Z","steps":["trace[1689795427] 'range keys from in-memory index tree' (duration: 130.121099ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:18.358162Z","caller":"traceutil/trace.go:171","msg":"trace[977115831] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"132.758778ms","start":"2026-05-07T13:19:18.225381Z","end":"2026-05-07T13:19:18.358139Z","steps":["trace[977115831] 'process raft request' (duration: 132.378934ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:19.335406Z","caller":"traceutil/trace.go:171","msg":"trace[1343186818] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1460; }","duration":"147.657606ms","start":"2026-05-07T13:19:19.187715Z","end":"2026-05-07T13:19:19.335372Z","steps":["trace[1343186818] 'read index received' (duration: 26.988904ms)","trace[1343186818] 'applied index is now lower than readState.Index' (duration: 120.667492ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:19.33553Z","caller":"traceutil/trace.go:171","msg":"trace[754586775] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"174.469028ms","start":"2026-05-07T13:19:19.161022Z","end":"2026-05-07T13:19:19.335491Z","steps":["trace[754586775] 'process raft request' (duration: 169.110104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:19.335618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.863638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:19:19.335658Z","caller":"traceutil/trace.go:171","msg":"trace[833092423] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1401; }","duration":"147.9789ms","start":"2026-05-07T13:19:19.187667Z","end":"2026-05-07T13:19:19.335646Z","steps":["trace[833092423] 'agreement among raft nodes before linearized reading' (duration: 147.865878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:19.335714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.276222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-05-07T13:19:19.335756Z","caller":"traceutil/trace.go:171","msg":"trace[27721237] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:1401; }","duration":"128.385424ms","start":"2026-05-07T13:19:19.20736Z","end":"2026-05-07T13:19:19.335745Z","steps":["trace[27721237] 'agreement among raft nodes before linearized reading' (duration: 128.256152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.1054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.088482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-07T13:19:38.105494Z","caller":"traceutil/trace.go:171","msg":"trace[177282122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:1462; }","duration":"425.220743ms","start":"2026-05-07T13:19:37.68025Z","end":"2026-05-07T13:19:38.105471Z","steps":["trace[177282122] 'range keys from in-memory index tree' (duration: 424.93417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.105535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:37.68023Z","time spent":"425.295824ms","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-07T13:19:38.105674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.785906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-07T13:19:38.105857Z","caller":"traceutil/trace.go:171","msg":"trace[1453951159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:1; response_revision:1462; }","duration":"418.041528ms","start":"2026-05-07T13:19:37.687791Z","end":"2026-05-07T13:19:38.105832Z","steps":["trace[1453951159] 'range keys from in-memory index tree' (duration: 417.653354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.10592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:37.687784Z","time spent":"418.124279ms","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"info","ts":"2026-05-07T13:19:38.201242Z","caller":"traceutil/trace.go:171","msg":"trace[1508364999] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1527; }","duration":"200.131138ms","start":"2026-05-07T13:19:38.001082Z","end":"2026-05-07T13:19:38.201214Z","steps":["trace[1508364999] 'read index received' (duration: 199.875095ms)","trace[1508364999] 'applied index is now lower than readState.Index' (duration: 254.843µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:38.201341Z","caller":"traceutil/trace.go:171","msg":"trace[1281164003] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"506.379795ms","start":"2026-05-07T13:19:37.694928Z","end":"2026-05-07T13:19:38.201308Z","steps":["trace[1281164003] 'process raft request' (duration: 506.105312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.201406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.305871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:19:38.201446Z","caller":"traceutil/trace.go:171","msg":"trace[1135126879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1463; }","duration":"200.416602ms","start":"2026-05-07T13:19:38.00101Z","end":"2026-05-07T13:19:38.201427Z","steps":["trace[1135126879] 'agreement among raft nodes before linearized reading' (duration: 200.330091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.201497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:19:37.694909Z","time spent":"506.500117ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:19:38.2017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.318592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:19:38.201763Z","caller":"traceutil/trace.go:171","msg":"trace[806134580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1463; }","duration":"126.416343ms","start":"2026-05-07T13:19:38.075322Z","end":"2026-05-07T13:19:38.201738Z","steps":["trace[806134580] 'agreement among raft nodes before linearized reading' (duration: 126.319712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.375448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.324987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-07T13:19:38.37564Z","caller":"traceutil/trace.go:171","msg":"trace[1287773883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:1463; }","duration":"129.557889ms","start":"2026-05-07T13:19:38.246065Z","end":"2026-05-07T13:19:38.375623Z","steps":["trace[1287773883] 'range keys from in-memory index tree' (duration: 129.236645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:38.375566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.193273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-07T13:19:38.375783Z","caller":"traceutil/trace.go:171","msg":"trace[698711343] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:1; response_revision:1463; }","duration":"146.395306ms","start":"2026-05-07T13:19:38.229339Z","end":"2026-05-07T13:19:38.375734Z","steps":["trace[698711343] 'range keys from in-memory index tree' (duration: 146.116003ms)"],"step_count":1}