{"level":"warn","ts":"2026-06-10T08:31:38.787295Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:31:38.787388Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233: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.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-10T08:31:38.787469Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:31:38.787482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-06-10T08:31:38.7875Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T08:31:38.788143Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-06-10T08:31:38.788216Z","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.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-10T08:31:38.795872Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.429129ms"} {"level":"info","ts":"2026-06-10T08:31:38.804352Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-06-10T08:31:38.804425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T08:31:38.804447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-06-10T08:31:38.804462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T08:31:38.80447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-06-10T08:31:38.804508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-06-10T08:31:38.811049Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T08:31:38.81256Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T08:31:38.813841Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-10T08:31:38.816806Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T08:31:38.81771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T08:31:38.817778Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T08:31:38.817835Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T08:31:38.817846Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T08:31:38.817925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-06-10T08:31:38.818013Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-06-10T08:31:38.820856Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T08:31:38.821049Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T08:31:38.821081Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T08:31:38.821533Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-06-10T08:31:38.821553Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-06-10T08:31:39.305198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T08:31:39.305263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T08:31:39.30528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-06-10T08:31:39.305292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-06-10T08:31:39.305298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-06-10T08:31:39.305308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-06-10T08:31:39.305315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-06-10T08:31:39.307111Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T08:31:39.307144Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:31:39.307318Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:31:39.307404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:31:39.307497Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T08:31:39.307585Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T08:31:39.309621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-06-10T08:31:39.310413Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T08:31:39.310982Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:31:39.311202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:31:39.311259Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:31:41.155967Z","caller":"traceutil/trace.go:171","msg":"trace[1307043212] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"237.306995ms","start":"2026-06-10T08:31:40.918633Z","end":"2026-06-10T08:31:41.15594Z","steps":["trace[1307043212] 'process raft request' (duration: 237.180393ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:41.161634Z","caller":"traceutil/trace.go:171","msg":"trace[508019092] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"109.428575ms","start":"2026-06-10T08:31:41.052176Z","end":"2026-06-10T08:31:41.161605Z","steps":["trace[508019092] 'process raft request' (duration: 109.321693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:41.87687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.568532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-10T08:31:41.877012Z","caller":"traceutil/trace.go:171","msg":"trace[1340907903] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"215.360174ms","start":"2026-06-10T08:31:41.661633Z","end":"2026-06-10T08:31:41.876994Z","steps":["trace[1340907903] 'read index received' (duration: 67.06444ms)","trace[1340907903] 'applied index is now lower than readState.Index' (duration: 148.294204ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:31:41.877084Z","caller":"traceutil/trace.go:171","msg":"trace[1745150228] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"252.771616ms","start":"2026-06-10T08:31:41.624258Z","end":"2026-06-10T08:31:41.87703Z","steps":["trace[1745150228] 'process raft request' (duration: 104.551743ms)","trace[1745150228] 'compare' (duration: 147.405298ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:31:41.877149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.507247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T08:31:41.877183Z","caller":"traceutil/trace.go:171","msg":"trace[1740004841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:83; }","duration":"215.576519ms","start":"2026-06-10T08:31:41.661595Z","end":"2026-06-10T08:31:41.877172Z","steps":["trace[1740004841] 'agreement among raft nodes before linearized reading' (duration: 215.454116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:48.611867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458694851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-06-10T08:31:48.611924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.161848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:31:48.611966Z","caller":"traceutil/trace.go:171","msg":"trace[1686881473] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:332; }","duration":"1.458833763s","start":"2026-06-10T08:31:47.153112Z","end":"2026-06-10T08:31:48.611946Z","steps":["trace[1686881473] 'range keys from in-memory index tree' (duration: 1.458583739s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:48.611976Z","caller":"traceutil/trace.go:171","msg":"trace[2077072634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:332; }","duration":"994.25079ms","start":"2026-06-10T08:31:47.617711Z","end":"2026-06-10T08:31:48.611962Z","steps":["trace[2077072634] 'range keys from in-memory index tree' (duration: 994.084907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:48.611861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.319837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T08:31:48.611992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193810596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-10T08:31:48.612013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:47.153088Z","time spent":"1.458914914s","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-06-10T08:31:48.612066Z","caller":"traceutil/trace.go:171","msg":"trace[1060770539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"653.560601ms","start":"2026-06-10T08:31:47.958465Z","end":"2026-06-10T08:31:48.612026Z","steps":["trace[1060770539] 'range keys from in-memory index tree' (duration: 653.194054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:48.612022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:47.617689Z","time spent":"994.319591ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:31:48.612089Z","caller":"traceutil/trace.go:171","msg":"trace[549570101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:332; }","duration":"1.193959669s","start":"2026-06-10T08:31:47.418109Z","end":"2026-06-10T08:31:48.612069Z","steps":["trace[549570101] 'range keys from in-memory index tree' (duration: 1.193714644s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:48.612124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:47.95845Z","time spent":"653.661613ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:31:48.611867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221882443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-10T08:31:48.612131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:47.418091Z","time spent":"1.19403145s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:31:48.612233Z","caller":"traceutil/trace.go:171","msg":"trace[839018252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:332; }","duration":"1.222318392s","start":"2026-06-10T08:31:47.389899Z","end":"2026-06-10T08:31:48.612218Z","steps":["trace[839018252] 'range keys from in-memory index tree' (duration: 1.22174015s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:48.612296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:47.389876Z","time spent":"1.222387694s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:31:48.765529Z","caller":"traceutil/trace.go:171","msg":"trace[1464841706] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"148.725343ms","start":"2026-06-10T08:31:48.616765Z","end":"2026-06-10T08:31:48.765491Z","steps":["trace[1464841706] 'process raft request' (duration: 148.54555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:49.466633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.605021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:31:49.466909Z","caller":"traceutil/trace.go:171","msg":"trace[1718576350] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:344; }","duration":"849.161736ms","start":"2026-06-10T08:31:48.617729Z","end":"2026-06-10T08:31:49.466891Z","steps":["trace[1718576350] 'read index received' (duration: 147.723674ms)","trace[1718576350] 'applied index is now lower than readState.Index' (duration: 701.437232ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:31:49.466991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.256327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:31:49.467023Z","caller":"traceutil/trace.go:171","msg":"trace[1078946086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:336; }","duration":"849.311189ms","start":"2026-06-10T08:31:48.617702Z","end":"2026-06-10T08:31:49.467014Z","steps":["trace[1078946086] 'agreement among raft nodes before linearized reading' (duration: 849.246918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:49.467059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:48.617695Z","time spent":"849.35681ms","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"info","ts":"2026-06-10T08:31:49.467198Z","caller":"traceutil/trace.go:171","msg":"trace[1506214029] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"849.91524ms","start":"2026-06-10T08:31:48.617246Z","end":"2026-06-10T08:31:49.467161Z","steps":["trace[1506214029] 'process raft request' (duration: 364.545435ms)","trace[1506214029] 'compare' (duration: 484.480898ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:31:49.467251Z","caller":"traceutil/trace.go:171","msg":"trace[1626810674] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"849.660805ms","start":"2026-06-10T08:31:48.617577Z","end":"2026-06-10T08:31:49.467238Z","steps":["trace[1626810674] 'process raft request' (duration: 849.258318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:49.467324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:48.617569Z","time spent":"849.720326ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:31:49.467373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:48.617225Z","time spent":"850.053202ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:31:49.467594Z","caller":"traceutil/trace.go:171","msg":"trace[288401367] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"849.729486ms","start":"2026-06-10T08:31:48.617515Z","end":"2026-06-10T08:31:49.467245Z","steps":["trace[288401367] 'process raft request' (duration: 849.210117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:49.467812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:48.617506Z","time spent":"850.200175ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:15.773536Z","caller":"traceutil/trace.go:171","msg":"trace[2134451470] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"127.187106ms","start":"2026-06-10T08:32:15.646322Z","end":"2026-06-10T08:32:15.773509Z","steps":["trace[2134451470] 'process raft request' (duration: 127.133905ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:15.773576Z","caller":"traceutil/trace.go:171","msg":"trace[1107050561] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"128.174166ms","start":"2026-06-10T08:32:15.645366Z","end":"2026-06-10T08:32:15.773541Z","steps":["trace[1107050561] 'process raft request' (duration: 127.849139ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:17.885127Z","caller":"traceutil/trace.go:171","msg":"trace[755335364] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"104.882979ms","start":"2026-06-10T08:32:17.780196Z","end":"2026-06-10T08:32:17.885079Z","steps":["trace[755335364] 'process raft request' (duration: 103.099814ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:17.885353Z","caller":"traceutil/trace.go:171","msg":"trace[615208598] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"104.328468ms","start":"2026-06-10T08:32:17.780973Z","end":"2026-06-10T08:32:17.885302Z","steps":["trace[615208598] 'process raft request' (duration: 104.014903ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:19.177751Z","caller":"traceutil/trace.go:171","msg":"trace[1021321119] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"183.660977ms","start":"2026-06-10T08:32:18.994065Z","end":"2026-06-10T08:32:19.177726Z","steps":["trace[1021321119] 'process raft request' (duration: 183.515185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:22.273823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729082525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:32:22.77421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729082525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:32:23.274832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.650549676s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-10T08:32:23.275121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729082525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:32:23.276074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.822072Z","time spent":"1.453995111s","remote":"127.0.0.1:44884","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-10T08:32:23.276448Z","caller":"traceutil/trace.go:171","msg":"trace[845874218] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.652262748s","start":"2026-06-10T08:32:21.624153Z","end":"2026-06-10T08:32:23.276416Z","steps":["trace[845874218] 'process raft request' (duration: 1.651123686s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.276607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.624135Z","time spent":"1.652371701s","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:23.282711Z","caller":"traceutil/trace.go:171","msg":"trace[39446024] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.646524529s","start":"2026-06-10T08:32:21.636167Z","end":"2026-06-10T08:32:23.282691Z","steps":["trace[39446024] 'process raft request' (duration: 1.646324735s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.282844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.636149Z","time spent":"1.64662315s","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:23.282986Z","caller":"traceutil/trace.go:171","msg":"trace[13100722] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:592; }","duration":"1.510130937s","start":"2026-06-10T08:32:21.772831Z","end":"2026-06-10T08:32:23.282962Z","steps":["trace[13100722] 'read index received' (duration: 1.502401258s)","trace[13100722] 'applied index is now lower than readState.Index' (duration: 7.726709ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:32:23.283071Z","caller":"traceutil/trace.go:171","msg":"trace[984642550] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.573300207s","start":"2026-06-10T08:32:21.709717Z","end":"2026-06-10T08:32:23.283017Z","steps":["trace[984642550] 'process raft request' (duration: 1.572915s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.283184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.709697Z","time spent":"1.573431569s","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:32:23.283449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510604955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:32:23.283513Z","caller":"traceutil/trace.go:171","msg":"trace[1490038869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.510756079s","start":"2026-06-10T08:32:21.772741Z","end":"2026-06-10T08:32:23.283497Z","steps":["trace[1490038869] 'agreement among raft nodes before linearized reading' (duration: 1.510514524s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.283549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.77268Z","time spent":"1.51086281s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:32:23.28375Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":14755374235729082564,"received-request-id":14755374235729082525} {"level":"warn","ts":"2026-06-10T08:32:23.283965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376057448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:32:23.284003Z","caller":"traceutil/trace.go:171","msg":"trace[1137716710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"1.37612628s","start":"2026-06-10T08:32:21.907866Z","end":"2026-06-10T08:32:23.283993Z","steps":["trace[1137716710] 'agreement among raft nodes before linearized reading' (duration: 1.376046498s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.284027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.907828Z","time spent":"1.37619278s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T08:32:23.28414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.610617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:32:23.284191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.364519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:32:23.284197Z","caller":"traceutil/trace.go:171","msg":"trace[141773037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"230.671188ms","start":"2026-06-10T08:32:23.053509Z","end":"2026-06-10T08:32:23.28418Z","steps":["trace[141773037] 'agreement among raft nodes before linearized reading' (duration: 230.569906ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:23.284214Z","caller":"traceutil/trace.go:171","msg":"trace[1955682764] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:572; }","duration":"719.426051ms","start":"2026-06-10T08:32:22.564782Z","end":"2026-06-10T08:32:23.284208Z","steps":["trace[1955682764] 'agreement among raft nodes before linearized reading' (duration: 719.37899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.284233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:22.564763Z","time spent":"719.466341ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-06-10T08:32:23.284468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376269822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:32:23.284505Z","caller":"traceutil/trace.go:171","msg":"trace[234395247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.376345953s","start":"2026-06-10T08:32:21.908149Z","end":"2026-06-10T08:32:23.284495Z","steps":["trace[234395247] 'agreement among raft nodes before linearized reading' (duration: 1.37616752s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.284502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355209729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:32:23.284528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.908142Z","time spent":"1.376380914s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T08:32:23.284546Z","caller":"traceutil/trace.go:171","msg":"trace[278755065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"1.35527909s","start":"2026-06-10T08:32:21.92925Z","end":"2026-06-10T08:32:23.284529Z","steps":["trace[278755065] 'agreement among raft nodes before linearized reading' (duration: 1.355231199s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:23.284567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:21.929225Z","time spent":"1.355337141s","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T08:32:28.92607Z","caller":"traceutil/trace.go:171","msg":"trace[1156181843] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"264.317312ms","start":"2026-06-10T08:32:28.661728Z","end":"2026-06-10T08:32:28.926045Z","steps":["trace[1156181843] 'process raft request' (duration: 263.750071ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:29.649853Z","caller":"traceutil/trace.go:171","msg":"trace[24672847] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"330.773536ms","start":"2026-06-10T08:32:29.319051Z","end":"2026-06-10T08:32:29.649825Z","steps":["trace[24672847] 'read index received' (duration: 330.4613ms)","trace[24672847] 'applied index is now lower than readState.Index' (duration: 310.695µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:32:29.650138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.0573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:32:29.65023Z","caller":"traceutil/trace.go:171","msg":"trace[900119430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"331.236965ms","start":"2026-06-10T08:32:29.31897Z","end":"2026-06-10T08:32:29.650207Z","steps":["trace[900119430] 'agreement among raft nodes before linearized reading' (duration: 330.948728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:29.650276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.318937Z","time spent":"331.330547ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:32:29.650607Z","caller":"traceutil/trace.go:171","msg":"trace[1878350645] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"365.051242ms","start":"2026-06-10T08:32:29.284832Z","end":"2026-06-10T08:32:29.649883Z","steps":["trace[1878350645] 'process raft request' (duration: 364.688775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:29.650691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.284803Z","time spent":"365.834476ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:32:30.364798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.37601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:32:30.364911Z","caller":"traceutil/trace.go:171","msg":"trace[752192505] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"714.923605ms","start":"2026-06-10T08:32:29.64997Z","end":"2026-06-10T08:32:30.364894Z","steps":["trace[752192505] 'read index received' (duration: 469.168437ms)","trace[752192505] 'applied index is now lower than readState.Index' (duration: 245.754058ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:32:30.365071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039142815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:32:30.365162Z","caller":"traceutil/trace.go:171","msg":"trace[1592770806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"1.039264996s","start":"2026-06-10T08:32:29.325878Z","end":"2026-06-10T08:32:30.365143Z","steps":["trace[1592770806] 'agreement among raft nodes before linearized reading' (duration: 1.039028312s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:30.365153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042105671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T08:32:30.365166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042026479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T08:32:30.365212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.325866Z","time spent":"1.039336817s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:32:30.365225Z","caller":"traceutil/trace.go:171","msg":"trace[601489131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"1.042215302s","start":"2026-06-10T08:32:29.32299Z","end":"2026-06-10T08:32:30.365206Z","steps":["trace[601489131] 'agreement among raft nodes before linearized reading' (duration: 1.041993558s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:30.365234Z","caller":"traceutil/trace.go:171","msg":"trace[1084223914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"1.042143282s","start":"2026-06-10T08:32:29.323073Z","end":"2026-06-10T08:32:30.365216Z","steps":["trace[1084223914] 'agreement among raft nodes before linearized reading' (duration: 1.041889337s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:30.36526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.322978Z","time spent":"1.042275473s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:32:30.365283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.323047Z","time spent":"1.042223853s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T08:32:30.365542Z","caller":"traceutil/trace.go:171","msg":"trace[67335658] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"934.021991ms","start":"2026-06-10T08:32:29.431489Z","end":"2026-06-10T08:32:30.365511Z","steps":["trace[67335658] 'process raft request' (duration: 687.848556ms)","trace[67335658] 'compare' (duration: 245.040674ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:32:30.365706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.431453Z","time spent":"934.155394ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:30.604481Z","caller":"traceutil/trace.go:171","msg":"trace[322966427] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"239.515007ms","start":"2026-06-10T08:32:30.364936Z","end":"2026-06-10T08:32:30.604451Z","steps":["trace[322966427] 'read index received' (duration: 239.399055ms)","trace[322966427] 'applied index is now lower than readState.Index' (duration: 114.232µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:32:30.604614Z","caller":"traceutil/trace.go:171","msg":"trace[1133076345] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"947.698043ms","start":"2026-06-10T08:32:29.656855Z","end":"2026-06-10T08:32:30.604554Z","steps":["trace[1133076345] 'process raft request' (duration: 947.403858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:30.604989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.656832Z","time spent":"947.983568ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:32:30.60501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.43506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:32:30.605188Z","caller":"traceutil/trace.go:171","msg":"trace[641980082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"673.644574ms","start":"2026-06-10T08:32:29.93152Z","end":"2026-06-10T08:32:30.605164Z","steps":["trace[641980082] 'agreement among raft nodes before linearized reading' (duration: 673.346188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:30.605316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:29.93146Z","time spent":"673.805027ms","remote":"127.0.0.1:55314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:32:31.43008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729082710,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:32:31.930623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729082710,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:32:32.083862Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.473044685s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-10T08:32:32.349802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.360064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:32:32.35009Z","caller":"traceutil/trace.go:171","msg":"trace[1119449458] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.919357756s","start":"2026-06-10T08:32:30.430714Z","end":"2026-06-10T08:32:32.350072Z","steps":["trace[1119449458] 'process raft request' (duration: 1.919316165s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:32.350145Z","caller":"traceutil/trace.go:171","msg":"trace[2042310370] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.978451456s","start":"2026-06-10T08:32:30.371666Z","end":"2026-06-10T08:32:32.350117Z","steps":["trace[2042310370] 'process raft request' (duration: 1.978238823s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:32.350157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:30.430686Z","time spent":"1.919444397s","remote":"127.0.0.1:55748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:32:32.350232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:30.371649Z","time spent":"1.978534079s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:32.350291Z","caller":"traceutil/trace.go:171","msg":"trace[1480933657] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.979429166s","start":"2026-06-10T08:32:30.370851Z","end":"2026-06-10T08:32:32.35028Z","steps":["trace[1480933657] 'process raft request' (duration: 1.713521573s)","trace[1480933657] 'compare' (duration: 264.970405ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:32:32.350336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:30.370803Z","time spent":"1.979508247s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:32.350342Z","caller":"traceutil/trace.go:171","msg":"trace[1061384481] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.978585169s","start":"2026-06-10T08:32:30.371748Z","end":"2026-06-10T08:32:32.350333Z","steps":["trace[1061384481] 'process raft request' (duration: 1.978241853s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:32.350392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:30.371743Z","time spent":"1.97862174s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:32:32.9302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00054924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-10T08:32:32.930293Z","caller":"traceutil/trace.go:171","msg":"trace[21831873] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000713943s","start":"2026-06-10T08:32:30.929558Z","end":"2026-06-10T08:32:32.930272Z","steps":["trace[21831873] 'agreement among raft nodes before linearized reading' (duration: 2.000583511s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:32.930434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:30.92953Z","time spent":"2.000804884s","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/10 08:32:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-10T08:32:33.366399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.833949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:32:33.366494Z","caller":"traceutil/trace.go:171","msg":"trace[1467673062] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:672; }","duration":"2.436849718s","start":"2026-06-10T08:32:30.929628Z","end":"2026-06-10T08:32:33.366478Z","steps":["trace[1467673062] 'read index received' (duration: 1.154594807s)","trace[1467673062] 'applied index is now lower than readState.Index' (duration: 1.282253891s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:32:33.366731Z","caller":"traceutil/trace.go:171","msg":"trace[702839168] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.739903718s","start":"2026-06-10T08:32:31.626814Z","end":"2026-06-10T08:32:33.366717Z","steps":["trace[702839168] 'process raft request' (duration: 1.623652191s)","trace[702839168] 'compare' (duration: 115.336579ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:32:33.366823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.119643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:32:33.366949Z","caller":"traceutil/trace.go:171","msg":"trace[1264705468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"436.274237ms","start":"2026-06-10T08:32:32.930652Z","end":"2026-06-10T08:32:33.366927Z","steps":["trace[1264705468] 'agreement among raft nodes before linearized reading' (duration: 436.107624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:33.366966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:31.626776Z","time spent":"1.73999358s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:32:33.367008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:32.930636Z","time spent":"436.357759ms","remote":"127.0.0.1:55314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:32:33.367238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.820701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:32:33.367291Z","caller":"traceutil/trace.go:171","msg":"trace[2058251515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"313.928743ms","start":"2026-06-10T08:32:33.053348Z","end":"2026-06-10T08:32:33.367277Z","steps":["trace[2058251515] 'agreement among raft nodes before linearized reading' (duration: 313.851332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:33.367339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:33.053317Z","time spent":"314.008715ms","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:32:33.367704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.467251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:32:33.367792Z","caller":"traceutil/trace.go:171","msg":"trace[960135001] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:652; }","duration":"802.591324ms","start":"2026-06-10T08:32:32.565189Z","end":"2026-06-10T08:32:33.367781Z","steps":["trace[960135001] 'agreement among raft nodes before linearized reading' (duration: 802.433342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:33.367827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:32.565168Z","time spent":"802.644165ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-06-10T08:32:33.368469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.291004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:32:33.368546Z","caller":"traceutil/trace.go:171","msg":"trace[2078358611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"758.460717ms","start":"2026-06-10T08:32:32.610069Z","end":"2026-06-10T08:32:33.36853Z","steps":["trace[2078358611] 'agreement among raft nodes before linearized reading' (duration: 756.712464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:33.368587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:32.610022Z","time spent":"758.557709ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T08:32:33.36701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004573372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:32:33.369253Z","caller":"traceutil/trace.go:171","msg":"trace[1567759266] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:652; }","duration":"1.006999438s","start":"2026-06-10T08:32:32.362235Z","end":"2026-06-10T08:32:33.369234Z","steps":["trace[1567759266] 'agreement among raft nodes before linearized reading' (duration: 1.00448134s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:33.369299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:32.362222Z","time spent":"1.007062089s","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-06-10T08:32:49.471087Z","caller":"traceutil/trace.go:171","msg":"trace[927471497] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"379.051041ms","start":"2026-06-10T08:32:49.092011Z","end":"2026-06-10T08:32:49.471062Z","steps":["trace[927471497] 'process raft request' (duration: 378.909568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:49.471243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:49.091996Z","time spent":"379.168563ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:49.859744Z","caller":"traceutil/trace.go:171","msg":"trace[415576466] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"411.588563ms","start":"2026-06-10T08:32:49.448106Z","end":"2026-06-10T08:32:49.859695Z","steps":["trace[415576466] 'read index received' (duration: 22.825837ms)","trace[415576466] 'applied index is now lower than readState.Index' (duration: 388.761546ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:32:49.859872Z","caller":"traceutil/trace.go:171","msg":"trace[711421830] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"476.428536ms","start":"2026-06-10T08:32:49.38341Z","end":"2026-06-10T08:32:49.859839Z","steps":["trace[711421830] 'process raft request' (duration: 476.14527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:49.860041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.865228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-10T08:32:49.860104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:49.383384Z","time spent":"476.63871ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:32:49.860129Z","caller":"traceutil/trace.go:171","msg":"trace[1325960747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:820; }","duration":"412.023862ms","start":"2026-06-10T08:32:49.448062Z","end":"2026-06-10T08:32:49.860086Z","steps":["trace[1325960747] 'agreement among raft nodes before linearized reading' (duration: 411.812138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:32:49.860173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:32:49.448041Z","time spent":"412.120643ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T08:32:49.860183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.457695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:32:49.860385Z","caller":"traceutil/trace.go:171","msg":"trace[368555644] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:820; }","duration":"206.71302ms","start":"2026-06-10T08:32:49.653651Z","end":"2026-06-10T08:32:49.860364Z","steps":["trace[368555644] 'agreement among raft nodes before linearized reading' (duration: 206.271142ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:24.891154Z","caller":"traceutil/trace.go:171","msg":"trace[1891530114] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"868.188564ms","start":"2026-06-10T08:33:24.022937Z","end":"2026-06-10T08:33:24.891126Z","steps":["trace[1891530114] 'process raft request' (duration: 868.067602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:24.891296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:24.022916Z","time spent":"868.305287ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:33:25.040208Z","caller":"traceutil/trace.go:171","msg":"trace[855062999] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"480.18504ms","start":"2026-06-10T08:33:24.559994Z","end":"2026-06-10T08:33:25.040179Z","steps":["trace[855062999] 'process raft request' (duration: 478.60117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:25.040375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:24.559963Z","time spent":"480.327613ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:33:25.040429Z","caller":"traceutil/trace.go:171","msg":"trace[334938106] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1075; }","duration":"427.341863ms","start":"2026-06-10T08:33:24.61306Z","end":"2026-06-10T08:33:25.040402Z","steps":["trace[334938106] 'read index received' (duration: 278.042049ms)","trace[334938106] 'applied index is now lower than readState.Index' (duration: 149.298764ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:25.040584Z","caller":"traceutil/trace.go:171","msg":"trace[524466457] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"415.62152ms","start":"2026-06-10T08:33:24.624923Z","end":"2026-06-10T08:33:25.040544Z","steps":["trace[524466457] 'process raft request' (duration: 415.198042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:25.040652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.583517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:33:25.040688Z","caller":"traceutil/trace.go:171","msg":"trace[199605449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"427.670109ms","start":"2026-06-10T08:33:24.613006Z","end":"2026-06-10T08:33:25.040676Z","steps":["trace[199605449] 'agreement among raft nodes before linearized reading' (duration: 427.512536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:25.04072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:24.612984Z","time spent":"427.72814ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:33:25.040755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:24.62491Z","time spent":"415.768543ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:25.040839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.332107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:33:25.040908Z","caller":"traceutil/trace.go:171","msg":"trace[1331993605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"424.408598ms","start":"2026-06-10T08:33:24.61648Z","end":"2026-06-10T08:33:25.040889Z","steps":["trace[1331993605] 'agreement among raft nodes before linearized reading' (duration: 424.237684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:25.040951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.21348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:33:25.040958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:24.616473Z","time spent":"424.469899ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:33:25.040981Z","caller":"traceutil/trace.go:171","msg":"trace[344986267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"111.275191ms","start":"2026-06-10T08:33:24.929697Z","end":"2026-06-10T08:33:25.040972Z","steps":["trace[344986267] 'agreement among raft nodes before linearized reading' (duration: 111.22652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:25.041193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.55264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:33:25.041224Z","caller":"traceutil/trace.go:171","msg":"trace[1017054900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"424.622551ms","start":"2026-06-10T08:33:24.616593Z","end":"2026-06-10T08:33:25.041216Z","steps":["trace[1017054900] 'agreement among raft nodes before linearized reading' (duration: 424.505459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:25.041246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:24.616564Z","time spent":"424.676734ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:33:26.451976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.157382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:33:26.45221Z","caller":"traceutil/trace.go:171","msg":"trace[1862168265] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1078; }","duration":"1.268786128s","start":"2026-06-10T08:33:25.183407Z","end":"2026-06-10T08:33:26.452193Z","steps":["trace[1862168265] 'read index received' (duration: 462.388321ms)","trace[1862168265] 'applied index is now lower than readState.Index' (duration: 806.396657ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:26.452399Z","caller":"traceutil/trace.go:171","msg":"trace[1627129221] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"1.405418502s","start":"2026-06-10T08:33:25.046967Z","end":"2026-06-10T08:33:26.452385Z","steps":["trace[1627129221] 'process raft request' (duration: 598.77299ms)","trace[1627129221] 'compare' (duration: 806.021949ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:33:26.452458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.046942Z","time spent":"1.405484334s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:26.452623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269190106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:33:26.452672Z","caller":"traceutil/trace.go:171","msg":"trace[1363571658] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1041; }","duration":"1.269309249s","start":"2026-06-10T08:33:25.183349Z","end":"2026-06-10T08:33:26.452659Z","steps":["trace[1363571658] 'agreement among raft nodes before linearized reading' (duration: 1.269195597s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:26.452684Z","caller":"traceutil/trace.go:171","msg":"trace[654508130] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"1.405685637s","start":"2026-06-10T08:33:25.046976Z","end":"2026-06-10T08:33:26.452662Z","steps":["trace[654508130] 'process raft request' (duration: 1.405092945s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:26.452707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.183319Z","time spent":"1.2693793s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:33:26.452648Z","caller":"traceutil/trace.go:171","msg":"trace[1489111279] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"1.405506054s","start":"2026-06-10T08:33:25.047062Z","end":"2026-06-10T08:33:26.452568Z","steps":["trace[1489111279] 'process raft request' (duration: 1.405098887s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:26.45284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.046958Z","time spent":"1.405791518s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:26.452906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.047038Z","time spent":"1.405808719s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:26.952856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729083585,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:33:27.113193Z","caller":"traceutil/trace.go:171","msg":"trace[540242621] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"660.934055ms","start":"2026-06-10T08:33:26.452238Z","end":"2026-06-10T08:33:27.113172Z","steps":["trace[540242621] 'read index received' (duration: 660.447875ms)","trace[540242621] 'applied index is now lower than readState.Index' (duration: 485.41µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:27.113268Z","caller":"traceutil/trace.go:171","msg":"trace[527357679] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.231828084s","start":"2026-06-10T08:33:25.88141Z","end":"2026-06-10T08:33:27.113238Z","steps":["trace[527357679] 'process raft request' (duration: 1.231259963s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:27.113371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439266627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T08:33:27.11341Z","caller":"traceutil/trace.go:171","msg":"trace[1400845474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"1.439367259s","start":"2026-06-10T08:33:25.67403Z","end":"2026-06-10T08:33:27.113397Z","steps":["trace[1400845474] 'agreement among raft nodes before linearized reading' (duration: 1.439228556s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:27.113401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.881383Z","time spent":"1.231943567s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:27.113411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718941247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:33:27.113456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.674009Z","time spent":"1.43942832s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-10T08:33:27.113492Z","caller":"traceutil/trace.go:171","msg":"trace[1743488072] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1042; }","duration":"1.719042088s","start":"2026-06-10T08:33:25.394432Z","end":"2026-06-10T08:33:27.113474Z","steps":["trace[1743488072] 'agreement among raft nodes before linearized reading' (duration: 1.718889456s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:27.113543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:25.394366Z","time spent":"1.71916726s","remote":"127.0.0.1:55554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:33:27.114576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.685637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T08:33:27.114618Z","caller":"traceutil/trace.go:171","msg":"trace[93881829] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"218.773369ms","start":"2026-06-10T08:33:26.895834Z","end":"2026-06-10T08:33:27.114608Z","steps":["trace[93881829] 'agreement among raft nodes before linearized reading' (duration: 218.663297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:27.114812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.154069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:27.115011Z","caller":"traceutil/trace.go:171","msg":"trace[1960892418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"185.408783ms","start":"2026-06-10T08:33:26.92959Z","end":"2026-06-10T08:33:27.114999Z","steps":["trace[1960892418] 'agreement among raft nodes before linearized reading' (duration: 184.963455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:35.380307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.219527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:33:35.380397Z","caller":"traceutil/trace.go:171","msg":"trace[1086750027] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"218.730458ms","start":"2026-06-10T08:33:35.16165Z","end":"2026-06-10T08:33:35.380381Z","steps":["trace[1086750027] 'process raft request' (duration: 94.362498ms)","trace[1086750027] 'compare' (duration: 124.071815ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:35.387325Z","caller":"traceutil/trace.go:171","msg":"trace[2129445197] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"190.857387ms","start":"2026-06-10T08:33:35.19643Z","end":"2026-06-10T08:33:35.387287Z","steps":["trace[2129445197] 'process raft request' (duration: 190.801156ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:35.387364Z","caller":"traceutil/trace.go:171","msg":"trace[1796080380] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"224.484468ms","start":"2026-06-10T08:33:35.16286Z","end":"2026-06-10T08:33:35.387345Z","steps":["trace[1796080380] 'process raft request' (duration: 224.191253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:50.551798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160832311s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:50.551948Z","caller":"traceutil/trace.go:171","msg":"trace[1004255790] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1115; }","duration":"1.161002074s","start":"2026-06-10T08:33:49.390927Z","end":"2026-06-10T08:33:50.551929Z","steps":["trace[1004255790] 'range keys from in-memory index tree' (duration: 1.16077828s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:50.552026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.482993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:50.552114Z","caller":"traceutil/trace.go:171","msg":"trace[1846703411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"622.638195ms","start":"2026-06-10T08:33:49.929455Z","end":"2026-06-10T08:33:50.552093Z","steps":["trace[1846703411] 'range keys from in-memory index tree' (duration: 622.35651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:50.552166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:49.929434Z","time spent":"622.721807ms","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:33:50.552515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113213733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T08:33:50.552593Z","caller":"traceutil/trace.go:171","msg":"trace[356994596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"1.113302505s","start":"2026-06-10T08:33:49.439272Z","end":"2026-06-10T08:33:50.552575Z","steps":["trace[356994596] 'range keys from in-memory index tree' (duration: 1.113008919s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:50.552639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:49.439205Z","time spent":"1.113425127s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:33:50.552699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108161548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:33:50.552738Z","caller":"traceutil/trace.go:171","msg":"trace[651950506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1115; }","duration":"1.1082243s","start":"2026-06-10T08:33:49.444501Z","end":"2026-06-10T08:33:50.552725Z","steps":["trace[651950506] 'range keys from in-memory index tree' (duration: 1.108022464s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:50.552779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:49.444486Z","time spent":"1.108284801s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:33:50.718023Z","caller":"traceutil/trace.go:171","msg":"trace[290392314] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"160.01628ms","start":"2026-06-10T08:33:50.55798Z","end":"2026-06-10T08:33:50.717997Z","steps":["trace[290392314] 'process raft request' (duration: 159.740294ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:50.74466Z","caller":"traceutil/trace.go:171","msg":"trace[343686534] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"128.457908ms","start":"2026-06-10T08:33:50.616174Z","end":"2026-06-10T08:33:50.744631Z","steps":["trace[343686534] 'read index received' (duration: 101.632907ms)","trace[343686534] 'applied index is now lower than readState.Index' (duration: 26.823921ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:33:50.744991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.802914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T08:33:50.745021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.576786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:33:50.745063Z","caller":"traceutil/trace.go:171","msg":"trace[1595352262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1117; }","duration":"128.941347ms","start":"2026-06-10T08:33:50.616108Z","end":"2026-06-10T08:33:50.745049Z","steps":["trace[1595352262] 'agreement among raft nodes before linearized reading' (duration: 128.683682ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:50.745084Z","caller":"traceutil/trace.go:171","msg":"trace[2106217966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"112.681288ms","start":"2026-06-10T08:33:50.632387Z","end":"2026-06-10T08:33:50.745069Z","steps":["trace[2106217966] 'agreement among raft nodes before linearized reading' (duration: 112.478334ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:50.745023Z","caller":"traceutil/trace.go:171","msg":"trace[877668456] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"186.77019ms","start":"2026-06-10T08:33:50.558175Z","end":"2026-06-10T08:33:50.744945Z","steps":["trace[877668456] 'process raft request' (duration: 186.24488ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:50.852656Z","caller":"traceutil/trace.go:171","msg":"trace[669445569] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"100.059877ms","start":"2026-06-10T08:33:50.752564Z","end":"2026-06-10T08:33:50.852624Z","steps":["trace[669445569] 'process raft request' (duration: 99.979236ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:50.852687Z","caller":"traceutil/trace.go:171","msg":"trace[1931796519] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"102.656077ms","start":"2026-06-10T08:33:50.749988Z","end":"2026-06-10T08:33:50.852644Z","steps":["trace[1931796519] 'process raft request' (duration: 10.626023ms)","trace[1931796519] 'compare' (duration: 91.776799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:33:52.828221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.700665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:52.828317Z","caller":"traceutil/trace.go:171","msg":"trace[574756443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"900.843227ms","start":"2026-06-10T08:33:51.927449Z","end":"2026-06-10T08:33:52.828292Z","steps":["trace[574756443] 'range keys from in-memory index tree' (duration: 900.644434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:52.828367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:51.927435Z","time spent":"900.919028ms","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:33:52.82856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.862578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-10T08:33:52.829439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.060786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:33:52.829508Z","caller":"traceutil/trace.go:171","msg":"trace[409879035] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:1121; }","duration":"261.166338ms","start":"2026-06-10T08:33:52.568324Z","end":"2026-06-10T08:33:52.82949Z","steps":["trace[409879035] 'range keys from in-memory index tree' (duration: 260.103617ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:52.829427Z","caller":"traceutil/trace.go:171","msg":"trace[659611359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1121; }","duration":"107.00864ms","start":"2026-06-10T08:33:52.722388Z","end":"2026-06-10T08:33:52.829397Z","steps":["trace[659611359] 'range keys from in-memory index tree' (duration: 105.749616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:53.223292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.952029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:33:53.223586Z","caller":"traceutil/trace.go:171","msg":"trace[672112887] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1167; }","duration":"367.266138ms","start":"2026-06-10T08:33:52.856297Z","end":"2026-06-10T08:33:53.223563Z","steps":["trace[672112887] 'read index received' (duration: 10.453338ms)","trace[672112887] 'applied index is now lower than readState.Index' (duration: 356.81169ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:53.223688Z","caller":"traceutil/trace.go:171","msg":"trace[1445970197] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"388.874331ms","start":"2026-06-10T08:33:52.834775Z","end":"2026-06-10T08:33:53.22365Z","steps":["trace[1445970197] 'process raft request' (duration: 388.635856ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:53.223696Z","caller":"traceutil/trace.go:171","msg":"trace[2038580335] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"388.908281ms","start":"2026-06-10T08:33:52.834717Z","end":"2026-06-10T08:33:53.223626Z","steps":["trace[2038580335] 'process raft request' (duration: 186.500994ms)","trace[2038580335] 'compare' (duration: 201.780906ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:33:53.223831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:52.834701Z","time spent":"389.074885ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:53.22384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:52.834769Z","time spent":"388.999333ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:53.223878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.413942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:33:53.223944Z","caller":"traceutil/trace.go:171","msg":"trace[613671986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"366.529874ms","start":"2026-06-10T08:33:52.857396Z","end":"2026-06-10T08:33:53.223926Z","steps":["trace[613671986] 'agreement among raft nodes before linearized reading' (duration: 366.365441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:53.223981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.662091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:53.224037Z","caller":"traceutil/trace.go:171","msg":"trace[1777888527] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"356.448103ms","start":"2026-06-10T08:33:52.867576Z","end":"2026-06-10T08:33:53.224024Z","steps":["trace[1777888527] 'process raft request' (duration: 355.938003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:53.224078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.594566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:33:53.224112Z","caller":"traceutil/trace.go:171","msg":"trace[2066226335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"366.648097ms","start":"2026-06-10T08:33:52.857455Z","end":"2026-06-10T08:33:53.224103Z","steps":["trace[2066226335] 'agreement among raft nodes before linearized reading' (duration: 366.552534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:53.224154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:52.857437Z","time spent":"366.710608ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:33:53.223988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:52.857376Z","time spent":"366.605115ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:33:53.224148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:52.867558Z","time spent":"356.533205ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:33:53.224038Z","caller":"traceutil/trace.go:171","msg":"trace[1750168917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"170.775505ms","start":"2026-06-10T08:33:53.053246Z","end":"2026-06-10T08:33:53.224022Z","steps":["trace[1750168917] 'agreement among raft nodes before linearized reading' (duration: 170.677583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:53.223887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.578894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:33:53.22448Z","caller":"traceutil/trace.go:171","msg":"trace[1537409404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"368.189357ms","start":"2026-06-10T08:33:52.856271Z","end":"2026-06-10T08:33:53.224461Z","steps":["trace[1537409404] 'agreement among raft nodes before linearized reading' (duration: 367.413221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:53.224562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:52.85626Z","time spent":"368.284378ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:33:53.491666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.198908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:33:53.491883Z","caller":"traceutil/trace.go:171","msg":"trace[1433764043] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1171; }","duration":"261.543004ms","start":"2026-06-10T08:33:53.230322Z","end":"2026-06-10T08:33:53.491865Z","steps":["trace[1433764043] 'read index received' (duration: 76.11674ms)","trace[1433764043] 'applied index is now lower than readState.Index' (duration: 185.425484ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:33:53.491952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.623516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T08:33:53.491988Z","caller":"traceutil/trace.go:171","msg":"trace[1973530059] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1127; }","duration":"261.670956ms","start":"2026-06-10T08:33:53.230307Z","end":"2026-06-10T08:33:53.491978Z","steps":["trace[1973530059] 'agreement among raft nodes before linearized reading' (duration: 261.608495ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:53.492086Z","caller":"traceutil/trace.go:171","msg":"trace[879927462] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"262.520953ms","start":"2026-06-10T08:33:53.22955Z","end":"2026-06-10T08:33:53.492071Z","steps":["trace[879927462] 'process raft request' (duration: 106.839467ms)","trace[879927462] 'compare' (duration: 155.027033ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:53.492189Z","caller":"traceutil/trace.go:171","msg":"trace[585501818] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"261.996283ms","start":"2026-06-10T08:33:53.230175Z","end":"2026-06-10T08:33:53.492171Z","steps":["trace[585501818] 'process raft request' (duration: 261.630786ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:53.515469Z","caller":"traceutil/trace.go:171","msg":"trace[190003691] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"205.404295ms","start":"2026-06-10T08:33:53.310043Z","end":"2026-06-10T08:33:53.515447Z","steps":["trace[190003691] 'process raft request' (duration: 205.303233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:55.604076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.52937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:33:55.604342Z","caller":"traceutil/trace.go:171","msg":"trace[1012804572] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1174; }","duration":"293.151296ms","start":"2026-06-10T08:33:55.311164Z","end":"2026-06-10T08:33:55.604315Z","steps":["trace[1012804572] 'read index received' (duration: 2.279474ms)","trace[1012804572] 'applied index is now lower than readState.Index' (duration: 290.869382ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:55.604385Z","caller":"traceutil/trace.go:171","msg":"trace[1135829832] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"370.824737ms","start":"2026-06-10T08:33:55.23352Z","end":"2026-06-10T08:33:55.604345Z","steps":["trace[1135829832] 'process raft request' (duration: 232.934989ms)","trace[1135829832] 'compare' (duration: 137.115793ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:33:55.605263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:55.233513Z","time spent":"370.934359ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:55.60561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.15646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:33:55.605674Z","caller":"traceutil/trace.go:171","msg":"trace[1342687995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"109.255252ms","start":"2026-06-10T08:33:55.496385Z","end":"2026-06-10T08:33:55.605641Z","steps":["trace[1342687995] 'agreement among raft nodes before linearized reading' (duration: 108.441417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:55.605734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.543453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:33:55.605841Z","caller":"traceutil/trace.go:171","msg":"trace[1252195119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"294.675566ms","start":"2026-06-10T08:33:55.311123Z","end":"2026-06-10T08:33:55.605799Z","steps":["trace[1252195119] 'agreement among raft nodes before linearized reading' (duration: 293.28021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:55.60709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.783531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:33:55.60717Z","caller":"traceutil/trace.go:171","msg":"trace[1423683317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1131; }","duration":"110.889883ms","start":"2026-06-10T08:33:55.496251Z","end":"2026-06-10T08:33:55.60714Z","steps":["trace[1423683317] 'agreement among raft nodes before linearized reading' (duration: 108.177451ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:55.853407Z","caller":"traceutil/trace.go:171","msg":"trace[1820748548] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"239.020873ms","start":"2026-06-10T08:33:55.614357Z","end":"2026-06-10T08:33:55.853377Z","steps":["trace[1820748548] 'process raft request' (duration: 238.970802ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:55.853489Z","caller":"traceutil/trace.go:171","msg":"trace[1258318609] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"239.302128ms","start":"2026-06-10T08:33:55.614148Z","end":"2026-06-10T08:33:55.85345Z","steps":["trace[1258318609] 'process raft request' (duration: 239.025963ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:55.858195Z","caller":"traceutil/trace.go:171","msg":"trace[1650999837] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"164.472324ms","start":"2026-06-10T08:33:55.693693Z","end":"2026-06-10T08:33:55.858166Z","steps":["trace[1650999837] 'process raft request' (duration: 164.331731ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:07.464976Z","caller":"traceutil/trace.go:171","msg":"trace[681676914] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"101.090537ms","start":"2026-06-10T08:34:07.363861Z","end":"2026-06-10T08:34:07.464952Z","steps":["trace[681676914] 'process raft request' (duration: 100.941334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:09.91997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.112076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:09.920038Z","caller":"traceutil/trace.go:171","msg":"trace[2068501457] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1227; }","duration":"346.236227ms","start":"2026-06-10T08:34:09.573784Z","end":"2026-06-10T08:34:09.92002Z","steps":["trace[2068501457] 'count revisions from in-memory index tree' (duration: 345.970322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:09.920077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:09.573765Z","time spent":"346.303108ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:34:09.92033Z","caller":"traceutil/trace.go:171","msg":"trace[1377149509] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"190.407779ms","start":"2026-06-10T08:34:09.72989Z","end":"2026-06-10T08:34:09.920298Z","steps":["trace[1377149509] 'read index received' (duration: 170.110982ms)","trace[1377149509] 'applied index is now lower than readState.Index' (duration: 20.295086ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:09.920463Z","caller":"traceutil/trace.go:171","msg":"trace[1664252430] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"238.867811ms","start":"2026-06-10T08:34:09.681579Z","end":"2026-06-10T08:34:09.920447Z","steps":["trace[1664252430] 'process raft request' (duration: 218.433503ms)","trace[1664252430] 'compare' (duration: 19.852568ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:09.920556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.572185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:34:09.920589Z","caller":"traceutil/trace.go:171","msg":"trace[1068734357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"112.642206ms","start":"2026-06-10T08:34:09.807938Z","end":"2026-06-10T08:34:09.920581Z","steps":["trace[1068734357] 'agreement among raft nodes before linearized reading' (duration: 112.550594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:09.920691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.802877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T08:34:09.920714Z","caller":"traceutil/trace.go:171","msg":"trace[1951968357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"190.860947ms","start":"2026-06-10T08:34:09.729848Z","end":"2026-06-10T08:34:09.920709Z","steps":["trace[1951968357] 'agreement among raft nodes before linearized reading' (duration: 190.803375ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:12.142651Z","caller":"traceutil/trace.go:171","msg":"trace[1081343633] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"614.540089ms","start":"2026-06-10T08:34:11.528083Z","end":"2026-06-10T08:34:12.142623Z","steps":["trace[1081343633] 'process raft request' (duration: 614.406957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:12.142811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:11.528064Z","time spent":"614.662363ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:34:13.193935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.30204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:34:13.194005Z","caller":"traceutil/trace.go:171","msg":"trace[878250395] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"1.263392115s","start":"2026-06-10T08:34:11.930602Z","end":"2026-06-10T08:34:13.193994Z","steps":["trace[878250395] 'read index received' (duration: 212.464199ms)","trace[878250395] 'applied index is now lower than readState.Index' (duration: 1.050926876s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:13.194142Z","caller":"traceutil/trace.go:171","msg":"trace[2118571449] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.268312098s","start":"2026-06-10T08:34:11.925821Z","end":"2026-06-10T08:34:13.194133Z","steps":["trace[2118571449] 'process raft request' (duration: 371.760423ms)","trace[2118571449] 'compare' (duration: 895.589747ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:13.194201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:11.925799Z","time spent":"1.26836614s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:34:13.194331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263730202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:13.194354Z","caller":"traceutil/trace.go:171","msg":"trace[1407121695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"1.263785383s","start":"2026-06-10T08:34:11.930562Z","end":"2026-06-10T08:34:13.194348Z","steps":["trace[1407121695] 'agreement among raft nodes before linearized reading' (duration: 1.263747962s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.194371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:11.930548Z","time spent":"1.263819444s","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:34:13.194543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263851594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:34:13.194568Z","caller":"traceutil/trace.go:171","msg":"trace[336944037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"1.263912946s","start":"2026-06-10T08:34:11.930649Z","end":"2026-06-10T08:34:13.194562Z","steps":["trace[336944037] 'agreement among raft nodes before linearized reading' (duration: 1.263834084s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.194585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:11.930627Z","time spent":"1.263953696s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:34:13.194712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.655159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:13.194734Z","caller":"traceutil/trace.go:171","msg":"trace[2045709410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"141.69486ms","start":"2026-06-10T08:34:13.053034Z","end":"2026-06-10T08:34:13.194729Z","steps":["trace[2045709410] 'agreement among raft nodes before linearized reading' (duration: 141.665649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.194816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.293492ms","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-06-10T08:34:13.19484Z","caller":"traceutil/trace.go:171","msg":"trace[1273338784] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1234; }","duration":"522.335664ms","start":"2026-06-10T08:34:12.672497Z","end":"2026-06-10T08:34:13.194832Z","steps":["trace[1273338784] 'agreement among raft nodes before linearized reading' (duration: 522.304913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.194859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:12.672486Z","time spent":"522.368574ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:34:13.194969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261812875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T08:34:13.194991Z","caller":"traceutil/trace.go:171","msg":"trace[1162808850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.261846516s","start":"2026-06-10T08:34:11.933139Z","end":"2026-06-10T08:34:13.194986Z","steps":["trace[1162808850] 'agreement among raft nodes before linearized reading' (duration: 1.261792465s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.194991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.761248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:34:13.195047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.887968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:34:13.195057Z","caller":"traceutil/trace.go:171","msg":"trace[183138694] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1234; }","duration":"685.890561ms","start":"2026-06-10T08:34:12.509149Z","end":"2026-06-10T08:34:13.19504Z","steps":["trace[183138694] 'agreement among raft nodes before linearized reading' (duration: 685.744808ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:13.195079Z","caller":"traceutil/trace.go:171","msg":"trace[1441700331] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:1234; }","duration":"624.950819ms","start":"2026-06-10T08:34:12.57012Z","end":"2026-06-10T08:34:13.195071Z","steps":["trace[1441700331] 'agreement among raft nodes before linearized reading' (duration: 624.793196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.195098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:12.570109Z","time spent":"624.98352ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-06-10T08:34:13.195009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:11.933121Z","time spent":"1.261882687s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:13.195094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:12.509123Z","time spent":"685.962281ms","remote":"127.0.0.1:33430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:34:13.195329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182604535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:34:13.195359Z","caller":"traceutil/trace.go:171","msg":"trace[525567611] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1234; }","duration":"1.182669476s","start":"2026-06-10T08:34:12.012683Z","end":"2026-06-10T08:34:13.195352Z","steps":["trace[525567611] 'agreement among raft nodes before linearized reading' (duration: 1.182619115s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.195379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:12.012665Z","time spent":"1.182709137s","remote":"127.0.0.1:55382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:34:13.195382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182058595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-10T08:34:13.195441Z","caller":"traceutil/trace.go:171","msg":"trace[621719477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.182148617s","start":"2026-06-10T08:34:12.013279Z","end":"2026-06-10T08:34:13.195428Z","steps":["trace[621719477] 'agreement among raft nodes before linearized reading' (duration: 1.181972703s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.195467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:12.013272Z","time spent":"1.182191087s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:13.195632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216580233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:34:13.195662Z","caller":"traceutil/trace.go:171","msg":"trace[554908774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.216637455s","start":"2026-06-10T08:34:11.979017Z","end":"2026-06-10T08:34:13.195655Z","steps":["trace[554908774] 'agreement among raft nodes before linearized reading' (duration: 1.216556642s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:13.195681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:11.979003Z","time spent":"1.216672605s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T08:34:25.385538Z","caller":"traceutil/trace.go:171","msg":"trace[475837907] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"145.885344ms","start":"2026-06-10T08:34:25.238884Z","end":"2026-06-10T08:34:25.384769Z","steps":["trace[475837907] 'process raft request' (duration: 145.597819ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:28.35061Z","caller":"traceutil/trace.go:171","msg":"trace[965922543] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"134.369129ms","start":"2026-06-10T08:34:28.216214Z","end":"2026-06-10T08:34:28.350584Z","steps":["trace[965922543] 'process raft request' (duration: 134.230046ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:29.001033Z","caller":"traceutil/trace.go:171","msg":"trace[582699839] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"477.767725ms","start":"2026-06-10T08:34:28.523228Z","end":"2026-06-10T08:34:29.000996Z","steps":["trace[582699839] 'process raft request' (duration: 477.516901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:29.001231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:28.523196Z","time spent":"477.934439ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:34:30.125966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.049729ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:30.126052Z","caller":"traceutil/trace.go:171","msg":"trace[1965854516] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1296; }","duration":"734.144591ms","start":"2026-06-10T08:34:29.391894Z","end":"2026-06-10T08:34:30.126039Z","steps":["trace[1965854516] 'range keys from in-memory index tree' (duration: 734.036148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:30.899704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597237364s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T08:34:30.899951Z","caller":"traceutil/trace.go:171","msg":"trace[615889917] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1349; }","duration":"1.969594668s","start":"2026-06-10T08:34:28.930318Z","end":"2026-06-10T08:34:30.899913Z","steps":["trace[615889917] 'read index received' (duration: 70.408077ms)","trace[615889917] 'applied index is now lower than readState.Index' (duration: 1.89918304s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:30.900063Z","caller":"traceutil/trace.go:171","msg":"trace[1574457091] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.499130114s","start":"2026-06-10T08:34:29.400852Z","end":"2026-06-10T08:34:30.899982Z","steps":["trace[1574457091] 'process raft request' (duration: 1.49895425s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:30.900285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:29.400818Z","time spent":"1.499340428s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:34:30.961888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031553369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:34:30.96194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548793708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:34:30.961991Z","caller":"traceutil/trace.go:171","msg":"trace[152966363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"2.031715274s","start":"2026-06-10T08:34:28.930247Z","end":"2026-06-10T08:34:30.961962Z","steps":["trace[152966363] 'agreement among raft nodes before linearized reading' (duration: 1.969809322s)","trace[152966363] 'range keys from in-memory index tree' (duration: 61.763418ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:30.962024Z","caller":"traceutil/trace.go:171","msg":"trace[1701612758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"1.54890241s","start":"2026-06-10T08:34:29.413092Z","end":"2026-06-10T08:34:30.961994Z","steps":["trace[1701612758] 'agreement among raft nodes before linearized reading' (duration: 1.487015488s)","trace[1701612758] 'range keys from in-memory index tree' (duration: 61.691617ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:30.962022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548966171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T08:34:30.962043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:28.930217Z","time spent":"2.031817335s","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T08:34:30.962074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:29.413084Z","time spent":"1.548978981s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T08:34:30.962098Z","caller":"traceutil/trace.go:171","msg":"trace[1449426844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"1.549096963s","start":"2026-06-10T08:34:29.412972Z","end":"2026-06-10T08:34:30.962069Z","steps":["trace[1449426844] 'agreement among raft nodes before linearized reading' (duration: 1.48714274s)","trace[1449426844] 'range keys from in-memory index tree' (duration: 61.747208ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:30.962107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552643012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-10T08:34:30.962161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:29.412951Z","time spent":"1.549200296s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:34:30.962165Z","caller":"traceutil/trace.go:171","msg":"trace[1925277192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1297; }","duration":"1.552732184s","start":"2026-06-10T08:34:29.409416Z","end":"2026-06-10T08:34:30.962148Z","steps":["trace[1925277192] 'agreement among raft nodes before linearized reading' (duration: 1.490663409s)","trace[1925277192] 'range keys from in-memory index tree' (duration: 61.903652ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:30.962197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:29.409407Z","time spent":"1.552784724s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:30.962205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.531937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:30.962244Z","caller":"traceutil/trace.go:171","msg":"trace[2079268202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1297; }","duration":"875.624029ms","start":"2026-06-10T08:34:30.086606Z","end":"2026-06-10T08:34:30.96223Z","steps":["trace[2079268202] 'agreement among raft nodes before linearized reading' (duration: 813.511373ms)","trace[2079268202] 'count revisions from in-memory index tree' (duration: 62.033025ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:30.962279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:30.086581Z","time spent":"875.68778ms","remote":"127.0.0.1:33446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:34:30.962289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548353818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T08:34:30.962308Z","caller":"traceutil/trace.go:171","msg":"trace[2139974789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"1.548385859s","start":"2026-06-10T08:34:29.413915Z","end":"2026-06-10T08:34:30.962301Z","steps":["trace[2139974789] 'agreement among raft nodes before linearized reading' (duration: 1.486190921s)","trace[2139974789] 'range keys from in-memory index tree' (duration: 62.152807ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:30.962321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:29.413907Z","time spent":"1.54841096s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:30.962348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.220658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T08:34:30.962383Z","caller":"traceutil/trace.go:171","msg":"trace[257065016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1297; }","duration":"608.281ms","start":"2026-06-10T08:34:30.354091Z","end":"2026-06-10T08:34:30.962372Z","steps":["trace[257065016] 'agreement among raft nodes before linearized reading' (duration: 546.009061ms)","trace[257065016] 'range keys from in-memory index tree' (duration: 62.182387ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:30.962418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:30.354072Z","time spent":"608.339221ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:34:31.134327Z","caller":"traceutil/trace.go:171","msg":"trace[1211583648] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1352; }","duration":"161.967598ms","start":"2026-06-10T08:34:30.972335Z","end":"2026-06-10T08:34:31.134302Z","steps":["trace[1211583648] 'read index received' (duration: 3.458087ms)","trace[1211583648] 'applied index is now lower than readState.Index' (duration: 158.508531ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:31.134549Z","caller":"traceutil/trace.go:171","msg":"trace[1156470867] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"161.866537ms","start":"2026-06-10T08:34:30.972642Z","end":"2026-06-10T08:34:31.134508Z","steps":["trace[1156470867] 'process raft request' (duration: 161.550541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:31.1346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.222264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:31.134624Z","caller":"traceutil/trace.go:171","msg":"trace[260844822] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"161.401917ms","start":"2026-06-10T08:34:30.973174Z","end":"2026-06-10T08:34:31.134576Z","steps":["trace[260844822] 'process raft request' (duration: 161.052341ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:31.134701Z","caller":"traceutil/trace.go:171","msg":"trace[1510217022] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"163.5901ms","start":"2026-06-10T08:34:30.971098Z","end":"2026-06-10T08:34:31.134688Z","steps":["trace[1510217022] 'process raft request' (duration: 101.490916ms)","trace[1510217022] 'compare' (duration: 61.351799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:31.134733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.936014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:34:31.134752Z","caller":"traceutil/trace.go:171","msg":"trace[1359182475] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1303; }","duration":"100.981194ms","start":"2026-06-10T08:34:31.033766Z","end":"2026-06-10T08:34:31.134747Z","steps":["trace[1359182475] 'agreement among raft nodes before linearized reading' (duration: 100.944784ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:31.134644Z","caller":"traceutil/trace.go:171","msg":"trace[1841373019] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"163.469428ms","start":"2026-06-10T08:34:30.971136Z","end":"2026-06-10T08:34:31.134605Z","steps":["trace[1841373019] 'process raft request' (duration: 162.952947ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:31.134659Z","caller":"traceutil/trace.go:171","msg":"trace[1847294310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"162.329285ms","start":"2026-06-10T08:34:30.972316Z","end":"2026-06-10T08:34:31.134645Z","steps":["trace[1847294310] 'agreement among raft nodes before linearized reading' (duration: 162.185633ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:31.135076Z","caller":"traceutil/trace.go:171","msg":"trace[487886897] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"163.866675ms","start":"2026-06-10T08:34:30.971202Z","end":"2026-06-10T08:34:31.135069Z","steps":["trace[487886897] 'process raft request' (duration: 162.933868ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:32.491243Z","caller":"traceutil/trace.go:171","msg":"trace[640602128] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"199.588004ms","start":"2026-06-10T08:34:32.291632Z","end":"2026-06-10T08:34:32.49122Z","steps":["trace[640602128] 'read index received' (duration: 199.412551ms)","trace[640602128] 'applied index is now lower than readState.Index' (duration: 174.493µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:32.491329Z","caller":"traceutil/trace.go:171","msg":"trace[874748096] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"816.511442ms","start":"2026-06-10T08:34:31.674783Z","end":"2026-06-10T08:34:32.491294Z","steps":["trace[874748096] 'process raft request' (duration: 816.310768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:32.4915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:31.674765Z","time spent":"816.641234ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:34:32.491568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.91128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"warn","ts":"2026-06-10T08:34:32.491494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.786792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:34:32.491676Z","caller":"traceutil/trace.go:171","msg":"trace[772125732] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1305; }","duration":"145.008457ms","start":"2026-06-10T08:34:32.346646Z","end":"2026-06-10T08:34:32.491655Z","steps":["trace[772125732] 'agreement among raft nodes before linearized reading' (duration: 144.726981ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:32.491623Z","caller":"traceutil/trace.go:171","msg":"trace[28784198] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1305; }","duration":"200.009893ms","start":"2026-06-10T08:34:32.2916Z","end":"2026-06-10T08:34:32.491609Z","steps":["trace[28784198] 'agreement among raft nodes before linearized reading' (duration: 199.741758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.228404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.976967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:34:33.228507Z","caller":"traceutil/trace.go:171","msg":"trace[655333280] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"732.527569ms","start":"2026-06-10T08:34:32.495959Z","end":"2026-06-10T08:34:33.228486Z","steps":["trace[655333280] 'process raft request' (duration: 155.40448ms)","trace[655333280] 'compare' (duration: 576.66637ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:33.228559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:32.495929Z","time spent":"732.608521ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:34:33.680223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.857149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:34:33.680381Z","caller":"traceutil/trace.go:171","msg":"trace[128671029] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1360; }","duration":"1.109035394s","start":"2026-06-10T08:34:32.571329Z","end":"2026-06-10T08:34:33.680365Z","steps":["trace[128671029] 'read index received' (duration: 80.048316ms)","trace[128671029] 'applied index is now lower than readState.Index' (duration: 1.028985738s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:33.680465Z","caller":"traceutil/trace.go:171","msg":"trace[1561838029] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.179977262s","start":"2026-06-10T08:34:32.500476Z","end":"2026-06-10T08:34:33.680453Z","steps":["trace[1561838029] 'process raft request' (duration: 911.811337ms)","trace[1561838029] 'compare' (duration: 267.754787ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:33.680527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:32.500459Z","time spent":"1.180036554s","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:34:33.680698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.96449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:34:33.680703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10934534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:34:33.680765Z","caller":"traceutil/trace.go:171","msg":"trace[10929363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"751.064932ms","start":"2026-06-10T08:34:32.929686Z","end":"2026-06-10T08:34:33.680751Z","steps":["trace[10929363] 'agreement among raft nodes before linearized reading' (duration: 750.950539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.680782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.32837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:34:33.68078Z","caller":"traceutil/trace.go:171","msg":"trace[357291464] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:1307; }","duration":"1.109477113s","start":"2026-06-10T08:34:32.571282Z","end":"2026-06-10T08:34:33.680759Z","steps":["trace[357291464] 'agreement among raft nodes before linearized reading' (duration: 1.109256519s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.680801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:32.929675Z","time spent":"751.119892ms","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T08:34:33.68082Z","caller":"traceutil/trace.go:171","msg":"trace[624732265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"540.420642ms","start":"2026-06-10T08:34:33.14039Z","end":"2026-06-10T08:34:33.68081Z","steps":["trace[624732265] 'agreement among raft nodes before linearized reading' (duration: 540.30904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.680829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:32.571266Z","time spent":"1.109554484s","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-06-10T08:34:33.680866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:33.140379Z","time spent":"540.461322ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:33.680883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.876089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:34:33.680957Z","caller":"traceutil/trace.go:171","msg":"trace[2110565051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"542.010342ms","start":"2026-06-10T08:34:33.138929Z","end":"2026-06-10T08:34:33.68094Z","steps":["trace[2110565051] 'agreement among raft nodes before linearized reading' (duration: 541.778788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.681006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:33.138905Z","time spent":"542.091495ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T08:34:33.68104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.685887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:34:33.68107Z","caller":"traceutil/trace.go:171","msg":"trace[573551014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"701.773128ms","start":"2026-06-10T08:34:32.979289Z","end":"2026-06-10T08:34:33.681062Z","steps":["trace[573551014] 'agreement among raft nodes before linearized reading' (duration: 701.663746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.681096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:32.979257Z","time spent":"701.833189ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:34:33.681175Z","caller":"traceutil/trace.go:171","msg":"trace[1031757601] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"776.040331ms","start":"2026-06-10T08:34:32.905126Z","end":"2026-06-10T08:34:33.681166Z","steps":["trace[1031757601] 'process raft request' (duration: 775.206445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.681201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.955353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-10T08:34:33.681228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.149206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:34:33.68124Z","caller":"traceutil/trace.go:171","msg":"trace[1483239258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"540.033834ms","start":"2026-06-10T08:34:33.141196Z","end":"2026-06-10T08:34:33.681229Z","steps":["trace[1483239258] 'agreement among raft nodes before linearized reading' (duration: 539.935262ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:33.681279Z","caller":"traceutil/trace.go:171","msg":"trace[454970442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"541.239387ms","start":"2026-06-10T08:34:33.140027Z","end":"2026-06-10T08:34:33.681266Z","steps":["trace[454970442] 'agreement among raft nodes before linearized reading' (duration: 541.068684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.681322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:33.140015Z","time spent":"541.299089ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:33.681282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:33.141173Z","time spent":"540.102135ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:33.681245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:32.905102Z","time spent":"776.095352ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:34:33.92455Z","caller":"traceutil/trace.go:171","msg":"trace[2112498799] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"237.316142ms","start":"2026-06-10T08:34:33.687196Z","end":"2026-06-10T08:34:33.924512Z","steps":["trace[2112498799] 'process raft request' (duration: 237.021676ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:33.924664Z","caller":"traceutil/trace.go:171","msg":"trace[1612551253] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"234.427646ms","start":"2026-06-10T08:34:33.690217Z","end":"2026-06-10T08:34:33.924645Z","steps":["trace[1612551253] 'process raft request' (duration: 234.396416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:33.925108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.521165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-10T08:34:33.925166Z","caller":"traceutil/trace.go:171","msg":"trace[342602998] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1313; }","duration":"240.612787ms","start":"2026-06-10T08:34:33.68454Z","end":"2026-06-10T08:34:33.925153Z","steps":["trace[342602998] 'agreement among raft nodes before linearized reading' (duration: 240.447864ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:33.924758Z","caller":"traceutil/trace.go:171","msg":"trace[690930837] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"237.363764ms","start":"2026-06-10T08:34:33.687384Z","end":"2026-06-10T08:34:33.924748Z","steps":["trace[690930837] 'process raft request' (duration: 237.143189ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:33.924882Z","caller":"traceutil/trace.go:171","msg":"trace[771676857] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"236.582227ms","start":"2026-06-10T08:34:33.688243Z","end":"2026-06-10T08:34:33.924825Z","steps":["trace[771676857] 'process raft request' (duration: 236.324032ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:33.925008Z","caller":"traceutil/trace.go:171","msg":"trace[1685210260] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"236.16759ms","start":"2026-06-10T08:34:33.688796Z","end":"2026-06-10T08:34:33.924963Z","steps":["trace[1685210260] 'process raft request' (duration: 235.795093ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:33.924937Z","caller":"traceutil/trace.go:171","msg":"trace[1463308928] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1369; }","duration":"203.145044ms","start":"2026-06-10T08:34:33.721749Z","end":"2026-06-10T08:34:33.924894Z","steps":["trace[1463308928] 'read index received' (duration: 202.716735ms)","trace[1463308928] 'applied index is now lower than readState.Index' (duration: 426.939µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:33.983708Z","caller":"traceutil/trace.go:171","msg":"trace[1240229772] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"260.944035ms","start":"2026-06-10T08:34:33.722741Z","end":"2026-06-10T08:34:33.983685Z","steps":["trace[1240229772] 'process raft request' (duration: 260.827243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:34.140702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.963571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:34:34.140826Z","caller":"traceutil/trace.go:171","msg":"trace[1167165725] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"154.549042ms","start":"2026-06-10T08:34:33.986259Z","end":"2026-06-10T08:34:34.140808Z","steps":["trace[1167165725] 'read index received' (duration: 44.316846ms)","trace[1167165725] 'applied index is now lower than readState.Index' (duration: 110.230866ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:34.140903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.652465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-10T08:34:34.140928Z","caller":"traceutil/trace.go:171","msg":"trace[1135762313] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1315; }","duration":"154.717486ms","start":"2026-06-10T08:34:33.986202Z","end":"2026-06-10T08:34:34.140919Z","steps":["trace[1135762313] 'agreement among raft nodes before linearized reading' (duration: 154.653145ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:34.140977Z","caller":"traceutil/trace.go:171","msg":"trace[1908390563] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"211.562099ms","start":"2026-06-10T08:34:33.929363Z","end":"2026-06-10T08:34:34.140925Z","steps":["trace[1908390563] 'process raft request' (duration: 101.272111ms)","trace[1908390563] 'compare' (duration: 109.705507ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:35.889719Z","caller":"traceutil/trace.go:171","msg":"trace[1179432729] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"199.626645ms","start":"2026-06-10T08:34:35.690071Z","end":"2026-06-10T08:34:35.889698Z","steps":["trace[1179432729] 'process raft request' (duration: 199.482213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:36.124466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.222139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-10T08:34:36.124507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.856651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:34:36.124551Z","caller":"traceutil/trace.go:171","msg":"trace[1542089815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"194.339951ms","start":"2026-06-10T08:34:35.93019Z","end":"2026-06-10T08:34:36.12453Z","steps":["trace[1542089815] 'range keys from in-memory index tree' (duration: 194.082817ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:36.124584Z","caller":"traceutil/trace.go:171","msg":"trace[1638673652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"194.954873ms","start":"2026-06-10T08:34:35.929609Z","end":"2026-06-10T08:34:36.124564Z","steps":["trace[1638673652] 'range keys from in-memory index tree' (duration: 194.705769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:36.124602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.452094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-10T08:34:36.124507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.443144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-10T08:34:36.124682Z","caller":"traceutil/trace.go:171","msg":"trace[1870054662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1316; }","duration":"195.582096ms","start":"2026-06-10T08:34:35.929077Z","end":"2026-06-10T08:34:36.124659Z","steps":["trace[1870054662] 'range keys from in-memory index tree' (duration: 195.286051ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:36.124688Z","caller":"traceutil/trace.go:171","msg":"trace[1962241649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"194.660698ms","start":"2026-06-10T08:34:35.930012Z","end":"2026-06-10T08:34:36.124673Z","steps":["trace[1962241649] 'range keys from in-memory index tree' (duration: 194.327822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:36.124763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.688189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-10T08:34:36.124852Z","caller":"traceutil/trace.go:171","msg":"trace[981008966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"193.812191ms","start":"2026-06-10T08:34:35.931018Z","end":"2026-06-10T08:34:36.124831Z","steps":["trace[981008966] 'range keys from in-memory index tree' (duration: 193.530775ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:36.454426Z","caller":"traceutil/trace.go:171","msg":"trace[736459373] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"262.564616ms","start":"2026-06-10T08:34:36.191833Z","end":"2026-06-10T08:34:36.454398Z","steps":["trace[736459373] 'process raft request' (duration: 262.305091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:45.718905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729084380,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:34:45.956901Z","caller":"traceutil/trace.go:171","msg":"trace[1403524532] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"738.151749ms","start":"2026-06-10T08:34:45.218723Z","end":"2026-06-10T08:34:45.956875Z","steps":["trace[1403524532] 'read index received' (duration: 738.031546ms)","trace[1403524532] 'applied index is now lower than readState.Index' (duration: 119.333µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:45.956916Z","caller":"traceutil/trace.go:171","msg":"trace[746433310] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"773.409249ms","start":"2026-06-10T08:34:45.183482Z","end":"2026-06-10T08:34:45.956892Z","steps":["trace[746433310] 'process raft request' (duration: 773.238336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:45.957038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.275812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:34:45.957065Z","caller":"traceutil/trace.go:171","msg":"trace[692868297] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1396; }","duration":"738.373843ms","start":"2026-06-10T08:34:45.218685Z","end":"2026-06-10T08:34:45.957059Z","steps":["trace[692868297] 'agreement among raft nodes before linearized reading' (duration: 738.277071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:45.957087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:45.218673Z","time spent":"738.407303ms","remote":"127.0.0.1:33458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:34:45.957101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:45.183466Z","time spent":"773.555681ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:34:46.457245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729084383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:34:46.958312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374235729084383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:34:47.019787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063059684s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T08:34:47.035888Z","caller":"traceutil/trace.go:171","msg":"trace[1802485326] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1454; }","duration":"1.078899876s","start":"2026-06-10T08:34:45.956967Z","end":"2026-06-10T08:34:47.035867Z","steps":["trace[1802485326] 'read index received' (duration: 1.063112427s)","trace[1802485326] 'applied index is now lower than readState.Index' (duration: 15.786789ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:34:47.03593Z","caller":"traceutil/trace.go:171","msg":"trace[836097451] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"1.116044071s","start":"2026-06-10T08:34:45.919843Z","end":"2026-06-10T08:34:47.035887Z","steps":["trace[836097451] 'process raft request' (duration: 1.100222472s)","trace[836097451] 'compare' (duration: 15.236347ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:34:47.03629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.487875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-10T08:34:47.036343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.06426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-10T08:34:47.036357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.547468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:34:47.036389Z","caller":"traceutil/trace.go:171","msg":"trace[1379952392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1398; }","duration":"737.62883ms","start":"2026-06-10T08:34:46.298751Z","end":"2026-06-10T08:34:47.03638Z","steps":["trace[1379952392] 'agreement among raft nodes before linearized reading' (duration: 737.502527ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:47.036389Z","caller":"traceutil/trace.go:171","msg":"trace[645626998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1398; }","duration":"733.143062ms","start":"2026-06-10T08:34:46.303234Z","end":"2026-06-10T08:34:47.036377Z","steps":["trace[645626998] 'agreement among raft nodes before linearized reading' (duration: 732.982829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:47.036411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:46.298729Z","time spent":"737.67684ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:34:47.036421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:46.303215Z","time spent":"733.199392ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:47.036434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.739429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T08:34:47.036463Z","caller":"traceutil/trace.go:171","msg":"trace[1734667250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1398; }","duration":"765.79783ms","start":"2026-06-10T08:34:46.270657Z","end":"2026-06-10T08:34:47.036455Z","steps":["trace[1734667250] 'agreement among raft nodes before linearized reading' (duration: 765.715008ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:47.036348Z","caller":"traceutil/trace.go:171","msg":"trace[119492614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1398; }","duration":"831.597368ms","start":"2026-06-10T08:34:46.204732Z","end":"2026-06-10T08:34:47.03633Z","steps":["trace[119492614] 'agreement among raft nodes before linearized reading' (duration: 831.397684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:47.036508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.59899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T08:34:47.036499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:46.27064Z","time spent":"765.852682ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:34:47.036527Z","caller":"traceutil/trace.go:171","msg":"trace[1698527406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1398; }","duration":"735.64153ms","start":"2026-06-10T08:34:46.30088Z","end":"2026-06-10T08:34:47.036521Z","steps":["trace[1698527406] 'agreement among raft nodes before linearized reading' (duration: 735.579559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:47.03653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:46.2047Z","time spent":"831.821681ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:47.036547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:46.300869Z","time spent":"735.672721ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:34:47.036606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.240586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:34:47.036646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306821364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T08:34:47.036683Z","caller":"traceutil/trace.go:171","msg":"trace[324452794] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1398; }","duration":"1.306893905s","start":"2026-06-10T08:34:45.729778Z","end":"2026-06-10T08:34:47.036672Z","steps":["trace[324452794] 'agreement among raft nodes before linearized reading' (duration: 1.306816334s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:34:47.036687Z","caller":"traceutil/trace.go:171","msg":"trace[1125385939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1398; }","duration":"110.363938ms","start":"2026-06-10T08:34:46.926305Z","end":"2026-06-10T08:34:47.036669Z","steps":["trace[1125385939] 'agreement among raft nodes before linearized reading' (duration: 110.203165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:34:47.036708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:45.729755Z","time spent":"1.306946876s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-10T08:34:47.036101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:34:45.919822Z","time spent":"1.116188405s","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:35:12.692752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.896586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:35:12.692821Z","caller":"traceutil/trace.go:171","msg":"trace[1853580851] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:1517; }","duration":"118.013008ms","start":"2026-06-10T08:35:12.574792Z","end":"2026-06-10T08:35:12.692805Z","steps":["trace[1853580851] 'range keys from in-memory index tree' (duration: 117.745383ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:12.91497Z","caller":"traceutil/trace.go:171","msg":"trace[71836295] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"216.329611ms","start":"2026-06-10T08:35:12.698594Z","end":"2026-06-10T08:35:12.914924Z","steps":["trace[71836295] 'process raft request' (duration: 160.435048ms)","trace[71836295] 'compare' (duration: 55.675739ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:12.915747Z","caller":"traceutil/trace.go:171","msg":"trace[1742424421] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"182.121993ms","start":"2026-06-10T08:35:12.73358Z","end":"2026-06-10T08:35:12.915702Z","steps":["trace[1742424421] 'process raft request' (duration: 181.274736ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:13.426377Z","caller":"traceutil/trace.go:171","msg":"trace[1445884259] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"127.349401ms","start":"2026-06-10T08:35:13.298992Z","end":"2026-06-10T08:35:13.426342Z","steps":["trace[1445884259] 'process raft request' (duration: 40.88624ms)","trace[1445884259] 'compare' (duration: 86.319518ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:13.426505Z","caller":"traceutil/trace.go:171","msg":"trace[297535112] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1584; }","duration":"124.018425ms","start":"2026-06-10T08:35:13.302451Z","end":"2026-06-10T08:35:13.42647Z","steps":["trace[297535112] 'read index received' (duration: 37.392282ms)","trace[297535112] 'applied index is now lower than readState.Index' (duration: 86.622803ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:35:13.426817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.339542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T08:35:13.426867Z","caller":"traceutil/trace.go:171","msg":"trace[850520047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1521; }","duration":"124.419784ms","start":"2026-06-10T08:35:13.302433Z","end":"2026-06-10T08:35:13.426852Z","steps":["trace[850520047] 'agreement among raft nodes before linearized reading' (duration: 124.235541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:35:13.427204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.03989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-10T08:35:13.427328Z","caller":"traceutil/trace.go:171","msg":"trace[177400582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1521; }","duration":"115.241614ms","start":"2026-06-10T08:35:13.312058Z","end":"2026-06-10T08:35:13.4273Z","steps":["trace[177400582] 'agreement among raft nodes before linearized reading' (duration: 114.733554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:35:13.827834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.916214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-10T08:35:13.827923Z","caller":"traceutil/trace.go:171","msg":"trace[927077765] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1526; }","duration":"122.061227ms","start":"2026-06-10T08:35:13.705837Z","end":"2026-06-10T08:35:13.827899Z","steps":["trace[927077765] 'range keys from in-memory index tree' (duration: 121.725231ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:15.425503Z","caller":"traceutil/trace.go:171","msg":"trace[74499111] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"208.826295ms","start":"2026-06-10T08:35:15.21665Z","end":"2026-06-10T08:35:15.425476Z","steps":["trace[74499111] 'process raft request' (duration: 208.673532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:35:15.61674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.920075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:35:15.616865Z","caller":"traceutil/trace.go:171","msg":"trace[1289907909] linearizableReadLoop","detail":"{readStateIndex:1593; appliedIndex:1592; }","duration":"148.968065ms","start":"2026-06-10T08:35:15.467875Z","end":"2026-06-10T08:35:15.616843Z","steps":["trace[1289907909] 'read index received' (duration: 23.780105ms)","trace[1289907909] 'applied index is now lower than readState.Index' (duration: 125.18586ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:15.616976Z","caller":"traceutil/trace.go:171","msg":"trace[1839377766] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"180.772366ms","start":"2026-06-10T08:35:15.436184Z","end":"2026-06-10T08:35:15.616956Z","steps":["trace[1839377766] 'process raft request' (duration: 55.554346ms)","trace[1839377766] 'compare' (duration: 124.774952ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:35:15.617308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.438413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:35:15.61738Z","caller":"traceutil/trace.go:171","msg":"trace[1719386605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1529; }","duration":"149.553046ms","start":"2026-06-10T08:35:15.467807Z","end":"2026-06-10T08:35:15.61736Z","steps":["trace[1719386605] 'agreement among raft nodes before linearized reading' (duration: 149.417363ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:15.617701Z","caller":"traceutil/trace.go:171","msg":"trace[2122050101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1529; }","duration":"100.005426ms","start":"2026-06-10T08:35:15.517681Z","end":"2026-06-10T08:35:15.617686Z","steps":["trace[2122050101] 'agreement among raft nodes before linearized reading' (duration: 99.928914ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:15.82043Z","caller":"traceutil/trace.go:171","msg":"trace[383717022] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"197.589775ms","start":"2026-06-10T08:35:15.622813Z","end":"2026-06-10T08:35:15.820403Z","steps":["trace[383717022] 'process raft request' (duration: 197.510304ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:15.820621Z","caller":"traceutil/trace.go:171","msg":"trace[950293866] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"197.734988ms","start":"2026-06-10T08:35:15.622868Z","end":"2026-06-10T08:35:15.820603Z","steps":["trace[950293866] 'process raft request' (duration: 197.493644ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:15.820849Z","caller":"traceutil/trace.go:171","msg":"trace[1172767704] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"196.669327ms","start":"2026-06-10T08:35:15.624161Z","end":"2026-06-10T08:35:15.82083Z","steps":["trace[1172767704] 'process raft request' (duration: 196.395251ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:15.820866Z","caller":"traceutil/trace.go:171","msg":"trace[886828104] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"198.062675ms","start":"2026-06-10T08:35:15.622729Z","end":"2026-06-10T08:35:15.820791Z","steps":["trace[886828104] 'process raft request' (duration: 197.457562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:35:16.185446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.797724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:35:16.185536Z","caller":"traceutil/trace.go:171","msg":"trace[1947802973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1534; }","duration":"255.942707ms","start":"2026-06-10T08:35:15.929573Z","end":"2026-06-10T08:35:16.185516Z","steps":["trace[1947802973] 'range keys from in-memory index tree' (duration: 255.711362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:35:16.185772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.594811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-10T08:35:16.185885Z","caller":"traceutil/trace.go:171","msg":"trace[947206904] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1534; }","duration":"136.761244ms","start":"2026-06-10T08:35:16.049102Z","end":"2026-06-10T08:35:16.185864Z","steps":["trace[947206904] 'range keys from in-memory index tree' (duration: 136.412908ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:22.756185Z","caller":"traceutil/trace.go:171","msg":"trace[826762060] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"128.027065ms","start":"2026-06-10T08:35:22.628132Z","end":"2026-06-10T08:35:22.756159Z","steps":["trace[826762060] 'process raft request' (duration: 67.568032ms)","trace[826762060] 'compare' (duration: 60.353191ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:35:24.094548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.972836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:35:24.094734Z","caller":"traceutil/trace.go:171","msg":"trace[1235142376] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"215.390198ms","start":"2026-06-10T08:35:23.879313Z","end":"2026-06-10T08:35:24.094703Z","steps":["trace[1235142376] 'process raft request' (duration: 71.187517ms)","trace[1235142376] 'compare' (duration: 143.869185ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:24.094802Z","caller":"traceutil/trace.go:171","msg":"trace[2119278517] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1635; }","duration":"215.291756ms","start":"2026-06-10T08:35:23.879502Z","end":"2026-06-10T08:35:24.094794Z","steps":["trace[2119278517] 'read index received' (duration: 71.026054ms)","trace[2119278517] 'applied index is now lower than readState.Index' (duration: 144.264812ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:24.094861Z","caller":"traceutil/trace.go:171","msg":"trace[2023101806] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"215.242516ms","start":"2026-06-10T08:35:23.879598Z","end":"2026-06-10T08:35:24.094841Z","steps":["trace[2023101806] 'process raft request' (duration: 215.051162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:35:24.094948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.440499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-10T08:35:24.094764Z","caller":"traceutil/trace.go:171","msg":"trace[219136861] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"214.332357ms","start":"2026-06-10T08:35:23.88041Z","end":"2026-06-10T08:35:24.094742Z","steps":["trace[219136861] 'process raft request' (duration: 214.286366ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:24.094987Z","caller":"traceutil/trace.go:171","msg":"trace[302243658] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1572; }","duration":"215.48939ms","start":"2026-06-10T08:35:23.879486Z","end":"2026-06-10T08:35:24.094975Z","steps":["trace[302243658] 'agreement among raft nodes before linearized reading' (duration: 215.341857ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:32.332504Z","caller":"traceutil/trace.go:171","msg":"trace[545107687] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"197.971593ms","start":"2026-06-10T08:35:32.134472Z","end":"2026-06-10T08:35:32.332443Z","steps":["trace[545107687] 'process raft request' (duration: 197.561055ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:32.332742Z","caller":"traceutil/trace.go:171","msg":"trace[490924101] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"196.789451ms","start":"2026-06-10T08:35:32.135904Z","end":"2026-06-10T08:35:32.332694Z","steps":["trace[490924101] 'process raft request' (duration: 196.466213ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:34.544045Z","caller":"traceutil/trace.go:171","msg":"trace[1345939] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"201.388031ms","start":"2026-06-10T08:35:34.342596Z","end":"2026-06-10T08:35:34.543985Z","steps":["trace[1345939] 'process raft request' (duration: 178.855656ms)","trace[1345939] 'compare' (duration: 22.212719ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:36.325617Z","caller":"traceutil/trace.go:171","msg":"trace[1168556117] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"135.439377ms","start":"2026-06-10T08:35:36.190139Z","end":"2026-06-10T08:35:36.325578Z","steps":["trace[1168556117] 'process raft request' (duration: 135.217142ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:36.433725Z","caller":"traceutil/trace.go:171","msg":"trace[1758300446] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"103.530177ms","start":"2026-06-10T08:35:36.330135Z","end":"2026-06-10T08:35:36.433666Z","steps":["trace[1758300446] 'process raft request' (duration: 103.298952ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:44.875519Z","caller":"traceutil/trace.go:171","msg":"trace[1970718142] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"106.668109ms","start":"2026-06-10T08:35:44.768832Z","end":"2026-06-10T08:35:44.875501Z","steps":["trace[1970718142] 'process raft request' (duration: 106.543476ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:36:42.065316Z","caller":"traceutil/trace.go:171","msg":"trace[375712873] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"118.295112ms","start":"2026-06-10T08:36:41.946993Z","end":"2026-06-10T08:36:42.065289Z","steps":["trace[375712873] 'process raft request' (duration: 118.122359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:36:54.807426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.506933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:36:54.807575Z","caller":"traceutil/trace.go:171","msg":"trace[1561880700] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"156.505682ms","start":"2026-06-10T08:36:54.651035Z","end":"2026-06-10T08:36:54.807541Z","steps":["trace[1561880700] 'read index received' (duration: 29.745663ms)","trace[1561880700] 'applied index is now lower than readState.Index' (duration: 126.758659ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:36:54.807853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.830399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-10T08:36:54.807894Z","caller":"traceutil/trace.go:171","msg":"trace[1185822167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1984; }","duration":"156.934941ms","start":"2026-06-10T08:36:54.650948Z","end":"2026-06-10T08:36:54.807883Z","steps":["trace[1185822167] 'agreement among raft nodes before linearized reading' (duration: 156.667826ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:36:54.807846Z","caller":"traceutil/trace.go:171","msg":"trace[1697714514] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"196.268203ms","start":"2026-06-10T08:36:54.61151Z","end":"2026-06-10T08:36:54.807778Z","steps":["trace[1697714514] 'process raft request' (duration: 69.28792ms)","trace[1697714514] 'compare' (duration: 126.36386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:36:55.088129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.910777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:36:55.088257Z","caller":"traceutil/trace.go:171","msg":"trace[194857597] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"161.944179ms","start":"2026-06-10T08:36:54.926293Z","end":"2026-06-10T08:36:55.088237Z","steps":["trace[194857597] 'read index received' (duration: 49.653555ms)","trace[194857597] 'applied index is now lower than readState.Index' (duration: 112.288544ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:36:55.088323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.036721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:36:55.08835Z","caller":"traceutil/trace.go:171","msg":"trace[797982162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1986; }","duration":"162.097722ms","start":"2026-06-10T08:36:54.926242Z","end":"2026-06-10T08:36:55.08834Z","steps":["trace[797982162] 'agreement among raft nodes before linearized reading' (duration: 162.046951ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:36:55.088335Z","caller":"traceutil/trace.go:171","msg":"trace[1861674937] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"178.387551ms","start":"2026-06-10T08:36:54.909909Z","end":"2026-06-10T08:36:55.088297Z","steps":["trace[1861674937] 'process raft request' (duration: 66.191489ms)","trace[1861674937] 'compare' (duration: 111.754984ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:36:55.323576Z","caller":"traceutil/trace.go:171","msg":"trace[1841843193] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"223.771233ms","start":"2026-06-10T08:36:55.099768Z","end":"2026-06-10T08:36:55.323539Z","steps":["trace[1841843193] 'process raft request' (duration: 223.56433ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:37:01.734775Z","caller":"traceutil/trace.go:171","msg":"trace[101676438] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"130.348759ms","start":"2026-06-10T08:37:01.604391Z","end":"2026-06-10T08:37:01.73474Z","steps":["trace[101676438] 'process raft request' (duration: 130.108174ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:37:02.417291Z","caller":"traceutil/trace.go:171","msg":"trace[1393622673] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"239.048593ms","start":"2026-06-10T08:37:02.178215Z","end":"2026-06-10T08:37:02.417263Z","steps":["trace[1393622673] 'process raft request' (duration: 238.883469ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:37:16.925829Z","caller":"traceutil/trace.go:171","msg":"trace[673000842] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"116.289253ms","start":"2026-06-10T08:37:16.809508Z","end":"2026-06-10T08:37:16.925798Z","steps":["trace[673000842] 'process raft request' (duration: 65.825642ms)","trace[673000842] 'compare' (duration: 50.336038ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:37:17.940547Z","caller":"traceutil/trace.go:171","msg":"trace[892209561] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"134.894809ms","start":"2026-06-10T08:37:17.805626Z","end":"2026-06-10T08:37:17.940521Z","steps":["trace[892209561] 'process raft request' (duration: 122.222219ms)","trace[892209561] 'compare' (duration: 12.467895ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:37:37.072323Z","caller":"traceutil/trace.go:171","msg":"trace[1525447036] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"111.719157ms","start":"2026-06-10T08:37:36.960577Z","end":"2026-06-10T08:37:37.072296Z","steps":["trace[1525447036] 'process raft request' (duration: 111.574224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:37:45.614643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.042189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:37:45.614735Z","caller":"traceutil/trace.go:171","msg":"trace[630087548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2215; }","duration":"118.191973ms","start":"2026-06-10T08:37:45.496524Z","end":"2026-06-10T08:37:45.614716Z","steps":["trace[630087548] 'range keys from in-memory index tree' (duration: 117.885887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:37:53.806105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.786835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:37:53.806233Z","caller":"traceutil/trace.go:171","msg":"trace[1044179441] linearizableReadLoop","detail":"{readStateIndex:2349; appliedIndex:2348; }","duration":"198.8463ms","start":"2026-06-10T08:37:53.607362Z","end":"2026-06-10T08:37:53.806208Z","steps":["trace[1044179441] 'read index received' (duration: 58.859711ms)","trace[1044179441] 'applied index is now lower than readState.Index' (duration: 139.984909ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:37:53.806414Z","caller":"traceutil/trace.go:171","msg":"trace[1779848575] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"308.63367ms","start":"2026-06-10T08:37:53.49776Z","end":"2026-06-10T08:37:53.806394Z","steps":["trace[1779848575] 'process raft request' (duration: 168.444246ms)","trace[1779848575] 'compare' (duration: 139.614452ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:37:53.806509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:37:53.497728Z","time spent":"308.733101ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:37:53.806778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.476091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:37:53.806834Z","caller":"traceutil/trace.go:171","msg":"trace[1853488346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2253; }","duration":"135.560403ms","start":"2026-06-10T08:37:53.671261Z","end":"2026-06-10T08:37:53.806822Z","steps":["trace[1853488346] 'agreement among raft nodes before linearized reading' (duration: 135.310218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:37:53.806831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.455443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-06-10T08:37:53.807362Z","caller":"traceutil/trace.go:171","msg":"trace[1448953158] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2253; }","duration":"200.041414ms","start":"2026-06-10T08:37:53.607302Z","end":"2026-06-10T08:37:53.807343Z","steps":["trace[1448953158] 'agreement among raft nodes before linearized reading' (duration: 199.262749ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:34.789211Z","caller":"traceutil/trace.go:171","msg":"trace[1941333966] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"115.243925ms","start":"2026-06-10T08:38:34.673913Z","end":"2026-06-10T08:38:34.789156Z","steps":["trace[1941333966] 'process raft request' (duration: 114.673334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:38:35.090135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.610433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:38:35.090316Z","caller":"traceutil/trace.go:171","msg":"trace[828812690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2462; }","duration":"159.858748ms","start":"2026-06-10T08:38:34.930431Z","end":"2026-06-10T08:38:35.09029Z","steps":["trace[828812690] 'range keys from in-memory index tree' (duration: 159.521032ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:35.090961Z","caller":"traceutil/trace.go:171","msg":"trace[1757544960] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"143.915486ms","start":"2026-06-10T08:38:34.947012Z","end":"2026-06-10T08:38:35.090927Z","steps":["trace[1757544960] 'process raft request' (duration: 141.930747ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:36.135489Z","caller":"traceutil/trace.go:171","msg":"trace[1222299557] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"119.344056ms","start":"2026-06-10T08:38:36.016108Z","end":"2026-06-10T08:38:36.135452Z","steps":["trace[1222299557] 'process raft request' (duration: 119.06999ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:36.532516Z","caller":"traceutil/trace.go:171","msg":"trace[386682072] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"224.017654ms","start":"2026-06-10T08:38:36.308461Z","end":"2026-06-10T08:38:36.532479Z","steps":["trace[386682072] 'process raft request' (duration: 223.80519ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:36.680775Z","caller":"traceutil/trace.go:171","msg":"trace[1231756436] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"123.803532ms","start":"2026-06-10T08:38:36.556918Z","end":"2026-06-10T08:38:36.680722Z","steps":["trace[1231756436] 'process raft request' (duration: 105.684877ms)","trace[1231756436] 'compare' (duration: 17.960632ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:38:36.999624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.358215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:38:36.999707Z","caller":"traceutil/trace.go:171","msg":"trace[213783101] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2469; }","duration":"169.585159ms","start":"2026-06-10T08:38:36.830104Z","end":"2026-06-10T08:38:36.999689Z","steps":["trace[213783101] 'count revisions from in-memory index tree' (duration: 169.236362ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:41.637374Z","caller":"traceutil/trace.go:171","msg":"trace[1012155363] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"125.45906ms","start":"2026-06-10T08:38:41.511888Z","end":"2026-06-10T08:38:41.637347Z","steps":["trace[1012155363] 'process raft request' (duration: 125.204515ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:38:41.744122Z","caller":"traceutil/trace.go:171","msg":"trace[1827232673] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"102.867427ms","start":"2026-06-10T08:38:41.641214Z","end":"2026-06-10T08:38:41.744081Z","steps":["trace[1827232673] 'process raft request' (duration: 80.04213ms)","trace[1827232673] 'compare' (duration: 22.655464ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:14.934087Z","caller":"traceutil/trace.go:171","msg":"trace[145825731] linearizableReadLoop","detail":"{readStateIndex:2771; appliedIndex:2770; }","duration":"181.084651ms","start":"2026-06-10T08:39:14.752983Z","end":"2026-06-10T08:39:14.934067Z","steps":["trace[145825731] 'read index received' (duration: 180.961099ms)","trace[145825731] 'applied index is now lower than readState.Index' (duration: 122.732µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:14.934302Z","caller":"traceutil/trace.go:171","msg":"trace[448449089] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"198.916341ms","start":"2026-06-10T08:39:14.735375Z","end":"2026-06-10T08:39:14.934291Z","steps":["trace[448449089] 'process raft request' (duration: 198.587415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:14.934504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.512021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tjjkj\" ","response":"range_response_count:1 size:11390"} {"level":"info","ts":"2026-06-10T08:39:14.934526Z","caller":"traceutil/trace.go:171","msg":"trace[1836243856] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tjjkj; range_end:; response_count:1; response_revision:2657; }","duration":"181.579612ms","start":"2026-06-10T08:39:14.75294Z","end":"2026-06-10T08:39:14.934519Z","steps":["trace[1836243856] 'agreement among raft nodes before linearized reading' (duration: 181.4767ms)"],"step_count":1}