{"level":"warn","ts":"2026-08-05T11:22:41.7599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:22:41.759981Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169: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.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-05T11:22:41.760045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:22:41.760058Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-08-05T11:22:41.760076Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-05T11:22:41.760687Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-08-05T11:22:41.760758Z","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.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-05T11:22:41.762645Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.672647ms"} {"level":"info","ts":"2026-08-05T11:22:41.767957Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-08-05T11:22:41.768044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:22:41.768073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-08-05T11:22:41.76809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:22:41.768103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-08-05T11:22:41.768142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-08-05T11:22:41.774525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:22:41.775681Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:22:41.776745Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-05T11:22:41.780224Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:22:41.781239Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-05T11:22:41.781219Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:22:41.78132Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-05T11:22:41.781329Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-05T11:22:41.782573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-08-05T11:22:41.783083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-08-05T11:22:41.784688Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-05T11:22:41.784847Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:22:41.784876Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:22:41.784938Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-08-05T11:22:41.784951Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-08-05T11:22:42.269023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:22:42.26908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:22:42.269105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-08-05T11:22:42.269119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:22:42.269131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-08-05T11:22:42.269142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-08-05T11:22:42.269155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-08-05T11:22:42.269876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:42.270712Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:22:42.270842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:22:42.270904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:22:42.272716Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:22:42.272764Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:22:42.273309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-08-05T11:22:42.273572Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:42.273636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:42.273662Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:42.274012Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-05T11:22:44.602587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.133357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-05T11:22:44.602681Z","caller":"traceutil/trace.go:171","msg":"trace[1928903065] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"101.308322ms","start":"2026-08-05T11:22:44.501354Z","end":"2026-08-05T11:22:44.602662Z","steps":["trace[1928903065] 'range keys from in-memory index tree' (duration: 101.071646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:44.602796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.24055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-05T11:22:44.602847Z","caller":"traceutil/trace.go:171","msg":"trace[1014526351] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:72; }","duration":"101.5858ms","start":"2026-08-05T11:22:44.50125Z","end":"2026-08-05T11:22:44.602836Z","steps":["trace[1014526351] 'range keys from in-memory index tree' (duration: 101.145768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:45.286471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.995172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:22:45.286585Z","caller":"traceutil/trace.go:171","msg":"trace[689602551] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"283.635314ms","start":"2026-08-05T11:22:45.002932Z","end":"2026-08-05T11:22:45.286568Z","steps":["trace[689602551] 'process raft request' (duration: 32.082356ms)","trace[689602551] 'compare' (duration: 250.840418ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:22:49.884027Z","caller":"traceutil/trace.go:171","msg":"trace[1921549776] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"201.808342ms","start":"2026-08-05T11:22:49.682188Z","end":"2026-08-05T11:22:49.883996Z","steps":["trace[1921549776] 'process raft request' (duration: 196.996256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:50.388476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822065945504471,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T11:22:50.560581Z","caller":"traceutil/trace.go:171","msg":"trace[233911727] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"672.11209ms","start":"2026-08-05T11:22:49.888447Z","end":"2026-08-05T11:22:50.560559Z","steps":["trace[233911727] 'read index received' (duration: 671.887614ms)","trace[233911727] 'applied index is now lower than readState.Index' (duration: 223.406µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:22:50.560698Z","caller":"traceutil/trace.go:171","msg":"trace[683403334] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"672.899372ms","start":"2026-08-05T11:22:49.887789Z","end":"2026-08-05T11:22:50.560688Z","steps":["trace[683403334] 'process raft request' (duration: 672.634495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:50.560911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.078991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-05T11:22:50.560981Z","caller":"traceutil/trace.go:171","msg":"trace[1407620676] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:321; }","duration":"671.191824ms","start":"2026-08-05T11:22:49.889771Z","end":"2026-08-05T11:22:50.560962Z","steps":["trace[1407620676] 'agreement among raft nodes before linearized reading' (duration: 671.06156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:50.560998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:49.887767Z","time spent":"672.956474ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:22:50.561012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:49.889766Z","time spent":"671.239986ms","remote":"127.0.0.1:37544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-05T11:22:50.561211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.757389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-05T11:22:50.561252Z","caller":"traceutil/trace.go:171","msg":"trace[827118573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:321; }","duration":"672.80647ms","start":"2026-08-05T11:22:49.888433Z","end":"2026-08-05T11:22:50.56124Z","steps":["trace[827118573] 'agreement among raft nodes before linearized reading' (duration: 672.714667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:50.561307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.086799ms","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-08-05T11:22:50.561522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:49.888428Z","time spent":"673.076737ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T11:22:50.561386Z","caller":"traceutil/trace.go:171","msg":"trace[704575111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:321; }","duration":"615.183411ms","start":"2026-08-05T11:22:49.946183Z","end":"2026-08-05T11:22:50.561366Z","steps":["trace[704575111] 'agreement among raft nodes before linearized reading' (duration: 615.013156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:50.561847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:49.946141Z","time spent":"615.690635ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:22:51.002748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.443984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:22:51.002983Z","caller":"traceutil/trace.go:171","msg":"trace[3429767] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"438.340755ms","start":"2026-08-05T11:22:50.564593Z","end":"2026-08-05T11:22:51.002934Z","steps":["trace[3429767] 'process raft request' (duration: 32.636283ms)","trace[3429767] 'compare' (duration: 405.308261ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:22:51.002997Z","caller":"traceutil/trace.go:171","msg":"trace[635583550] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"437.729377ms","start":"2026-08-05T11:22:50.565255Z","end":"2026-08-05T11:22:51.002984Z","steps":["trace[635583550] 'process raft request' (duration: 437.662725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:51.003084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.565249Z","time spent":"437.794999ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:22:51.003094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.564566Z","time spent":"438.477838ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:22:51.003042Z","caller":"traceutil/trace.go:171","msg":"trace[1634420828] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"438.022475ms","start":"2026-08-05T11:22:50.564993Z","end":"2026-08-05T11:22:51.003016Z","steps":["trace[1634420828] 'process raft request' (duration: 437.85609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:51.003268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.564978Z","time spent":"438.242312ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:22:51.6289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.406964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:22:51.629002Z","caller":"traceutil/trace.go:171","msg":"trace[1494402132] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"613.389812ms","start":"2026-08-05T11:22:51.015592Z","end":"2026-08-05T11:22:51.628982Z","steps":["trace[1494402132] 'process raft request' (duration: 344.838174ms)","trace[1494402132] 'compare' (duration: 268.262669ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:22:51.629052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:51.01558Z","time spent":"613.452803ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:23:18.70147Z","caller":"traceutil/trace.go:171","msg":"trace[1244738181] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"226.640923ms","start":"2026-08-05T11:23:18.474792Z","end":"2026-08-05T11:23:18.701432Z","steps":["trace[1244738181] 'process raft request' (duration: 192.369105ms)","trace[1244738181] 'compare' (duration: 33.97638ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:18.702045Z","caller":"traceutil/trace.go:171","msg":"trace[108900388] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"222.97291ms","start":"2026-08-05T11:23:18.479013Z","end":"2026-08-05T11:23:18.701985Z","steps":["trace[108900388] 'process raft request' (duration: 222.334612ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:18.702206Z","caller":"traceutil/trace.go:171","msg":"trace[1828568851] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"212.413741ms","start":"2026-08-05T11:23:18.489754Z","end":"2026-08-05T11:23:18.702168Z","steps":["trace[1828568851] 'process raft request' (duration: 212.116613ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:18.901084Z","caller":"traceutil/trace.go:171","msg":"trace[199834563] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"194.005812ms","start":"2026-08-05T11:23:18.707056Z","end":"2026-08-05T11:23:18.901061Z","steps":["trace[199834563] 'process raft request' (duration: 188.557158ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:18.901258Z","caller":"traceutil/trace.go:171","msg":"trace[1177167477] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"179.380078ms","start":"2026-08-05T11:23:18.721843Z","end":"2026-08-05T11:23:18.901223Z","steps":["trace[1177167477] 'process raft request' (duration: 179.129841ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:18.901339Z","caller":"traceutil/trace.go:171","msg":"trace[1963794779] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"178.629247ms","start":"2026-08-05T11:23:18.722678Z","end":"2026-08-05T11:23:18.901307Z","steps":["trace[1963794779] 'process raft request' (duration: 178.39448ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:18.902615Z","caller":"traceutil/trace.go:171","msg":"trace[650227609] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"144.778361ms","start":"2026-08-05T11:23:18.757628Z","end":"2026-08-05T11:23:18.902407Z","steps":["trace[650227609] 'process raft request' (duration: 143.609858ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:18.909262Z","caller":"traceutil/trace.go:171","msg":"trace[1795068279] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"142.237719ms","start":"2026-08-05T11:23:18.767003Z","end":"2026-08-05T11:23:18.909241Z","steps":["trace[1795068279] 'process raft request' (duration: 138.572895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:21.76845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.675822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:23:21.76857Z","caller":"traceutil/trace.go:171","msg":"trace[1141780946] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"473.360785ms","start":"2026-08-05T11:23:21.295189Z","end":"2026-08-05T11:23:21.76855Z","steps":["trace[1141780946] 'process raft request' (duration: 256.514188ms)","trace[1141780946] 'compare' (duration: 216.516278ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:21.768638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:21.295165Z","time spent":"473.439327ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:23:21.771182Z","caller":"traceutil/trace.go:171","msg":"trace[1284772425] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"475.925217ms","start":"2026-08-05T11:23:21.295235Z","end":"2026-08-05T11:23:21.77116Z","steps":["trace[1284772425] 'process raft request' (duration: 475.809214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:21.771359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:21.295218Z","time spent":"476.083432ms","remote":"127.0.0.1:37534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:23:21.780983Z","caller":"traceutil/trace.go:171","msg":"trace[2135711587] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"103.817314ms","start":"2026-08-05T11:23:21.677144Z","end":"2026-08-05T11:23:21.780961Z","steps":["trace[2135711587] 'process raft request' (duration: 102.848906ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:25.306081Z","caller":"traceutil/trace.go:171","msg":"trace[338759802] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"121.759271ms","start":"2026-08-05T11:23:25.184289Z","end":"2026-08-05T11:23:25.306049Z","steps":["trace[338759802] 'read index received' (duration: 89.721626ms)","trace[338759802] 'applied index is now lower than readState.Index' (duration: 32.036215ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:25.306219Z","caller":"traceutil/trace.go:171","msg":"trace[988776615] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"135.973832ms","start":"2026-08-05T11:23:25.170212Z","end":"2026-08-05T11:23:25.306186Z","steps":["trace[988776615] 'process raft request' (duration: 103.70722ms)","trace[988776615] 'compare' (duration: 31.974023ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:25.306562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.250774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-05T11:23:25.306625Z","caller":"traceutil/trace.go:171","msg":"trace[908968535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"122.355578ms","start":"2026-08-05T11:23:25.18425Z","end":"2026-08-05T11:23:25.306605Z","steps":["trace[908968535] 'agreement among raft nodes before linearized reading' (duration: 121.918895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:25.682142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.879351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:23:25.682458Z","caller":"traceutil/trace.go:171","msg":"trace[1286015634] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"478.074558ms","start":"2026-08-05T11:23:25.204346Z","end":"2026-08-05T11:23:25.68242Z","steps":["trace[1286015634] 'process raft request' (duration: 319.860358ms)","trace[1286015634] 'compare' (duration: 157.741077ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:25.682611Z","caller":"traceutil/trace.go:171","msg":"trace[1964053057] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"371.987681ms","start":"2026-08-05T11:23:25.310605Z","end":"2026-08-05T11:23:25.682592Z","steps":["trace[1964053057] 'process raft request' (duration: 371.624931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:25.682711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:25.31058Z","time spent":"372.090483ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:23:25.682592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:25.204319Z","time spent":"478.217612ms","remote":"127.0.0.1:37690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T11:23:26.311773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822065945505151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:23:26.711143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.981505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:23:26.711272Z","caller":"traceutil/trace.go:171","msg":"trace[50476500] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"994.660923ms","start":"2026-08-05T11:23:25.716583Z","end":"2026-08-05T11:23:26.711244Z","steps":["trace[50476500] 'process raft request' (duration: 631.450812ms)","trace[50476500] 'compare' (duration: 362.850741ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:26.711462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:25.716572Z","time spent":"994.801767ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:23:26.744781Z","caller":"traceutil/trace.go:171","msg":"trace[1408707621] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"933.626578ms","start":"2026-08-05T11:23:25.811127Z","end":"2026-08-05T11:23:26.744753Z","steps":["trace[1408707621] 'read index received' (duration: 536.92936ms)","trace[1408707621] 'applied index is now lower than readState.Index' (duration: 396.696188ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:26.745282Z","caller":"traceutil/trace.go:171","msg":"trace[271941460] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.027577993s","start":"2026-08-05T11:23:25.717661Z","end":"2026-08-05T11:23:26.745249Z","steps":["trace[271941460] 'process raft request' (duration: 1.026951725s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:26.745444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:25.717612Z","time spent":"1.027752248s","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:23:26.745412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.270017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:23:26.745535Z","caller":"traceutil/trace.go:171","msg":"trace[1444771672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"934.406651ms","start":"2026-08-05T11:23:25.811086Z","end":"2026-08-05T11:23:26.745492Z","steps":["trace[1444771672] 'agreement among raft nodes before linearized reading' (duration: 934.182305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:26.745583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:25.811068Z","time spent":"934.504923ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T11:23:26.749503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.050508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:23:26.749578Z","caller":"traceutil/trace.go:171","msg":"trace[860382974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"357.165191ms","start":"2026-08-05T11:23:26.392394Z","end":"2026-08-05T11:23:26.749559Z","steps":["trace[860382974] 'agreement among raft nodes before linearized reading' (duration: 357.039237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:26.749613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:26.392374Z","time spent":"357.231213ms","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T11:23:42.478025Z","caller":"traceutil/trace.go:171","msg":"trace[1250934576] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"275.490743ms","start":"2026-08-05T11:23:42.202506Z","end":"2026-08-05T11:23:42.477997Z","steps":["trace[1250934576] 'process raft request' (duration: 275.052451ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:16.293491Z","caller":"traceutil/trace.go:171","msg":"trace[708864147] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"360.941628ms","start":"2026-08-05T11:24:15.932527Z","end":"2026-08-05T11:24:16.293468Z","steps":["trace[708864147] 'process raft request' (duration: 360.798374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:16.293632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:15.932512Z","time spent":"361.048441ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:16.443616Z","caller":"traceutil/trace.go:171","msg":"trace[1716584057] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"299.725634ms","start":"2026-08-05T11:24:16.143868Z","end":"2026-08-05T11:24:16.443593Z","steps":["trace[1716584057] 'read index received' (duration: 149.452501ms)","trace[1716584057] 'applied index is now lower than readState.Index' (duration: 150.272453ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:16.443651Z","caller":"traceutil/trace.go:171","msg":"trace[479871674] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"501.725424ms","start":"2026-08-05T11:24:15.941905Z","end":"2026-08-05T11:24:16.44363Z","steps":["trace[479871674] 'process raft request' (duration: 482.602475ms)","trace[479871674] 'compare' (duration: 18.874132ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:16.443707Z","caller":"traceutil/trace.go:171","msg":"trace[484638251] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"379.709547ms","start":"2026-08-05T11:24:16.06399Z","end":"2026-08-05T11:24:16.4437Z","steps":["trace[484638251] 'process raft request' (duration: 379.561643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:16.44377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:16.063969Z","time spent":"379.753457ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:16.443765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:15.941882Z","time spent":"501.813057ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:16.443886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.002891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:16.443948Z","caller":"traceutil/trace.go:171","msg":"trace[1750201557] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1008; }","duration":"300.122164ms","start":"2026-08-05T11:24:16.143812Z","end":"2026-08-05T11:24:16.443934Z","steps":["trace[1750201557] 'agreement among raft nodes before linearized reading' (duration: 300.006061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:16.443978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:16.143791Z","time spent":"300.180526ms","remote":"127.0.0.1:37834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:24:39.333476Z","caller":"traceutil/trace.go:171","msg":"trace[1189232282] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"466.829651ms","start":"2026-08-05T11:24:38.866622Z","end":"2026-08-05T11:24:39.333451Z","steps":["trace[1189232282] 'process raft request' (duration: 466.660066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:39.333813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:38.866598Z","time spent":"467.006126ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:39.357837Z","caller":"traceutil/trace.go:171","msg":"trace[315369811] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1122; }","duration":"230.826472ms","start":"2026-08-05T11:24:39.126991Z","end":"2026-08-05T11:24:39.357818Z","steps":["trace[315369811] 'read index received' (duration: 206.326532ms)","trace[315369811] 'applied index is now lower than readState.Index' (duration: 24.49865ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:39.357955Z","caller":"traceutil/trace.go:171","msg":"trace[2028858987] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"317.479104ms","start":"2026-08-05T11:24:39.040455Z","end":"2026-08-05T11:24:39.357934Z","steps":["trace[2028858987] 'process raft request' (duration: 317.260717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:39.358081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:39.040439Z","time spent":"317.568176ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:39.358345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.351147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T11:24:39.358392Z","caller":"traceutil/trace.go:171","msg":"trace[1679673003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"231.44152ms","start":"2026-08-05T11:24:39.126939Z","end":"2026-08-05T11:24:39.35838Z","steps":["trace[1679673003] 'agreement among raft nodes before linearized reading' (duration: 230.987507ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:39.652248Z","caller":"traceutil/trace.go:171","msg":"trace[2049280231] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"258.531903ms","start":"2026-08-05T11:24:39.393691Z","end":"2026-08-05T11:24:39.652222Z","steps":["trace[2049280231] 'read index received' (duration: 251.576257ms)","trace[2049280231] 'applied index is now lower than readState.Index' (duration: 6.954846ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:39.652536Z","caller":"traceutil/trace.go:171","msg":"trace[1304481545] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"289.695791ms","start":"2026-08-05T11:24:39.362826Z","end":"2026-08-05T11:24:39.652522Z","steps":["trace[1304481545] 'process raft request' (duration: 282.323794ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:39.652734Z","caller":"traceutil/trace.go:171","msg":"trace[1746342392] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"289.872316ms","start":"2026-08-05T11:24:39.362852Z","end":"2026-08-05T11:24:39.652724Z","steps":["trace[1746342392] 'process raft request' (duration: 289.29852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:39.652886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.184101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:39.652919Z","caller":"traceutil/trace.go:171","msg":"trace[932940032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"259.287774ms","start":"2026-08-05T11:24:39.393622Z","end":"2026-08-05T11:24:39.652909Z","steps":["trace[932940032] 'agreement among raft nodes before linearized reading' (duration: 259.221042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:39.653094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.006738ms","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-08-05T11:24:39.653159Z","caller":"traceutil/trace.go:171","msg":"trace[1477306701] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1084; }","duration":"242.136511ms","start":"2026-08-05T11:24:39.411007Z","end":"2026-08-05T11:24:39.653143Z","steps":["trace[1477306701] 'agreement among raft nodes before linearized reading' (duration: 242.022088ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:40.604392Z","caller":"traceutil/trace.go:171","msg":"trace[294198527] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"181.235116ms","start":"2026-08-05T11:24:40.423118Z","end":"2026-08-05T11:24:40.604353Z","steps":["trace[294198527] 'process raft request' (duration: 180.784013ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:40.838354Z","caller":"traceutil/trace.go:171","msg":"trace[1806246842] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"140.332773ms","start":"2026-08-05T11:24:40.697999Z","end":"2026-08-05T11:24:40.838332Z","steps":["trace[1806246842] 'read index received' (duration: 125.384752ms)","trace[1806246842] 'applied index is now lower than readState.Index' (duration: 14.947081ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:40.838481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.466428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:40.838536Z","caller":"traceutil/trace.go:171","msg":"trace[1646926615] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"317.406062ms","start":"2026-08-05T11:24:40.521118Z","end":"2026-08-05T11:24:40.838524Z","steps":["trace[1646926615] 'process raft request' (duration: 302.317016ms)","trace[1646926615] 'compare' (duration: 14.793187ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:40.838649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:40.52109Z","time spent":"317.507555ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:40.838513Z","caller":"traceutil/trace.go:171","msg":"trace[733025247] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1087; }","duration":"140.56651ms","start":"2026-08-05T11:24:40.697938Z","end":"2026-08-05T11:24:40.838505Z","steps":["trace[733025247] 'agreement among raft nodes before linearized reading' (duration: 140.471887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:41.133046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.566266ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-05T11:24:42.055525Z","caller":"traceutil/trace.go:171","msg":"trace[1001802437] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"318.225874ms","start":"2026-08-05T11:24:41.737256Z","end":"2026-08-05T11:24:42.055482Z","steps":["trace[1001802437] 'process raft request' (duration: 293.463716ms)","trace[1001802437] 'compare' (duration: 24.264464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:42.055734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:41.737243Z","time spent":"318.377798ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:57.724956Z","caller":"traceutil/trace.go:171","msg":"trace[953034855] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"335.116127ms","start":"2026-08-05T11:24:57.38982Z","end":"2026-08-05T11:24:57.724936Z","steps":["trace[953034855] 'read index received' (duration: 334.968563ms)","trace[953034855] 'applied index is now lower than readState.Index' (duration: 146.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:57.725061Z","caller":"traceutil/trace.go:171","msg":"trace[1202306817] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"443.478388ms","start":"2026-08-05T11:24:57.281543Z","end":"2026-08-05T11:24:57.725021Z","steps":["trace[1202306817] 'process raft request' (duration: 443.269372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:57.725118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.275081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:24:57.725144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.642156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T11:24:57.725197Z","caller":"traceutil/trace.go:171","msg":"trace[1779631411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"289.737769ms","start":"2026-08-05T11:24:57.435449Z","end":"2026-08-05T11:24:57.725187Z","steps":["trace[1779631411] 'agreement among raft nodes before linearized reading' (duration: 289.600125ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:57.725198Z","caller":"traceutil/trace.go:171","msg":"trace[1956713730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"335.379044ms","start":"2026-08-05T11:24:57.38978Z","end":"2026-08-05T11:24:57.725159Z","steps":["trace[1956713730] 'agreement among raft nodes before linearized reading' (duration: 335.261521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:57.72521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:57.281515Z","time spent":"443.607102ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:57.725235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:57.389761Z","time spent":"335.465076ms","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T11:24:58.069058Z","caller":"traceutil/trace.go:171","msg":"trace[219372180] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"269.292593ms","start":"2026-08-05T11:24:57.799742Z","end":"2026-08-05T11:24:58.069034Z","steps":["trace[219372180] 'process raft request' (duration: 266.785732ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:10.280703Z","caller":"traceutil/trace.go:171","msg":"trace[1309129531] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"108.101554ms","start":"2026-08-05T11:25:10.172578Z","end":"2026-08-05T11:25:10.280679Z","steps":["trace[1309129531] 'read index received' (duration: 101.724444ms)","trace[1309129531] 'applied index is now lower than readState.Index' (duration: 6.37563ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:10.280794Z","caller":"traceutil/trace.go:171","msg":"trace[1625400305] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"145.093356ms","start":"2026-08-05T11:25:10.135669Z","end":"2026-08-05T11:25:10.280762Z","steps":["trace[1625400305] 'process raft request' (duration: 138.656525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:10.280954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.351962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-05T11:25:10.281001Z","caller":"traceutil/trace.go:171","msg":"trace[2095883937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"108.448323ms","start":"2026-08-05T11:25:10.172539Z","end":"2026-08-05T11:25:10.280988Z","steps":["trace[2095883937] 'agreement among raft nodes before linearized reading' (duration: 108.284469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:11.135304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.256976ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-05T11:25:14.271771Z","caller":"traceutil/trace.go:171","msg":"trace[831326562] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"333.292074ms","start":"2026-08-05T11:25:13.938456Z","end":"2026-08-05T11:25:14.271748Z","steps":["trace[831326562] 'read index received' (duration: 333.226842ms)","trace[831326562] 'applied index is now lower than readState.Index' (duration: 64.152µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:14.27214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.649784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T11:25:14.272189Z","caller":"traceutil/trace.go:171","msg":"trace[1237273883] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"393.236952ms","start":"2026-08-05T11:25:13.878926Z","end":"2026-08-05T11:25:14.272163Z","steps":["trace[1237273883] 'process raft request' (duration: 392.617534ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:14.272251Z","caller":"traceutil/trace.go:171","msg":"trace[1430389895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"333.809338ms","start":"2026-08-05T11:25:13.938412Z","end":"2026-08-05T11:25:14.272221Z","steps":["trace[1430389895] 'agreement among raft nodes before linearized reading' (duration: 333.51211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:14.272305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:13.93839Z","time spent":"333.903711ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:25:14.272313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:13.87891Z","time spent":"393.333245ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:25:14.272324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.250616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T11:25:14.272395Z","caller":"traceutil/trace.go:171","msg":"trace[1011965652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"136.37264ms","start":"2026-08-05T11:25:14.136004Z","end":"2026-08-05T11:25:14.272376Z","steps":["trace[1011965652] 'agreement among raft nodes before linearized reading' (duration: 136.134053ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:14.637044Z","caller":"traceutil/trace.go:171","msg":"trace[1280919959] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"354.998406ms","start":"2026-08-05T11:25:14.282022Z","end":"2026-08-05T11:25:14.637021Z","steps":["trace[1280919959] 'process raft request' (duration: 306.8529ms)","trace[1280919959] 'compare' (duration: 47.906559ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:14.637176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:14.282001Z","time spent":"355.100059ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:14.656001Z","caller":"traceutil/trace.go:171","msg":"trace[1456696423] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1315; }","duration":"358.028692ms","start":"2026-08-05T11:25:14.297953Z","end":"2026-08-05T11:25:14.655981Z","steps":["trace[1456696423] 'read index received' (duration: 290.926532ms)","trace[1456696423] 'applied index is now lower than readState.Index' (duration: 67.10117ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:14.656094Z","caller":"traceutil/trace.go:171","msg":"trace[636747202] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"373.851967ms","start":"2026-08-05T11:25:14.282234Z","end":"2026-08-05T11:25:14.656086Z","steps":["trace[636747202] 'process raft request' (duration: 373.637671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:14.65618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:14.282211Z","time spent":"373.903259ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:25:14.656246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.560612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:25:14.656299Z","caller":"traceutil/trace.go:171","msg":"trace[512890786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"263.651625ms","start":"2026-08-05T11:25:14.392635Z","end":"2026-08-05T11:25:14.656286Z","steps":["trace[512890786] 'agreement among raft nodes before linearized reading' (duration: 263.577503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:14.656454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.658514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:25:14.656484Z","caller":"traceutil/trace.go:171","msg":"trace[71411623] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1266; }","duration":"133.718586ms","start":"2026-08-05T11:25:14.522758Z","end":"2026-08-05T11:25:14.656476Z","steps":["trace[71411623] 'agreement among raft nodes before linearized reading' (duration: 133.668225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:14.656617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.66538ms","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-08-05T11:25:14.65665Z","caller":"traceutil/trace.go:171","msg":"trace[199930915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"358.722222ms","start":"2026-08-05T11:25:14.297916Z","end":"2026-08-05T11:25:14.656639Z","steps":["trace[199930915] 'agreement among raft nodes before linearized reading' (duration: 358.617289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:14.656677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:14.297903Z","time spent":"358.767662ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:25:16.194014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.390939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:25:16.194145Z","caller":"traceutil/trace.go:171","msg":"trace[209524358] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"453.767998ms","start":"2026-08-05T11:25:15.740356Z","end":"2026-08-05T11:25:16.194124Z","steps":["trace[209524358] 'process raft request' (duration: 294.184284ms)","trace[209524358] 'compare' (duration: 158.978377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:16.194547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:15.740335Z","time spent":"454.161059ms","remote":"127.0.0.1:37470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:16.369334Z","caller":"traceutil/trace.go:171","msg":"trace[1296099071] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"104.886744ms","start":"2026-08-05T11:25:16.264431Z","end":"2026-08-05T11:25:16.369318Z","steps":["trace[1296099071] 'process raft request' (duration: 104.77558ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:16.521472Z","caller":"traceutil/trace.go:171","msg":"trace[1117307983] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1324; }","duration":"131.597585ms","start":"2026-08-05T11:25:16.389852Z","end":"2026-08-05T11:25:16.521449Z","steps":["trace[1117307983] 'read index received' (duration: 41.653952ms)","trace[1117307983] 'applied index is now lower than readState.Index' (duration: 89.942513ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:16.521703Z","caller":"traceutil/trace.go:171","msg":"trace[1752216024] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"146.900397ms","start":"2026-08-05T11:25:16.374782Z","end":"2026-08-05T11:25:16.521682Z","steps":["trace[1752216024] 'process raft request' (duration: 89.247683ms)","trace[1752216024] 'compare' (duration: 57.240182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:16.521809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.946636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:25:16.521865Z","caller":"traceutil/trace.go:171","msg":"trace[1774667377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"132.054239ms","start":"2026-08-05T11:25:16.389797Z","end":"2026-08-05T11:25:16.521852Z","steps":["trace[1774667377] 'agreement among raft nodes before linearized reading' (duration: 131.908564ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:16.882078Z","caller":"traceutil/trace.go:171","msg":"trace[266500472] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"222.955929ms","start":"2026-08-05T11:25:16.659099Z","end":"2026-08-05T11:25:16.882055Z","steps":["trace[266500472] 'read index received' (duration: 222.748973ms)","trace[266500472] 'applied index is now lower than readState.Index' (duration: 205.996µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:16.88238Z","caller":"traceutil/trace.go:171","msg":"trace[686174923] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"233.268578ms","start":"2026-08-05T11:25:16.6491Z","end":"2026-08-05T11:25:16.882368Z","steps":["trace[686174923] 'process raft request' (duration: 232.801115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:16.882633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.519404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T11:25:16.882675Z","caller":"traceutil/trace.go:171","msg":"trace[1239348144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"223.607397ms","start":"2026-08-05T11:25:16.659058Z","end":"2026-08-05T11:25:16.882666Z","steps":["trace[1239348144] 'agreement among raft nodes before linearized reading' (duration: 223.476843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:16.882848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.024719ms","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-08-05T11:25:16.882883Z","caller":"traceutil/trace.go:171","msg":"trace[583095712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"213.097101ms","start":"2026-08-05T11:25:16.669778Z","end":"2026-08-05T11:25:16.882875Z","steps":["trace[583095712] 'agreement among raft nodes before linearized reading' (duration: 213.015178ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:17.244004Z","caller":"traceutil/trace.go:171","msg":"trace[1862382873] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"356.246502ms","start":"2026-08-05T11:25:16.887716Z","end":"2026-08-05T11:25:17.243963Z","steps":["trace[1862382873] 'process raft request' (duration: 262.695527ms)","trace[1862382873] 'compare' (duration: 93.405281ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:17.244162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:16.887698Z","time spent":"356.386155ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:17.263817Z","caller":"traceutil/trace.go:171","msg":"trace[2010217957] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"375.840054ms","start":"2026-08-05T11:25:16.887934Z","end":"2026-08-05T11:25:17.263774Z","steps":["trace[2010217957] 'process raft request' (duration: 375.678099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:17.263974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:16.887926Z","time spent":"375.968077ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:17.264179Z","caller":"traceutil/trace.go:171","msg":"trace[733974465] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"376.158832ms","start":"2026-08-05T11:25:16.887985Z","end":"2026-08-05T11:25:17.264144Z","steps":["trace[733974465] 'process raft request' (duration: 375.750781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:17.264309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:16.887979Z","time spent":"376.258755ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:17.264445Z","caller":"traceutil/trace.go:171","msg":"trace[1149368600] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"247.217561ms","start":"2026-08-05T11:25:17.017216Z","end":"2026-08-05T11:25:17.264433Z","steps":["trace[1149368600] 'process raft request' (duration: 246.852381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.064236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.567918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T11:25:27.06433Z","caller":"traceutil/trace.go:171","msg":"trace[769247448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"140.693291ms","start":"2026-08-05T11:25:26.923604Z","end":"2026-08-05T11:25:27.064297Z","steps":["trace[769247448] 'range keys from in-memory index tree' (duration: 140.457875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.899053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.060991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:25:27.899189Z","caller":"traceutil/trace.go:171","msg":"trace[991927570] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"585.151868ms","start":"2026-08-05T11:25:27.314025Z","end":"2026-08-05T11:25:27.899176Z","steps":["trace[991927570] 'process raft request' (duration: 585.100126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.89925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:27.313999Z","time spent":"585.218379ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:27.899476Z","caller":"traceutil/trace.go:171","msg":"trace[1717101304] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"831.057862ms","start":"2026-08-05T11:25:27.068407Z","end":"2026-08-05T11:25:27.899465Z","steps":["trace[1717101304] 'process raft request' (duration: 645.527478ms)","trace[1717101304] 'compare' (duration: 184.638849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:27.899518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:27.06838Z","time spent":"831.118004ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:25:27.899573Z","caller":"traceutil/trace.go:171","msg":"trace[926143184] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"611.277974ms","start":"2026-08-05T11:25:27.288275Z","end":"2026-08-05T11:25:27.899553Z","steps":["trace[926143184] 'read index received' (duration: 425.691008ms)","trace[926143184] 'applied index is now lower than readState.Index' (duration: 185.568955ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:27.899749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.472149ms","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-08-05T11:25:27.899755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.04503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:25:27.8998Z","caller":"traceutil/trace.go:171","msg":"trace[2079801509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1354; }","duration":"611.554381ms","start":"2026-08-05T11:25:27.288233Z","end":"2026-08-05T11:25:27.899787Z","steps":["trace[2079801509] 'agreement among raft nodes before linearized reading' (duration: 611.403557ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:27.89982Z","caller":"traceutil/trace.go:171","msg":"trace[1146117362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"506.153163ms","start":"2026-08-05T11:25:27.393659Z","end":"2026-08-05T11:25:27.899812Z","steps":["trace[1146117362] 'agreement among raft nodes before linearized reading' (duration: 506.05536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.899822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.488076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-05T11:25:27.89984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:27.288217Z","time spent":"611.611962ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:25:27.899842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:27.393642Z","time spent":"506.194493ms","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:25:27.899884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.782251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-05T11:25:27.899928Z","caller":"traceutil/trace.go:171","msg":"trace[313682374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"571.867204ms","start":"2026-08-05T11:25:27.32805Z","end":"2026-08-05T11:25:27.899918Z","steps":["trace[313682374] 'agreement among raft nodes before linearized reading' (duration: 571.762091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.899963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:27.32803Z","time spent":"571.925035ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T11:25:27.899873Z","caller":"traceutil/trace.go:171","msg":"trace[1107370178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"575.563807ms","start":"2026-08-05T11:25:27.324297Z","end":"2026-08-05T11:25:27.899861Z","steps":["trace[1107370178] 'agreement among raft nodes before linearized reading' (duration: 575.393602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.900463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:25:27.324287Z","time spent":"576.160594ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}