{"level":"warn","ts":"2026-02-11T03:15:17.203037Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T03:15:17.203302Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-02-11T03:15:17.203499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T03:15:17.203576Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-02-11T03:15:17.203656Z","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-02-11T03:15:17.204591Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-02-11T03:15:17.204772Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-02-11T03:15:17.23075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.604007ms"} {"level":"info","ts":"2026-02-11T03:15:17.301004Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-02-11T03:15:17.301364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T03:15:17.301516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-02-11T03:15:17.301603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T03:15:17.301685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-02-11T03:15:17.301876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-02-11T03:15:17.328134Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T03:15:17.331087Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T03:15:17.378768Z","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-02-11T03:15:17.4604Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T03:15:17.464001Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T03:15:17.46431Z","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-02-11T03:15:17.464384Z","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-02-11T03:15:17.46446Z","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-02-11T03:15:17.469973Z","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-02-11T03:15:17.47016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T03:15:17.470204Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T03:15:17.470304Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-02-11T03:15:17.470343Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-02-11T03:15:17.521988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-02-11T03:15:17.522555Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-02-11T03:15:17.902665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T03:15:17.902742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T03:15:17.902814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-02-11T03:15:17.902837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-02-11T03:15:17.902851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-02-11T03:15:17.902865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-02-11T03:15:17.902887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-02-11T03:15:18.259179Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:15:18.282236Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T03:15:18.282273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T03:15:18.283208Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T03:15:18.286143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-02-11T03:15:18.289049Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T03:15:18.289121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T03:15:18.290185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T03:15:18.304337Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:15:18.30447Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T03:15:18.304524Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-11T03:15:18.492308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.328349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:18.49238Z","caller":"traceutil/trace.go:171","msg":"trace[640427620] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"142.447524ms","start":"2026-02-11T03:15:18.349918Z","end":"2026-02-11T03:15:18.492366Z","steps":["trace[640427620] 'count revisions from in-memory index tree' (duration: 142.296791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:18.492766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.011027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:18.492803Z","caller":"traceutil/trace.go:171","msg":"trace[475775663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"143.054465ms","start":"2026-02-11T03:15:18.349741Z","end":"2026-02-11T03:15:18.492795Z","steps":["trace[475775663] 'range keys from in-memory index tree' (duration: 142.74107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:18.493077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.064959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:18.493106Z","caller":"traceutil/trace.go:171","msg":"trace[1313177245] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"116.115647ms","start":"2026-02-11T03:15:18.376983Z","end":"2026-02-11T03:15:18.493098Z","steps":["trace[1313177245] 'count revisions from in-memory index tree' (duration: 116.018941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:18.493367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.97279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:18.493463Z","caller":"traceutil/trace.go:171","msg":"trace[1822489868] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"104.032908ms","start":"2026-02-11T03:15:18.389355Z","end":"2026-02-11T03:15:18.493388Z","steps":["trace[1822489868] 'count revisions from in-memory index tree' (duration: 103.908114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:18.493688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.40592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:18.493715Z","caller":"traceutil/trace.go:171","msg":"trace[1118643429] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"104.468777ms","start":"2026-02-11T03:15:18.38924Z","end":"2026-02-11T03:15:18.493709Z","steps":["trace[1118643429] 'range keys from in-memory index tree' (duration: 104.202299ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.07111Z","caller":"traceutil/trace.go:171","msg":"trace[121575317] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"199.794063ms","start":"2026-02-11T03:15:19.87128Z","end":"2026-02-11T03:15:20.071074Z","steps":["trace[121575317] 'process raft request' (duration: 199.604301ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.391285Z","caller":"traceutil/trace.go:171","msg":"trace[661645851] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"513.604512ms","start":"2026-02-11T03:15:19.87765Z","end":"2026-02-11T03:15:20.391254Z","steps":["trace[661645851] 'read index received' (duration: 193.219954ms)","trace[661645851] 'applied index is now lower than readState.Index' (duration: 320.383508ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:20.391417Z","caller":"traceutil/trace.go:171","msg":"trace[1940043603] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"517.152019ms","start":"2026-02-11T03:15:19.874255Z","end":"2026-02-11T03:15:20.391407Z","steps":["trace[1940043603] 'process raft request' (duration: 516.510898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.392249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.176294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.39249Z","caller":"traceutil/trace.go:171","msg":"trace[323556038] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"435.47409ms","start":"2026-02-11T03:15:19.956991Z","end":"2026-02-11T03:15:20.392465Z","steps":["trace[323556038] 'agreement among raft nodes before linearized reading' (duration: 435.081498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.392677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.956981Z","time spent":"435.679871ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T03:15:20.392731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.874249Z","time spent":"517.198877ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:20.393772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.649003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.393964Z","caller":"traceutil/trace.go:171","msg":"trace[791592589] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"381.854293ms","start":"2026-02-11T03:15:20.012096Z","end":"2026-02-11T03:15:20.39395Z","steps":["trace[791592589] 'agreement among raft nodes before linearized reading' (duration: 381.641343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.394141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:20.012091Z","time spent":"382.036205ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T03:15:20.394367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.529788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.394539Z","caller":"traceutil/trace.go:171","msg":"trace[1448446166] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"383.70988ms","start":"2026-02-11T03:15:20.010816Z","end":"2026-02-11T03:15:20.394526Z","steps":["trace[1448446166] 'agreement among raft nodes before linearized reading' (duration: 383.50012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.394749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:20.010811Z","time spent":"383.92484ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T03:15:20.395404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.215885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.396276Z","caller":"traceutil/trace.go:171","msg":"trace[1353819546] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"480.801854ms","start":"2026-02-11T03:15:19.915465Z","end":"2026-02-11T03:15:20.396267Z","steps":["trace[1353819546] 'process raft request' (duration: 475.695453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.396354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.915428Z","time spent":"480.886451ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2846,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:20.396812Z","caller":"traceutil/trace.go:171","msg":"trace[2011618404] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"436.872362ms","start":"2026-02-11T03:15:19.959928Z","end":"2026-02-11T03:15:20.396801Z","steps":["trace[2011618404] 'process raft request' (duration: 431.283571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.39687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.959913Z","time spent":"436.93862ms","remote":"127.0.0.1:58514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:20.397992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.331331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.39808Z","caller":"traceutil/trace.go:171","msg":"trace[1653683932] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"520.419537ms","start":"2026-02-11T03:15:19.877647Z","end":"2026-02-11T03:15:20.398067Z","steps":["trace[1653683932] 'agreement among raft nodes before linearized reading' (duration: 519.436241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.39824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.877628Z","time spent":"520.602139ms","remote":"127.0.0.1:58448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-11T03:15:20.399026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.46028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.399056Z","caller":"traceutil/trace.go:171","msg":"trace[1197640364] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:0; response_revision:10; }","duration":"440.595764ms","start":"2026-02-11T03:15:19.958453Z","end":"2026-02-11T03:15:20.399049Z","steps":["trace[1197640364] 'agreement among raft nodes before linearized reading' (duration: 440.533047ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.396285Z","caller":"traceutil/trace.go:171","msg":"trace[1995873191] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"436.154114ms","start":"2026-02-11T03:15:19.960113Z","end":"2026-02-11T03:15:20.396267Z","steps":["trace[1995873191] 'agreement among raft nodes before linearized reading' (duration: 435.200967ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.395978Z","caller":"traceutil/trace.go:171","msg":"trace[1115082421] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"510.617655ms","start":"2026-02-11T03:15:19.885341Z","end":"2026-02-11T03:15:20.395958Z","steps":["trace[1115082421] 'process raft request' (duration: 505.630728ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.396044Z","caller":"traceutil/trace.go:171","msg":"trace[771454734] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"510.396374ms","start":"2026-02-11T03:15:19.885617Z","end":"2026-02-11T03:15:20.396014Z","steps":["trace[771454734] 'process raft request' (duration: 505.402928ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.396072Z","caller":"traceutil/trace.go:171","msg":"trace[2088346921] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"510.388895ms","start":"2026-02-11T03:15:19.885677Z","end":"2026-02-11T03:15:20.396065Z","steps":["trace[2088346921] 'process raft request' (duration: 505.36815ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.3961Z","caller":"traceutil/trace.go:171","msg":"trace[1307135032] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"510.371556ms","start":"2026-02-11T03:15:19.885722Z","end":"2026-02-11T03:15:20.396093Z","steps":["trace[1307135032] 'process raft request' (duration: 505.35643ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.396127Z","caller":"traceutil/trace.go:171","msg":"trace[887106268] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"510.330817ms","start":"2026-02-11T03:15:19.885788Z","end":"2026-02-11T03:15:20.396119Z","steps":["trace[887106268] 'process raft request' (duration: 505.325152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.399075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.958391Z","time spent":"440.67943ms","remote":"127.0.0.1:58092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-02-11T03:15:20.400194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.960103Z","time spent":"440.025051ms","remote":"127.0.0.1:58138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-11T03:15:20.400395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.88533Z","time spent":"515.008259ms","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:20.400507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.885714Z","time spent":"514.642666ms","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:20.400625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.885782Z","time spent":"514.77754ms","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:20.401045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.88561Z","time spent":"515.416531ms","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T03:15:20.401231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:19.885669Z","time spent":"515.536906ms","remote":"127.0.0.1:58600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:20.560539Z","caller":"traceutil/trace.go:171","msg":"trace[1460666588] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"143.171678ms","start":"2026-02-11T03:15:20.417327Z","end":"2026-02-11T03:15:20.560499Z","steps":["trace[1460666588] 'process raft request' (duration: 109.061482ms)","trace[1460666588] 'compare' (duration: 33.164079ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:20.562562Z","caller":"traceutil/trace.go:171","msg":"trace[774239957] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"144.898132ms","start":"2026-02-11T03:15:20.41765Z","end":"2026-02-11T03:15:20.562548Z","steps":["trace[774239957] 'process raft request' (duration: 144.668852ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.562818Z","caller":"traceutil/trace.go:171","msg":"trace[721907734] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"145.209267ms","start":"2026-02-11T03:15:20.417599Z","end":"2026-02-11T03:15:20.562808Z","steps":["trace[721907734] 'process raft request' (duration: 144.48921ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.563086Z","caller":"traceutil/trace.go:171","msg":"trace[1709715562] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"145.401879ms","start":"2026-02-11T03:15:20.417675Z","end":"2026-02-11T03:15:20.563077Z","steps":["trace[1709715562] 'process raft request' (duration: 144.71603ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.566757Z","caller":"traceutil/trace.go:171","msg":"trace[248311029] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:14; }","duration":"127.280868ms","start":"2026-02-11T03:15:20.439457Z","end":"2026-02-11T03:15:20.566738Z","steps":["trace[248311029] 'read index received' (duration: 87.301213ms)","trace[248311029] 'applied index is now lower than readState.Index' (duration: 39.974935ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:20.568475Z","caller":"traceutil/trace.go:171","msg":"trace[1977179322] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"148.899103ms","start":"2026-02-11T03:15:20.419561Z","end":"2026-02-11T03:15:20.56846Z","steps":["trace[1977179322] 'process raft request' (duration: 142.957598ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.568998Z","caller":"traceutil/trace.go:171","msg":"trace[1512781766] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"149.39815ms","start":"2026-02-11T03:15:20.41959Z","end":"2026-02-11T03:15:20.568989Z","steps":["trace[1512781766] 'process raft request' (duration: 143.36271ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.570188Z","caller":"traceutil/trace.go:171","msg":"trace[1656527790] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"150.09456ms","start":"2026-02-11T03:15:20.420084Z","end":"2026-02-11T03:15:20.570179Z","steps":["trace[1656527790] 'process raft request' (duration: 145.445397ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.566564Z","caller":"traceutil/trace.go:171","msg":"trace[2015602744] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"147.308664ms","start":"2026-02-11T03:15:20.419227Z","end":"2026-02-11T03:15:20.566536Z","steps":["trace[2015602744] 'process raft request' (duration: 143.245506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.570454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.159753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.570495Z","caller":"traceutil/trace.go:171","msg":"trace[57380942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"153.2242ms","start":"2026-02-11T03:15:20.417259Z","end":"2026-02-11T03:15:20.570483Z","steps":["trace[57380942] 'agreement among raft nodes before linearized reading' (duration: 153.142164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:20.570762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.155289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T03:15:20.571531Z","caller":"traceutil/trace.go:171","msg":"trace[1027684527] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"115.943794ms","start":"2026-02-11T03:15:20.45557Z","end":"2026-02-11T03:15:20.571514Z","steps":["trace[1027684527] 'agreement among raft nodes before linearized reading' (duration: 115.087362ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.572638Z","caller":"traceutil/trace.go:171","msg":"trace[695173048] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"116.766947ms","start":"2026-02-11T03:15:20.455858Z","end":"2026-02-11T03:15:20.572625Z","steps":["trace[695173048] 'process raft request' (duration: 109.840887ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:20.573813Z","caller":"traceutil/trace.go:171","msg":"trace[1373016533] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"117.746844ms","start":"2026-02-11T03:15:20.456059Z","end":"2026-02-11T03:15:20.573806Z","steps":["trace[1373016533] 'process raft request' (duration: 109.717322ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:21.835328Z","caller":"traceutil/trace.go:171","msg":"trace[1812901267] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"262.835157ms","start":"2026-02-11T03:15:21.572443Z","end":"2026-02-11T03:15:21.835278Z","steps":["trace[1812901267] 'process raft request' (duration: 168.352596ms)","trace[1812901267] 'compare' (duration: 94.262151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:21.835733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.369682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:21.835923Z","caller":"traceutil/trace.go:171","msg":"trace[678362650] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:189; }","duration":"222.617401ms","start":"2026-02-11T03:15:21.613288Z","end":"2026-02-11T03:15:21.835906Z","steps":["trace[678362650] 'agreement among raft nodes before linearized reading' (duration: 222.072705ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:21.835251Z","caller":"traceutil/trace.go:171","msg":"trace[1635824045] linearizableReadLoop","detail":"{readStateIndex:194; appliedIndex:193; }","duration":"221.910112ms","start":"2026-02-11T03:15:21.613313Z","end":"2026-02-11T03:15:21.835223Z","steps":["trace[1635824045] 'read index received' (duration: 127.428301ms)","trace[1635824045] 'applied index is now lower than readState.Index' (duration: 94.480591ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:22.375744Z","caller":"traceutil/trace.go:171","msg":"trace[1836616645] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"166.381497ms","start":"2026-02-11T03:15:22.209332Z","end":"2026-02-11T03:15:22.375713Z","steps":["trace[1836616645] 'process raft request' (duration: 86.022424ms)","trace[1836616645] 'compare' (duration: 80.123273ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:22.532408Z","caller":"traceutil/trace.go:171","msg":"trace[1989598302] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"152.400546ms","start":"2026-02-11T03:15:22.379973Z","end":"2026-02-11T03:15:22.532374Z","steps":["trace[1989598302] 'process raft request' (duration: 123.133611ms)","trace[1989598302] 'compare' (duration: 29.097033ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:22.913612Z","caller":"traceutil/trace.go:171","msg":"trace[1229928008] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"164.541336ms","start":"2026-02-11T03:15:22.748996Z","end":"2026-02-11T03:15:22.913537Z","steps":["trace[1229928008] 'process raft request' (duration: 97.484632ms)","trace[1229928008] 'compare' (duration: 66.42801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:23.437463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.058951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-lk8a2y\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:23.43762Z","caller":"traceutil/trace.go:171","msg":"trace[1164321249] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-lk8a2y; range_end:; response_count:0; response_revision:250; }","duration":"158.361858ms","start":"2026-02-11T03:15:23.279235Z","end":"2026-02-11T03:15:23.437597Z","steps":["trace[1164321249] 'range keys from in-memory index tree' (duration: 157.863858ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:23.733409Z","caller":"traceutil/trace.go:171","msg":"trace[1607053575] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"210.258654ms","start":"2026-02-11T03:15:23.523125Z","end":"2026-02-11T03:15:23.733383Z","steps":["trace[1607053575] 'process raft request' (duration: 137.897855ms)","trace[1607053575] 'compare' (duration: 72.179776ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:24.088424Z","caller":"traceutil/trace.go:171","msg":"trace[2053202197] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"101.70354ms","start":"2026-02-11T03:15:23.98669Z","end":"2026-02-11T03:15:24.088393Z","steps":["trace[2053202197] 'process raft request' (duration: 64.839784ms)","trace[2053202197] 'compare' (duration: 36.306747ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:24.088646Z","caller":"traceutil/trace.go:171","msg":"trace[1221621510] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:263; }","duration":"101.828415ms","start":"2026-02-11T03:15:23.986808Z","end":"2026-02-11T03:15:24.088636Z","steps":["trace[1221621510] 'read index received' (duration: 64.737898ms)","trace[1221621510] 'applied index is now lower than readState.Index' (duration: 37.089007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:24.088945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.095735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:24.088988Z","caller":"traceutil/trace.go:171","msg":"trace[2079933416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:259; }","duration":"102.21284ms","start":"2026-02-11T03:15:23.986761Z","end":"2026-02-11T03:15:24.088974Z","steps":["trace[2079933416] 'agreement among raft nodes before linearized reading' (duration: 101.930381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:24.622883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.130291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-11T03:15:24.622945Z","caller":"traceutil/trace.go:171","msg":"trace[401589996] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:286; }","duration":"173.245106ms","start":"2026-02-11T03:15:24.449687Z","end":"2026-02-11T03:15:24.622932Z","steps":["trace[401589996] 'range keys from in-memory index tree' (duration: 173.042065ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:24.915691Z","caller":"traceutil/trace.go:171","msg":"trace[458084370] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"180.955431ms","start":"2026-02-11T03:15:24.734714Z","end":"2026-02-11T03:15:24.91567Z","steps":["trace[458084370] 'process raft request' (duration: 180.842586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.204774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.567473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:15:25.205062Z","caller":"traceutil/trace.go:171","msg":"trace[1764798233] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"285.861323ms","start":"2026-02-11T03:15:24.919184Z","end":"2026-02-11T03:15:25.205045Z","steps":["trace[1764798233] 'process raft request' (duration: 285.816645ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:25.205363Z","caller":"traceutil/trace.go:171","msg":"trace[83191370] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"448.330558ms","start":"2026-02-11T03:15:24.757021Z","end":"2026-02-11T03:15:25.205351Z","steps":["trace[83191370] 'process raft request' (duration: 201.982875ms)","trace[83191370] 'compare' (duration: 245.441708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:25.205416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.757001Z","time spent":"448.386457ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:25.205543Z","caller":"traceutil/trace.go:171","msg":"trace[586966569] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"304.680045ms","start":"2026-02-11T03:15:24.900854Z","end":"2026-02-11T03:15:25.205534Z","steps":["trace[586966569] 'process raft request' (duration: 304.009071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.205586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.900837Z","time spent":"304.728503ms","remote":"127.0.0.1:58136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T03:15:25.205713Z","caller":"traceutil/trace.go:171","msg":"trace[2118176825] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:293; }","duration":"304.78939ms","start":"2026-02-11T03:15:24.900914Z","end":"2026-02-11T03:15:25.205704Z","steps":["trace[2118176825] 'read index received' (duration: 15.094869ms)","trace[2118176825] 'applied index is now lower than readState.Index' (duration: 289.693371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:25.205963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.009322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:25.205996Z","caller":"traceutil/trace.go:171","msg":"trace[150130723] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:290; }","duration":"305.08798ms","start":"2026-02-11T03:15:24.900898Z","end":"2026-02-11T03:15:25.205986Z","steps":["trace[150130723] 'agreement among raft nodes before linearized reading' (duration: 304.983603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.206027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.900894Z","time spent":"305.127059ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-11T03:15:25.224736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.709022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-11T03:15:25.224797Z","caller":"traceutil/trace.go:171","msg":"trace[918735912] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:290; }","duration":"323.795218ms","start":"2026-02-11T03:15:24.900988Z","end":"2026-02-11T03:15:25.224783Z","steps":["trace[918735912] 'agreement among raft nodes before linearized reading' (duration: 323.671333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:25.224829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:24.900983Z","time spent":"323.838347ms","remote":"127.0.0.1:58448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-11T03:15:25.225218Z","caller":"traceutil/trace.go:171","msg":"trace[1640185752] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"229.026255ms","start":"2026-02-11T03:15:24.995615Z","end":"2026-02-11T03:15:25.224641Z","steps":["trace[1640185752] 'process raft request' (duration: 228.89622ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:32.381718Z","caller":"traceutil/trace.go:171","msg":"trace[1210464432] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"189.903675ms","start":"2026-02-11T03:15:32.19179Z","end":"2026-02-11T03:15:32.381694Z","steps":["trace[1210464432] 'read index received' (duration: 109.828401ms)","trace[1210464432] 'applied index is now lower than readState.Index' (duration: 80.074464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:32.382128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.312983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-11T03:15:32.382241Z","caller":"traceutil/trace.go:171","msg":"trace[1188911308] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:411; }","duration":"190.447088ms","start":"2026-02-11T03:15:32.191754Z","end":"2026-02-11T03:15:32.382202Z","steps":["trace[1188911308] 'agreement among raft nodes before linearized reading' (duration: 190.137178ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:32.382942Z","caller":"traceutil/trace.go:171","msg":"trace[142687574] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"210.395425ms","start":"2026-02-11T03:15:32.172526Z","end":"2026-02-11T03:15:32.382921Z","steps":["trace[142687574] 'process raft request' (duration: 129.082148ms)","trace[142687574] 'compare' (duration: 79.997127ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:32.745955Z","caller":"traceutil/trace.go:171","msg":"trace[825387050] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"216.569964ms","start":"2026-02-11T03:15:32.529361Z","end":"2026-02-11T03:15:32.74593Z","steps":["trace[825387050] 'process raft request' (duration: 216.38074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.791792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.022877577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:15:35.79224Z","caller":"traceutil/trace.go:171","msg":"trace[903042061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"2.023341375s","start":"2026-02-11T03:15:33.768868Z","end":"2026-02-11T03:15:35.79221Z","steps":["trace[903042061] 'range keys from in-memory index tree' (duration: 2.022680263s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.792494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.768798Z","time spent":"2.023666516s","remote":"127.0.0.1:58044","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-02-11T03:15:35.792903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.801827214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"info","ts":"2026-02-11T03:15:35.79313Z","caller":"traceutil/trace.go:171","msg":"trace[829835132] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:413; }","duration":"2.802089347s","start":"2026-02-11T03:15:32.991021Z","end":"2026-02-11T03:15:35.793111Z","steps":["trace[829835132] 'range keys from in-memory index tree' (duration: 2.8016403s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.793261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:32.991003Z","time spent":"2.802238713s","remote":"127.0.0.1:58220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5283,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-11T03:15:35.793803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.214045497s","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-02-11T03:15:35.794061Z","caller":"traceutil/trace.go:171","msg":"trace[1035136080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"2.214348428s","start":"2026-02-11T03:15:33.579694Z","end":"2026-02-11T03:15:35.794042Z","steps":["trace[1035136080] 'range keys from in-memory index tree' (duration: 2.2139254s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.794206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.579666Z","time spent":"2.214520313s","remote":"127.0.0.1:58310","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-02-11T03:15:35.794707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.173949082s","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-02-11T03:15:35.794875Z","caller":"traceutil/trace.go:171","msg":"trace[78736863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"2.174157916s","start":"2026-02-11T03:15:33.620698Z","end":"2026-02-11T03:15:35.794856Z","steps":["trace[78736863] 'range keys from in-memory index tree' (duration: 2.173858624s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.795301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:33.620674Z","time spent":"2.174607783s","remote":"127.0.0.1:58310","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-02-11T03:15:35.796856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40889296s","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-02-11T03:15:35.796902Z","caller":"traceutil/trace.go:171","msg":"trace[830739884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"1.408991757s","start":"2026-02-11T03:15:34.387899Z","end":"2026-02-11T03:15:35.796891Z","steps":["trace[830739884] 'range keys from in-memory index tree' (duration: 1.408749705s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.79693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:34.387869Z","time spent":"1.409053496s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:15:35.797179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346185171s","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-02-11T03:15:35.797207Z","caller":"traceutil/trace.go:171","msg":"trace[1884688663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"1.346258718s","start":"2026-02-11T03:15:34.450938Z","end":"2026-02-11T03:15:35.797197Z","steps":["trace[1884688663] 'range keys from in-memory index tree' (duration: 1.345691855s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:35.797231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:34.450908Z","time spent":"1.346314216s","remote":"127.0.0.1:58310","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-02-11T03:15:35.850231Z","caller":"traceutil/trace.go:171","msg":"trace[1015990475] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"162.405851ms","start":"2026-02-11T03:15:35.687798Z","end":"2026-02-11T03:15:35.850204Z","steps":["trace[1015990475] 'process raft request' (duration: 162.208916ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:35.979082Z","caller":"traceutil/trace.go:171","msg":"trace[1150024461] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"158.017792ms","start":"2026-02-11T03:15:35.821037Z","end":"2026-02-11T03:15:35.979055Z","steps":["trace[1150024461] 'process raft request' (duration: 157.976523ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:35.979597Z","caller":"traceutil/trace.go:171","msg":"trace[1850178078] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"205.938165ms","start":"2026-02-11T03:15:35.773646Z","end":"2026-02-11T03:15:35.979584Z","steps":["trace[1850178078] 'process raft request' (duration: 172.334466ms)","trace[1850178078] 'compare' (duration: 32.725913ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:15:35.980404Z","caller":"traceutil/trace.go:171","msg":"trace[601118109] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"160.864304ms","start":"2026-02-11T03:15:35.819528Z","end":"2026-02-11T03:15:35.980392Z","steps":["trace[601118109] 'process raft request' (duration: 159.310517ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:35.980526Z","caller":"traceutil/trace.go:171","msg":"trace[1584235719] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"160.834565ms","start":"2026-02-11T03:15:35.819685Z","end":"2026-02-11T03:15:35.98052Z","steps":["trace[1584235719] 'process raft request' (duration: 159.20185ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:35.980692Z","caller":"traceutil/trace.go:171","msg":"trace[996105932] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"160.957891ms","start":"2026-02-11T03:15:35.819727Z","end":"2026-02-11T03:15:35.980685Z","steps":["trace[996105932] 'process raft request' (duration: 159.19057ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:35.980776Z","caller":"traceutil/trace.go:171","msg":"trace[54945727] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"161.02325ms","start":"2026-02-11T03:15:35.819745Z","end":"2026-02-11T03:15:35.980768Z","steps":["trace[54945727] 'process raft request' (duration: 159.226669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:37.664146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.5694ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T03:15:38.150148Z","caller":"traceutil/trace.go:171","msg":"trace[1571841421] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"119.87862ms","start":"2026-02-11T03:15:38.030227Z","end":"2026-02-11T03:15:38.150106Z","steps":["trace[1571841421] 'process raft request' (duration: 97.006457ms)","trace[1571841421] 'compare' (duration: 22.59454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:40.533341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:15:41.034288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:15:41.476115Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449808661s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T03:15:41.476708Z","caller":"traceutil/trace.go:171","msg":"trace[604602574] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.450448677s","start":"2026-02-11T03:15:40.026232Z","end":"2026-02-11T03:15:41.476681Z","steps":["trace[604602574] 'process raft request' (duration: 1.450099775s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.476853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.026221Z","time spent":"1.450536004s","remote":"127.0.0.1:58310","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-02-11T03:15:41.716225Z","caller":"traceutil/trace.go:171","msg":"trace[1088622937] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"1.68834875s","start":"2026-02-11T03:15:40.027848Z","end":"2026-02-11T03:15:41.716197Z","steps":["trace[1088622937] 'process raft request' (duration: 1.685703998s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.716412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.02784Z","time spent":"1.688456168s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:15:41.716751Z","caller":"traceutil/trace.go:171","msg":"trace[447924316] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"1.684328844s","start":"2026-02-11T03:15:40.032412Z","end":"2026-02-11T03:15:41.71674Z","steps":["trace[447924316] 'read index received' (duration: 1.445114069s)","trace[447924316] 'applied index is now lower than readState.Index' (duration: 239.214115ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:41.716988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561186132s","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-02-11T03:15:41.717036Z","caller":"traceutil/trace.go:171","msg":"trace[1515975747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"1.56127468s","start":"2026-02-11T03:15:40.15575Z","end":"2026-02-11T03:15:41.717024Z","steps":["trace[1515975747] 'agreement among raft nodes before linearized reading' (duration: 1.561197092s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.717072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.155731Z","time spent":"1.56133197s","remote":"127.0.0.1:58310","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-02-11T03:15:41.717011Z","caller":"traceutil/trace.go:171","msg":"trace[451651175] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"546.705478ms","start":"2026-02-11T03:15:41.170297Z","end":"2026-02-11T03:15:41.717002Z","steps":["trace[451651175] 'process raft request' (duration: 545.747318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.717226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:41.170273Z","time spent":"546.923694ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:15:41.717259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684842392s","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-02-11T03:15:41.717306Z","caller":"traceutil/trace.go:171","msg":"trace[1799909310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"1.68493878s","start":"2026-02-11T03:15:40.032355Z","end":"2026-02-11T03:15:41.717294Z","steps":["trace[1799909310] 'agreement among raft nodes before linearized reading' (duration: 1.684860292s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:41.717348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:40.032328Z","time spent":"1.685010958s","remote":"127.0.0.1:58310","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-02-11T03:15:43.593283Z","caller":"traceutil/trace.go:171","msg":"trace[1729969441] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"108.719807ms","start":"2026-02-11T03:15:43.484528Z","end":"2026-02-11T03:15:43.593247Z","steps":["trace[1729969441] 'process raft request' (duration: 108.547639ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:52.023895Z","caller":"traceutil/trace.go:171","msg":"trace[1091917338] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"232.840643ms","start":"2026-02-11T03:15:51.791027Z","end":"2026-02-11T03:15:52.023867Z","steps":["trace[1091917338] 'process raft request' (duration: 232.660235ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:52.024807Z","caller":"traceutil/trace.go:171","msg":"trace[974794599] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:501; }","duration":"228.838717ms","start":"2026-02-11T03:15:51.795953Z","end":"2026-02-11T03:15:52.024792Z","steps":["trace[974794599] 'read index received' (duration: 228.832388ms)","trace[974794599] 'applied index is now lower than readState.Index' (duration: 5.459µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:15:52.025109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.132455ms","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-02-11T03:15:52.025207Z","caller":"traceutil/trace.go:171","msg":"trace[1104213408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"229.265952ms","start":"2026-02-11T03:15:51.795931Z","end":"2026-02-11T03:15:52.025197Z","steps":["trace[1104213408] 'agreement among raft nodes before linearized reading' (duration: 229.090494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:52.02656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.232216ms","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-02-11T03:15:52.027358Z","caller":"traceutil/trace.go:171","msg":"trace[1996283729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"173.091814ms","start":"2026-02-11T03:15:51.854244Z","end":"2026-02-11T03:15:52.027335Z","steps":["trace[1996283729] 'agreement among raft nodes before linearized reading' (duration: 172.193756ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:15:52.027786Z","caller":"traceutil/trace.go:171","msg":"trace[906196845] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"126.764582ms","start":"2026-02-11T03:15:51.901008Z","end":"2026-02-11T03:15:52.027772Z","steps":["trace[906196845] 'process raft request' (duration: 125.182003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:15:57.875333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.127227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rswb8\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-11T03:15:57.875423Z","caller":"traceutil/trace.go:171","msg":"trace[2060437155] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rswb8; range_end:; response_count:1; response_revision:530; }","duration":"152.284206ms","start":"2026-02-11T03:15:57.723119Z","end":"2026-02-11T03:15:57.875404Z","steps":["trace[2060437155] 'range keys from in-memory index tree' (duration: 151.88663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.423863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240578,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T03:16:00.434331Z","caller":"traceutil/trace.go:171","msg":"trace[1008554358] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"511.420459ms","start":"2026-02-11T03:15:59.922883Z","end":"2026-02-11T03:16:00.434304Z","steps":["trace[1008554358] 'read index received' (duration: 511.017552ms)","trace[1008554358] 'applied index is now lower than readState.Index' (duration: 401.927µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:00.434753Z","caller":"traceutil/trace.go:171","msg":"trace[679299065] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"685.068314ms","start":"2026-02-11T03:15:59.749671Z","end":"2026-02-11T03:16:00.43474Z","steps":["trace[679299065] 'process raft request' (duration: 684.486587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.434877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:59.749647Z","time spent":"685.134014ms","remote":"127.0.0.1:58310","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-02-11T03:16:00.4365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.610954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-02-11T03:16:00.436538Z","caller":"traceutil/trace.go:171","msg":"trace[808551527] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:536; }","duration":"513.696603ms","start":"2026-02-11T03:15:59.922832Z","end":"2026-02-11T03:16:00.436529Z","steps":["trace[808551527] 'agreement among raft nodes before linearized reading' (duration: 513.621073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.436563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:15:59.922809Z","time spent":"513.749703ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-11T03:16:00.436693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.560138ms","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-02-11T03:16:00.436714Z","caller":"traceutil/trace.go:171","msg":"trace[1232485853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"372.616888ms","start":"2026-02-11T03:16:00.064089Z","end":"2026-02-11T03:16:00.436706Z","steps":["trace[1232485853] 'agreement among raft nodes before linearized reading' (duration: 372.577568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.436731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.06398Z","time spent":"372.747416ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:16:00.436885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.324346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:00.436905Z","caller":"traceutil/trace.go:171","msg":"trace[36078707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"214.382086ms","start":"2026-02-11T03:16:00.222517Z","end":"2026-02-11T03:16:00.436899Z","steps":["trace[36078707] 'agreement among raft nodes before linearized reading' (duration: 214.345936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.437017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.279687ms","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-02-11T03:16:00.437034Z","caller":"traceutil/trace.go:171","msg":"trace[2130736659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"348.307957ms","start":"2026-02-11T03:16:00.088721Z","end":"2026-02-11T03:16:00.437029Z","steps":["trace[2130736659] 'agreement among raft nodes before linearized reading' (duration: 348.270777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.437053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.088717Z","time spent":"348.329807ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:16:00.437139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.434536ms","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-02-11T03:16:00.438532Z","caller":"traceutil/trace.go:171","msg":"trace[750893335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"349.856317ms","start":"2026-02-11T03:16:00.088666Z","end":"2026-02-11T03:16:00.438522Z","steps":["trace[750893335] 'agreement among raft nodes before linearized reading' (duration: 348.452916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:00.438683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.088641Z","time spent":"349.994266ms","remote":"127.0.0.1:58310","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-02-11T03:16:00.951253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240581,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:01.498303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.95061ms","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-02-11T03:16:01.498447Z","caller":"traceutil/trace.go:171","msg":"trace[904558690] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:536; }","duration":"347.114429ms","start":"2026-02-11T03:16:01.151309Z","end":"2026-02-11T03:16:01.498424Z","steps":["trace[904558690] 'range keys from in-memory index tree' (duration: 346.92838ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:01.502392Z","caller":"traceutil/trace.go:171","msg":"trace[1977797807] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.051787189s","start":"2026-02-11T03:16:00.450501Z","end":"2026-02-11T03:16:01.502288Z","steps":["trace[1977797807] 'process raft request' (duration: 996.816627ms)","trace[1977797807] 'compare' (duration: 53.302632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:01.503844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.450478Z","time spent":"1.052580564s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:16:01.540395Z","caller":"traceutil/trace.go:171","msg":"trace[573316449] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:555; }","duration":"1.08948652s","start":"2026-02-11T03:16:00.450719Z","end":"2026-02-11T03:16:01.540205Z","steps":["trace[573316449] 'read index received' (duration: 996.611509ms)","trace[573316449] 'applied index is now lower than readState.Index' (duration: 92.873741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:01.540582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089834008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:01.540618Z","caller":"traceutil/trace.go:171","msg":"trace[338865161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.089907837s","start":"2026-02-11T03:16:00.450696Z","end":"2026-02-11T03:16:01.540604Z","steps":["trace[338865161] 'agreement among raft nodes before linearized reading' (duration: 1.089783518s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:01.540678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.450689Z","time spent":"1.089980027s","remote":"127.0.0.1:58058","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-02-11T03:16:01.541166Z","caller":"traceutil/trace.go:171","msg":"trace[1092697027] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.090377364s","start":"2026-02-11T03:16:00.450772Z","end":"2026-02-11T03:16:01.541149Z","steps":["trace[1092697027] 'process raft request' (duration: 1.089199921s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:01.541817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.450768Z","time spent":"1.090443864s","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4255,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:16:01.542277Z","caller":"traceutil/trace.go:171","msg":"trace[1584079153] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.085756627s","start":"2026-02-11T03:16:00.456501Z","end":"2026-02-11T03:16:01.542258Z","steps":["trace[1584079153] 'process raft request' (duration: 1.083616s)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:01.5429Z","caller":"traceutil/trace.go:171","msg":"trace[1687129920] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.086324054s","start":"2026-02-11T03:16:00.456557Z","end":"2026-02-11T03:16:01.542881Z","steps":["trace[1687129920] 'process raft request' (duration: 1.083604661s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:01.543236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.456553Z","time spent":"1.086602382s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:01.545984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:00.45649Z","time spent":"1.085836556s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:02.945944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:03.44634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:03.946558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:04.447479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:04.538867Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.174577353s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T03:16:04.578502Z","caller":"traceutil/trace.go:171","msg":"trace[53062053] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"2.472787259s","start":"2026-02-11T03:16:02.105684Z","end":"2026-02-11T03:16:04.578471Z","steps":["trace[53062053] 'process raft request' (duration: 2.433592601s)","trace[53062053] 'compare' (duration: 39.027919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:04.578686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:02.105648Z","time spent":"2.472920099s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:05.222312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999992991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T03:16:05.222432Z","caller":"traceutil/trace.go:171","msg":"trace[2081106660] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00017028s","start":"2026-02-11T03:16:03.222229Z","end":"2026-02-11T03:16:05.2224Z","steps":["trace[2081106660] 'agreement among raft nodes before linearized reading' (duration: 2.00002949s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:05.222481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:03.222198Z","time spent":"2.0002736s","remote":"127.0.0.1:58044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 03:16:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-11T03:16:05.350628Z","caller":"traceutil/trace.go:171","msg":"trace[1610320859] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:560; }","duration":"2.904656948s","start":"2026-02-11T03:16:02.445908Z","end":"2026-02-11T03:16:05.350565Z","steps":["trace[1610320859] 'read index received' (duration: 2.093386484s)","trace[1610320859] 'applied index is now lower than readState.Index' (duration: 811.269294ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:05.350749Z","caller":"traceutil/trace.go:171","msg":"trace[972525177] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"2.985307511s","start":"2026-02-11T03:16:02.365429Z","end":"2026-02-11T03:16:05.350737Z","steps":["trace[972525177] 'process raft request' (duration: 2.951093419s)","trace[972525177] 'compare' (duration: 33.263155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:05.350928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:02.365416Z","time spent":"2.985356321s","remote":"127.0.0.1:58092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:05.351204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.905292246s","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-02-11T03:16:05.351226Z","caller":"traceutil/trace.go:171","msg":"trace[1217559479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"2.905351026s","start":"2026-02-11T03:16:02.445867Z","end":"2026-02-11T03:16:05.351218Z","steps":["trace[1217559479] 'agreement among raft nodes before linearized reading' (duration: 2.905221587s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:05.351245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:02.445852Z","time spent":"2.905389156s","remote":"127.0.0.1:58310","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-02-11T03:16:05.351382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84219267s","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-02-11T03:16:05.351398Z","caller":"traceutil/trace.go:171","msg":"trace[1024788317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"1.842242489s","start":"2026-02-11T03:16:03.50915Z","end":"2026-02-11T03:16:05.351393Z","steps":["trace[1024788317] 'agreement among raft nodes before linearized reading' (duration: 1.84218234s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:05.351413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:03.509134Z","time spent":"1.842275819s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:16:05.351524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.692696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:05.351538Z","caller":"traceutil/trace.go:171","msg":"trace[1165647688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"128.742046ms","start":"2026-02-11T03:16:05.222792Z","end":"2026-02-11T03:16:05.351534Z","steps":["trace[1165647688] 'agreement among raft nodes before linearized reading' (duration: 128.715166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:05.351655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788095892s","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-02-11T03:16:05.35167Z","caller":"traceutil/trace.go:171","msg":"trace[438854646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"1.788110582s","start":"2026-02-11T03:16:03.563555Z","end":"2026-02-11T03:16:05.351665Z","steps":["trace[438854646] 'agreement among raft nodes before linearized reading' (duration: 1.788055842s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:05.351687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:03.563524Z","time spent":"1.788159241s","remote":"127.0.0.1:58310","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-02-11T03:16:05.351774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788826299s","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-02-11T03:16:05.351788Z","caller":"traceutil/trace.go:171","msg":"trace[121622461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"1.788881279s","start":"2026-02-11T03:16:03.562902Z","end":"2026-02-11T03:16:05.351783Z","steps":["trace[121622461] 'agreement among raft nodes before linearized reading' (duration: 1.788835949s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:05.351801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:03.562884Z","time spent":"1.788911769s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T03:16:10.306298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.232994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rswb8\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-11T03:16:10.306373Z","caller":"traceutil/trace.go:171","msg":"trace[305124226] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rswb8; range_end:; response_count:1; response_revision:581; }","duration":"179.322814ms","start":"2026-02-11T03:16:10.127033Z","end":"2026-02-11T03:16:10.306356Z","steps":["trace[305124226] 'range keys from in-memory index tree' (duration: 178.977233ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:10.808171Z","caller":"traceutil/trace.go:171","msg":"trace[356793137] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"486.139659ms","start":"2026-02-11T03:16:10.321996Z","end":"2026-02-11T03:16:10.808136Z","steps":["trace[356793137] 'process raft request' (duration: 485.903579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:10.808366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:10.321968Z","time spent":"486.296259ms","remote":"127.0.0.1:58220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:16:12.062183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240676,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:12.576266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240676,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:13.077333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564995107240676,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T03:16:13.55142Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.133677198s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T03:16:13.551942Z","caller":"traceutil/trace.go:171","msg":"trace[2015928657] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"1.990336686s","start":"2026-02-11T03:16:11.561587Z","end":"2026-02-11T03:16:13.551924Z","steps":["trace[2015928657] 'read index received' (duration: 1.990142755s)","trace[2015928657] 'applied index is now lower than readState.Index' (duration: 193.041µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:13.552187Z","caller":"traceutil/trace.go:171","msg":"trace[1731810621] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"2.134515039s","start":"2026-02-11T03:16:11.417663Z","end":"2026-02-11T03:16:13.552178Z","steps":["trace[1731810621] 'process raft request' (duration: 2.134115498s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:13.552269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:11.417651Z","time spent":"2.134556819s","remote":"127.0.0.1:58310","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-02-11T03:16:13.552534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990943026s","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-02-11T03:16:13.552564Z","caller":"traceutil/trace.go:171","msg":"trace[1038137565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.991016106s","start":"2026-02-11T03:16:11.561541Z","end":"2026-02-11T03:16:13.552557Z","steps":["trace[1038137565] 'agreement among raft nodes before linearized reading' (duration: 1.990848146s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:13.552584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:11.561511Z","time spent":"1.991068206s","remote":"127.0.0.1:58310","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-02-11T03:16:13.552706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903238063s","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-02-11T03:16:13.552728Z","caller":"traceutil/trace.go:171","msg":"trace[1559295946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.903303343s","start":"2026-02-11T03:16:11.649419Z","end":"2026-02-11T03:16:13.552723Z","steps":["trace[1559295946] 'agreement among raft nodes before linearized reading' (duration: 1.903248653s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:13.552745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:11.649394Z","time spent":"1.903346323s","remote":"127.0.0.1:58310","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-02-11T03:16:13.552865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.120009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:13.552885Z","caller":"traceutil/trace.go:171","msg":"trace[47949097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"334.137539ms","start":"2026-02-11T03:16:13.21874Z","end":"2026-02-11T03:16:13.552877Z","steps":["trace[47949097] 'agreement among raft nodes before linearized reading' (duration: 334.106179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:13.5529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:13.218692Z","time spent":"334.20447ms","remote":"127.0.0.1:58044","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-02-11T03:16:13.553159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473827144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-11T03:16:13.553204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.87770946s","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-02-11T03:16:13.553225Z","caller":"traceutil/trace.go:171","msg":"trace[2064063925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"1.87777433s","start":"2026-02-11T03:16:11.675445Z","end":"2026-02-11T03:16:13.55322Z","steps":["trace[2064063925] 'agreement among raft nodes before linearized reading' (duration: 1.8777159s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:13.553237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:11.675423Z","time spent":"1.87781153s","remote":"127.0.0.1:58310","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-02-11T03:16:13.553235Z","caller":"traceutil/trace.go:171","msg":"trace[742745052] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:583; }","duration":"1.473969624s","start":"2026-02-11T03:16:12.079244Z","end":"2026-02-11T03:16:13.553213Z","steps":["trace[742745052] 'agreement among raft nodes before linearized reading' (duration: 1.473777834s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:13.553297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:12.079223Z","time spent":"1.474061544s","remote":"127.0.0.1:58092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-02-11T03:16:14.224357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:13.556122Z","time spent":"668.22871ms","remote":"127.0.0.1:58092","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T03:16:14.229969Z","caller":"traceutil/trace.go:171","msg":"trace[1389832486] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"668.542183ms","start":"2026-02-11T03:16:13.561408Z","end":"2026-02-11T03:16:14.22995Z","steps":["trace[1389832486] 'process raft request' (duration: 668.502413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:14.2301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:13.561404Z","time spent":"668.632213ms","remote":"127.0.0.1:58310","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-02-11T03:16:14.230127Z","caller":"traceutil/trace.go:171","msg":"trace[2050181848] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"668.602283ms","start":"2026-02-11T03:16:13.561506Z","end":"2026-02-11T03:16:14.230108Z","steps":["trace[2050181848] 'process raft request' (duration: 668.422783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:14.230227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:13.561502Z","time spent":"668.665023ms","remote":"127.0.0.1:58310","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-02-11T03:16:14.23029Z","caller":"traceutil/trace.go:171","msg":"trace[1411894938] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"668.920624ms","start":"2026-02-11T03:16:13.561363Z","end":"2026-02-11T03:16:14.230283Z","steps":["trace[1411894938] 'process raft request' (duration: 668.423603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:14.230325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:13.561349Z","time spent":"668.957715ms","remote":"127.0.0.1:58310","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-02-11T03:16:15.78671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.566856ms","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-02-11T03:16:15.786774Z","caller":"traceutil/trace.go:171","msg":"trace[1552239401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"226.709686ms","start":"2026-02-11T03:16:15.560049Z","end":"2026-02-11T03:16:15.786759Z","steps":["trace[1552239401] 'range keys from in-memory index tree' (duration: 226.396825ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:16.80138Z","caller":"traceutil/trace.go:171","msg":"trace[1525932351] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"343.730383ms","start":"2026-02-11T03:16:16.45762Z","end":"2026-02-11T03:16:16.801351Z","steps":["trace[1525932351] 'process raft request' (duration: 343.542633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:16.801824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:16.457595Z","time spent":"344.142254ms","remote":"127.0.0.1:58310","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-02-11T03:16:19.699682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.40849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-11T03:16:19.699759Z","caller":"traceutil/trace.go:171","msg":"trace[1305533763] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:656; }","duration":"213.527911ms","start":"2026-02-11T03:16:19.486214Z","end":"2026-02-11T03:16:19.699742Z","steps":["trace[1305533763] 'range keys from in-memory index tree' (duration: 213.201679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:27.980553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.773812ms","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-02-11T03:16:27.980633Z","caller":"traceutil/trace.go:171","msg":"trace[598009244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"113.890613ms","start":"2026-02-11T03:16:27.866724Z","end":"2026-02-11T03:16:27.980614Z","steps":["trace[598009244] 'range keys from in-memory index tree' (duration: 113.614442ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:29.456246Z","caller":"traceutil/trace.go:171","msg":"trace[318401269] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"158.182079ms","start":"2026-02-11T03:16:29.29803Z","end":"2026-02-11T03:16:29.456212Z","steps":["trace[318401269] 'process raft request' (duration: 158.008836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:29.725104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.558253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T03:16:29.725479Z","caller":"traceutil/trace.go:171","msg":"trace[180445082] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"255.240863ms","start":"2026-02-11T03:16:29.470197Z","end":"2026-02-11T03:16:29.725438Z","steps":["trace[180445082] 'read index received' (duration: 43.240056ms)","trace[180445082] 'applied index is now lower than readState.Index' (duration: 211.993427ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:29.725516Z","caller":"traceutil/trace.go:171","msg":"trace[1549665767] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"253.923171ms","start":"2026-02-11T03:16:29.471557Z","end":"2026-02-11T03:16:29.72548Z","steps":["trace[1549665767] 'process raft request' (duration: 253.843571ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:29.725883Z","caller":"traceutil/trace.go:171","msg":"trace[1964743293] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"254.783079ms","start":"2026-02-11T03:16:29.471091Z","end":"2026-02-11T03:16:29.725874Z","steps":["trace[1964743293] 'process raft request' (duration: 254.159663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:29.726154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.932979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-wpp4v\" ","response":"range_response_count:1 size:5019"} {"level":"info","ts":"2026-02-11T03:16:29.726209Z","caller":"traceutil/trace.go:171","msg":"trace[1301450621] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-wpp4v; range_end:; response_count:1; response_revision:759; }","duration":"256.054549ms","start":"2026-02-11T03:16:29.470141Z","end":"2026-02-11T03:16:29.726196Z","steps":["trace[1301450621] 'agreement among raft nodes before linearized reading' (duration: 255.834517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:35.221978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.452564ms","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-02-11T03:16:35.222048Z","caller":"traceutil/trace.go:171","msg":"trace[959237024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"103.572805ms","start":"2026-02-11T03:16:35.118458Z","end":"2026-02-11T03:16:35.222031Z","steps":["trace[959237024] 'range keys from in-memory index tree' (duration: 103.289543ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:35.355944Z","caller":"traceutil/trace.go:171","msg":"trace[222684570] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"129.82883ms","start":"2026-02-11T03:16:35.22609Z","end":"2026-02-11T03:16:35.355919Z","steps":["trace[222684570] 'process raft request' (duration: 129.670498ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:36.81769Z","caller":"traceutil/trace.go:171","msg":"trace[1030603107] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"255.60749ms","start":"2026-02-11T03:16:36.562056Z","end":"2026-02-11T03:16:36.817664Z","steps":["trace[1030603107] 'read index received' (duration: 255.390308ms)","trace[1030603107] 'applied index is now lower than readState.Index' (duration: 216.032µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:36.817898Z","caller":"traceutil/trace.go:171","msg":"trace[1115035971] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"407.519905ms","start":"2026-02-11T03:16:36.410364Z","end":"2026-02-11T03:16:36.817884Z","steps":["trace[1115035971] 'process raft request' (duration: 407.11721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:36.818023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:36.410343Z","time spent":"407.591605ms","remote":"127.0.0.1:58310","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-02-11T03:16:36.818102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.843934ms","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-02-11T03:16:36.818161Z","caller":"traceutil/trace.go:171","msg":"trace[1673304237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"223.954496ms","start":"2026-02-11T03:16:36.594192Z","end":"2026-02-11T03:16:36.818147Z","steps":["trace[1673304237] 'agreement among raft nodes before linearized reading' (duration: 223.799084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:36.818291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.235438ms","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-02-11T03:16:36.818313Z","caller":"traceutil/trace.go:171","msg":"trace[4229995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"256.293559ms","start":"2026-02-11T03:16:36.562012Z","end":"2026-02-11T03:16:36.818305Z","steps":["trace[4229995] 'agreement among raft nodes before linearized reading' (duration: 256.234939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:36.818396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.98624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:16:36.818419Z","caller":"traceutil/trace.go:171","msg":"trace[1579590258] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:805; }","duration":"123.0469ms","start":"2026-02-11T03:16:36.695364Z","end":"2026-02-11T03:16:36.818411Z","steps":["trace[1579590258] 'agreement among raft nodes before linearized reading' (duration: 122.99778ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:37.51739Z","caller":"traceutil/trace.go:171","msg":"trace[670376446] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"153.919335ms","start":"2026-02-11T03:16:37.362887Z","end":"2026-02-11T03:16:37.516806Z","steps":["trace[670376446] 'read index received' (duration: 153.646302ms)","trace[670376446] 'applied index is now lower than readState.Index' (duration: 272.073µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:37.517908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.945638ms","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-02-11T03:16:37.517985Z","caller":"traceutil/trace.go:171","msg":"trace[1959827254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"155.102379ms","start":"2026-02-11T03:16:37.362837Z","end":"2026-02-11T03:16:37.517939Z","steps":["trace[1959827254] 'agreement among raft nodes before linearized reading' (duration: 154.812976ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:37.519275Z","caller":"traceutil/trace.go:171","msg":"trace[41204653] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"178.836533ms","start":"2026-02-11T03:16:37.3404Z","end":"2026-02-11T03:16:37.519236Z","steps":["trace[41204653] 'process raft request' (duration: 176.149721ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:37.74775Z","caller":"traceutil/trace.go:171","msg":"trace[2098182625] transaction","detail":"{read_only:false; number_of_response:1; response_revision:813; }","duration":"226.652833ms","start":"2026-02-11T03:16:37.52106Z","end":"2026-02-11T03:16:37.747713Z","steps":["trace[2098182625] 'process raft request' (duration: 226.44816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:16:37.747903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.786515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:16:37.747958Z","caller":"traceutil/trace.go:171","msg":"trace[1860081215] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:814; }","duration":"102.879756ms","start":"2026-02-11T03:16:37.645068Z","end":"2026-02-11T03:16:37.747948Z","steps":["trace[1860081215] 'agreement among raft nodes before linearized reading' (duration: 102.731425ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:37.747738Z","caller":"traceutil/trace.go:171","msg":"trace[1553617710] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:841; }","duration":"102.627383ms","start":"2026-02-11T03:16:37.645081Z","end":"2026-02-11T03:16:37.747709Z","steps":["trace[1553617710] 'read index received' (duration: 26.111451ms)","trace[1553617710] 'applied index is now lower than readState.Index' (duration: 76.514422ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:37.748284Z","caller":"traceutil/trace.go:171","msg":"trace[987028960] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"217.386152ms","start":"2026-02-11T03:16:37.530889Z","end":"2026-02-11T03:16:37.748275Z","steps":["trace[987028960] 'process raft request' (duration: 216.759254ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:47.25158Z","caller":"traceutil/trace.go:171","msg":"trace[705774418] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"299.170915ms","start":"2026-02-11T03:16:46.951408Z","end":"2026-02-11T03:16:47.250579Z","steps":["trace[705774418] 'process raft request' (duration: 261.628996ms)","trace[705774418] 'compare' (duration: 36.405741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:47.25211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:16:46.951379Z","time spent":"300.655267ms","remote":"127.0.0.1:58310","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-02-11T03:16:47.252718Z","caller":"traceutil/trace.go:171","msg":"trace[1455854186] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:869; }","duration":"288.88072ms","start":"2026-02-11T03:16:46.963823Z","end":"2026-02-11T03:16:47.252703Z","steps":["trace[1455854186] 'read index received' (duration: 7.751657ms)","trace[1455854186] 'applied index is now lower than readState.Index' (duration: 281.127613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:16:47.252979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.152764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T03:16:47.253149Z","caller":"traceutil/trace.go:171","msg":"trace[1522538021] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:840; }","duration":"289.364719ms","start":"2026-02-11T03:16:46.963775Z","end":"2026-02-11T03:16:47.25314Z","steps":["trace[1522538021] 'agreement among raft nodes before linearized reading' (duration: 289.119624ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:47.296031Z","caller":"traceutil/trace.go:171","msg":"trace[779107106] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"238.11309ms","start":"2026-02-11T03:16:47.057893Z","end":"2026-02-11T03:16:47.296006Z","steps":["trace[779107106] 'process raft request' (duration: 237.957998ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:53.106838Z","caller":"traceutil/trace.go:171","msg":"trace[1878667647] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"107.85717ms","start":"2026-02-11T03:16:52.998946Z","end":"2026-02-11T03:16:53.106804Z","steps":["trace[1878667647] 'process raft request' (duration: 33.761252ms)","trace[1878667647] 'compare' (duration: 73.970406ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:16:54.065068Z","caller":"traceutil/trace.go:171","msg":"trace[546138853] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"160.634911ms","start":"2026-02-11T03:16:53.904409Z","end":"2026-02-11T03:16:54.065044Z","steps":["trace[546138853] 'process raft request' (duration: 160.487548ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:16:55.587431Z","caller":"traceutil/trace.go:171","msg":"trace[191511374] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"152.713504ms","start":"2026-02-11T03:16:55.434392Z","end":"2026-02-11T03:16:55.587105Z","steps":["trace[191511374] 'process raft request' (duration: 152.519969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:07.403437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.094488ms","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-02-11T03:17:07.403508Z","caller":"traceutil/trace.go:171","msg":"trace[1285544692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"101.21895ms","start":"2026-02-11T03:17:07.302274Z","end":"2026-02-11T03:17:07.403493Z","steps":["trace[1285544692] 'range keys from in-memory index tree' (duration: 100.959314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:07.403915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.782291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:07.403939Z","caller":"traceutil/trace.go:171","msg":"trace[669501003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"185.841412ms","start":"2026-02-11T03:17:07.218092Z","end":"2026-02-11T03:17:07.403934Z","steps":["trace[669501003] 'range keys from in-memory index tree' (duration: 185.71866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:07.619744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.372934ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T03:17:07.619849Z","caller":"traceutil/trace.go:171","msg":"trace[1383368951] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"116.452069ms","start":"2026-02-11T03:17:07.503381Z","end":"2026-02-11T03:17:07.619833Z","steps":["trace[1383368951] 'read index received' (duration: 18.571µs)","trace[1383368951] 'applied index is now lower than readState.Index' (duration: 116.431848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:07.620005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.611242ms","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-02-11T03:17:07.620045Z","caller":"traceutil/trace.go:171","msg":"trace[892609782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"116.675233ms","start":"2026-02-11T03:17:07.503343Z","end":"2026-02-11T03:17:07.620018Z","steps":["trace[892609782] 'agreement among raft nodes before linearized reading' (duration: 116.53082ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:07.884868Z","caller":"traceutil/trace.go:171","msg":"trace[840467810] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"185.632907ms","start":"2026-02-11T03:17:07.699213Z","end":"2026-02-11T03:17:07.884846Z","steps":["trace[840467810] 'process raft request' (duration: 111.594077ms)","trace[840467810] 'compare' (duration: 73.742274ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:19.068431Z","caller":"traceutil/trace.go:171","msg":"trace[851414772] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"445.615503ms","start":"2026-02-11T03:17:18.622803Z","end":"2026-02-11T03:17:19.068419Z","steps":["trace[851414772] 'process raft request' (duration: 367.201881ms)","trace[851414772] 'compare' (duration: 77.806147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:19.068545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:18.622787Z","time spent":"445.699594ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:17:26.772307Z","caller":"traceutil/trace.go:171","msg":"trace[1120022480] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"116.371416ms","start":"2026-02-11T03:17:26.6559Z","end":"2026-02-11T03:17:26.772271Z","steps":["trace[1120022480] 'process raft request' (duration: 116.151851ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:46.270863Z","caller":"traceutil/trace.go:171","msg":"trace[794474524] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"309.65583ms","start":"2026-02-11T03:17:45.961171Z","end":"2026-02-11T03:17:46.270826Z","steps":["trace[794474524] 'read index received' (duration: 309.226069ms)","trace[794474524] 'applied index is now lower than readState.Index' (duration: 428.241µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:46.271071Z","caller":"traceutil/trace.go:171","msg":"trace[1246701843] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"627.794912ms","start":"2026-02-11T03:17:45.643262Z","end":"2026-02-11T03:17:46.271057Z","steps":["trace[1246701843] 'process raft request' (duration: 627.274948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:46.27135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:45.643242Z","time spent":"627.925115ms","remote":"127.0.0.1:58310","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-02-11T03:17:46.271705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.541935ms","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-02-11T03:17:46.271741Z","caller":"traceutil/trace.go:171","msg":"trace[317649772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1032; }","duration":"310.623637ms","start":"2026-02-11T03:17:45.961107Z","end":"2026-02-11T03:17:46.271731Z","steps":["trace[317649772] 'agreement among raft nodes before linearized reading' (duration: 310.477413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:46.271831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:45.96108Z","time spent":"310.70775ms","remote":"127.0.0.1:58310","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-02-11T03:17:46.272327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.864929ms","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-02-11T03:17:46.272358Z","caller":"traceutil/trace.go:171","msg":"trace[1905660560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"256.9251ms","start":"2026-02-11T03:17:46.015425Z","end":"2026-02-11T03:17:46.27235Z","steps":["trace[1905660560] 'agreement among raft nodes before linearized reading' (duration: 256.845927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:48.521515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.560809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:17:48.521774Z","caller":"traceutil/trace.go:171","msg":"trace[429073977] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"188.229268ms","start":"2026-02-11T03:17:48.333521Z","end":"2026-02-11T03:17:48.52175Z","steps":["trace[429073977] 'process raft request' (duration: 188.143586ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:48.521808Z","caller":"traceutil/trace.go:171","msg":"trace[1547839229] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"190.226204ms","start":"2026-02-11T03:17:48.331547Z","end":"2026-02-11T03:17:48.521773Z","steps":["trace[1547839229] 'process raft request' (duration: 63.264114ms)","trace[1547839229] 'compare' (duration: 126.313732ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:51.092228Z","caller":"traceutil/trace.go:171","msg":"trace[1169146409] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"100.680999ms","start":"2026-02-11T03:17:50.991521Z","end":"2026-02-11T03:17:51.092202Z","steps":["trace[1169146409] 'process raft request' (duration: 100.500854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:54.6764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.896448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:54.676486Z","caller":"traceutil/trace.go:171","msg":"trace[241222865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"453.063854ms","start":"2026-02-11T03:17:54.223403Z","end":"2026-02-11T03:17:54.676466Z","steps":["trace[241222865] 'range keys from in-memory index tree' (duration: 452.761015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:54.677037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:54.223357Z","time spent":"453.152797ms","remote":"127.0.0.1:58044","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-02-11T03:17:54.678805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.967212ms","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-02-11T03:17:54.679225Z","caller":"traceutil/trace.go:171","msg":"trace[1693339634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"277.441855ms","start":"2026-02-11T03:17:54.40176Z","end":"2026-02-11T03:17:54.679202Z","steps":["trace[1693339634] 'range keys from in-memory index tree' (duration: 276.820338ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:17:55.008589Z","caller":"traceutil/trace.go:171","msg":"trace[70232080] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"323.770858ms","start":"2026-02-11T03:17:54.684794Z","end":"2026-02-11T03:17:55.008565Z","steps":["trace[70232080] 'process raft request' (duration: 323.652105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:55.008972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:54.684774Z","time spent":"324.094427ms","remote":"127.0.0.1:58310","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-02-11T03:17:55.740342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.923943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:17:55.740473Z","caller":"traceutil/trace.go:171","msg":"trace[1478920114] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"589.315368ms","start":"2026-02-11T03:17:55.151148Z","end":"2026-02-11T03:17:55.740464Z","steps":["trace[1478920114] 'read index received' (duration: 225.161548ms)","trace[1478920114] 'applied index is now lower than readState.Index' (duration: 364.15329ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:55.740599Z","caller":"traceutil/trace.go:171","msg":"trace[1455851755] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.052674715s","start":"2026-02-11T03:17:54.687918Z","end":"2026-02-11T03:17:55.740592Z","steps":["trace[1455851755] 'process raft request' (duration: 688.445843ms)","trace[1455851755] 'compare' (duration: 363.735488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:55.740631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:54.687902Z","time spent":"1.052711706s","remote":"127.0.0.1:58310","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-02-11T03:17:55.740724Z","caller":"traceutil/trace.go:171","msg":"trace[1085653648] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"1.052768817s","start":"2026-02-11T03:17:54.687948Z","end":"2026-02-11T03:17:55.740717Z","steps":["trace[1085653648] 'process raft request' (duration: 1.052462098s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:55.740747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:54.687933Z","time spent":"1.052800919s","remote":"127.0.0.1:58310","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-02-11T03:17:55.740848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.70943ms","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-02-11T03:17:55.740861Z","caller":"traceutil/trace.go:171","msg":"trace[1084459391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"589.763941ms","start":"2026-02-11T03:17:55.151093Z","end":"2026-02-11T03:17:55.740857Z","steps":["trace[1084459391] 'agreement among raft nodes before linearized reading' (duration: 589.70543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:55.740876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:55.151069Z","time spent":"589.802863ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:17:55.740952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.493918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:55.740968Z","caller":"traceutil/trace.go:171","msg":"trace[274104790] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1063; }","duration":"550.511468ms","start":"2026-02-11T03:17:55.190451Z","end":"2026-02-11T03:17:55.740962Z","steps":["trace[274104790] 'agreement among raft nodes before linearized reading' (duration: 550.485487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:55.740984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:55.190389Z","time spent":"550.588421ms","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:17:55.741231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.778994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T03:17:55.741248Z","caller":"traceutil/trace.go:171","msg":"trace[1566336313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"122.822874ms","start":"2026-02-11T03:17:55.61842Z","end":"2026-02-11T03:17:55.741243Z","steps":["trace[1566336313] 'agreement among raft nodes before linearized reading' (duration: 122.773163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:56.654824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.595765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:17:56.655195Z","caller":"traceutil/trace.go:171","msg":"trace[1268135963] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"909.668733ms","start":"2026-02-11T03:17:55.745507Z","end":"2026-02-11T03:17:56.655175Z","steps":["trace[1268135963] 'process raft request' (duration: 546.624935ms)","trace[1268135963] 'compare' (duration: 362.40313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:56.655281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:55.745488Z","time spent":"909.771116ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:17:57.303911Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011857853s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T03:17:57.446039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.818695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:17:57.446207Z","caller":"traceutil/trace.go:171","msg":"trace[1028050121] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"561.704088ms","start":"2026-02-11T03:17:56.884486Z","end":"2026-02-11T03:17:57.44619Z","steps":["trace[1028050121] 'process raft request' (duration: 561.641336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:57.446274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:56.884461Z","time spent":"561.77752ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:17:57.446885Z","caller":"traceutil/trace.go:171","msg":"trace[1350338090] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1110; }","duration":"1.562751854s","start":"2026-02-11T03:17:55.884102Z","end":"2026-02-11T03:17:57.446854Z","steps":["trace[1350338090] 'read index received' (duration: 408.038827ms)","trace[1350338090] 'applied index is now lower than readState.Index' (duration: 1.154709607s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:57.447084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562961721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:57.447115Z","caller":"traceutil/trace.go:171","msg":"trace[88781371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"1.563035692s","start":"2026-02-11T03:17:55.88407Z","end":"2026-02-11T03:17:57.447106Z","steps":["trace[88781371] 'agreement among raft nodes before linearized reading' (duration: 1.562952149s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:57.447156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:55.884056Z","time spent":"1.563090474s","remote":"127.0.0.1:58044","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-02-11T03:17:57.447411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276474345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:57.447465Z","caller":"traceutil/trace.go:171","msg":"trace[355365536] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1066; }","duration":"1.276531026s","start":"2026-02-11T03:17:56.170924Z","end":"2026-02-11T03:17:57.447455Z","steps":["trace[355365536] 'agreement among raft nodes before linearized reading' (duration: 1.276460504s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:57.447493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:56.170868Z","time spent":"1.276618119s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:17:57.447823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.246365ms","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-02-11T03:17:57.44786Z","caller":"traceutil/trace.go:171","msg":"trace[304681590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"435.283547ms","start":"2026-02-11T03:17:57.012567Z","end":"2026-02-11T03:17:57.447851Z","steps":["trace[304681590] 'agreement among raft nodes before linearized reading' (duration: 435.143582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:57.447887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:57.012511Z","time spent":"435.369989ms","remote":"127.0.0.1:58310","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-02-11T03:17:57.448049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.950924ms","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-02-11T03:17:57.448091Z","caller":"traceutil/trace.go:171","msg":"trace[1739732001] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1066; }","duration":"797.033546ms","start":"2026-02-11T03:17:56.651048Z","end":"2026-02-11T03:17:57.448082Z","steps":["trace[1739732001] 'agreement among raft nodes before linearized reading' (duration: 796.965974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:57.448118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:56.651004Z","time spent":"797.107459ms","remote":"127.0.0.1:58318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-11T03:17:57.448257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228637019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:17:57.44829Z","caller":"traceutil/trace.go:171","msg":"trace[1796688989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"1.228704411s","start":"2026-02-11T03:17:56.219578Z","end":"2026-02-11T03:17:57.448282Z","steps":["trace[1796688989] 'agreement among raft nodes before linearized reading' (duration: 1.22866029s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:57.448318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:56.219558Z","time spent":"1.228752513s","remote":"127.0.0.1:58058","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-02-11T03:17:57.44657Z","caller":"traceutil/trace.go:171","msg":"trace[1705568739] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.698812209s","start":"2026-02-11T03:17:55.747744Z","end":"2026-02-11T03:17:57.446557Z","steps":["trace[1705568739] 'process raft request' (duration: 1.556387608s)","trace[1705568739] 'compare' (duration: 141.629999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:57.457268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:55.747732Z","time spent":"1.70946917s","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T03:17:57.818401Z","caller":"traceutil/trace.go:171","msg":"trace[461263777] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"357.147253ms","start":"2026-02-11T03:17:57.461222Z","end":"2026-02-11T03:17:57.81837Z","steps":["trace[461263777] 'process raft request' (duration: 301.790853ms)","trace[461263777] 'compare' (duration: 55.042501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:57.818496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:57.461192Z","time spent":"357.259217ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:17:59.025551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.738634ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T03:17:59.026718Z","caller":"traceutil/trace.go:171","msg":"trace[1735928465] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"1.281360518s","start":"2026-02-11T03:17:57.745329Z","end":"2026-02-11T03:17:59.02669Z","steps":["trace[1735928465] 'read index received' (duration: 17.686954ms)","trace[1735928465] 'applied index is now lower than readState.Index' (duration: 1.263671964s)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:17:59.027015Z","caller":"traceutil/trace.go:171","msg":"trace[1590117244] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"582.49639ms","start":"2026-02-11T03:17:58.444499Z","end":"2026-02-11T03:17:59.026996Z","steps":["trace[1590117244] 'process raft request' (duration: 581.165981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:17:59.027203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:58.444478Z","time spent":"582.572473ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T03:17:59.053092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079148154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-02-11T03:17:59.053252Z","caller":"traceutil/trace.go:171","msg":"trace[1537850431] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:1068; }","duration":"1.07935648s","start":"2026-02-11T03:17:57.973873Z","end":"2026-02-11T03:17:59.05323Z","steps":["trace[1537850431] 'agreement among raft nodes before linearized reading' (duration: 1.053261686s)","trace[1537850431] 'range keys from in-memory index tree' (duration: 25.801175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:59.053373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:57.973857Z","time spent":"1.079499433s","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":5,"response size":1803,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-02-11T03:17:59.053796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305359232s","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-02-11T03:17:59.05391Z","caller":"traceutil/trace.go:171","msg":"trace[1311956255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1068; }","duration":"1.305512536s","start":"2026-02-11T03:17:57.748379Z","end":"2026-02-11T03:17:59.053892Z","steps":["trace[1311956255] 'agreement among raft nodes before linearized reading' (duration: 1.278596267s)","trace[1311956255] 'range keys from in-memory index tree' (duration: 26.699943ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:59.054006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:57.748358Z","time spent":"1.30563501s","remote":"127.0.0.1:58310","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-02-11T03:17:59.054437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30911239s","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-02-11T03:17:59.054533Z","caller":"traceutil/trace.go:171","msg":"trace[784274761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"1.309243534s","start":"2026-02-11T03:17:57.745275Z","end":"2026-02-11T03:17:59.054519Z","steps":["trace[784274761] 'agreement among raft nodes before linearized reading' (duration: 1.281592254s)","trace[784274761] 'range keys from in-memory index tree' (duration: 27.470235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:59.054684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:57.745255Z","time spent":"1.309409929s","remote":"127.0.0.1:58310","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-02-11T03:17:59.05511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.026753ms","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-02-11T03:17:59.055207Z","caller":"traceutil/trace.go:171","msg":"trace[2078891872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1068; }","duration":"396.185147ms","start":"2026-02-11T03:17:58.659007Z","end":"2026-02-11T03:17:59.055192Z","steps":["trace[2078891872] 'agreement among raft nodes before linearized reading' (duration: 368.115665ms)","trace[2078891872] 'range keys from in-memory index tree' (duration: 27.902567ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:17:59.055301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:17:58.658988Z","time spent":"396.299951ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T03:18:25.563424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.71704ms","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-02-11T03:18:25.564479Z","caller":"traceutil/trace.go:171","msg":"trace[1580049017] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"152.417881ms","start":"2026-02-11T03:18:25.410691Z","end":"2026-02-11T03:18:25.563109Z","steps":["trace[1580049017] 'read index received' (duration: 85.112821ms)","trace[1580049017] 'applied index is now lower than readState.Index' (duration: 67.30407ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:18:25.564546Z","caller":"traceutil/trace.go:171","msg":"trace[17217360] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"157.327985ms","start":"2026-02-11T03:18:25.405927Z","end":"2026-02-11T03:18:25.563255Z","steps":["trace[17217360] 'process raft request' (duration: 89.827159ms)","trace[17217360] 'compare' (duration: 67.068983ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:18:25.564689Z","caller":"traceutil/trace.go:171","msg":"trace[1247390570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1147; }","duration":"152.796672ms","start":"2026-02-11T03:18:25.41067Z","end":"2026-02-11T03:18:25.563467Z","steps":["trace[1247390570] 'agreement among raft nodes before linearized reading' (duration: 152.662048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:25.564755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.387869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/kubeadm-certs\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-02-11T03:18:25.571541Z","caller":"traceutil/trace.go:171","msg":"trace[390842629] range","detail":"{range_begin:/registry/secrets/kube-system/kubeadm-certs; range_end:; response_count:1; response_revision:1147; }","duration":"158.171703ms","start":"2026-02-11T03:18:25.413332Z","end":"2026-02-11T03:18:25.571503Z","steps":["trace[390842629] 'agreement among raft nodes before linearized reading' (duration: 151.329318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:26.152499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.916576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:18:26.152856Z","caller":"traceutil/trace.go:171","msg":"trace[1879546475] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"543.794768ms","start":"2026-02-11T03:18:25.608777Z","end":"2026-02-11T03:18:26.152572Z","steps":["trace[1879546475] 'read index received' (duration: 429.627245ms)","trace[1879546475] 'applied index is now lower than readState.Index' (duration: 114.166413ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:18:26.153074Z","caller":"traceutil/trace.go:171","msg":"trace[542226598] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"582.529758ms","start":"2026-02-11T03:18:25.57052Z","end":"2026-02-11T03:18:26.15305Z","steps":["trace[542226598] 'process raft request' (duration: 467.988702ms)","trace[542226598] 'compare' (duration: 113.589706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:18:26.153467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.668886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317274"} {"level":"info","ts":"2026-02-11T03:18:26.153644Z","caller":"traceutil/trace.go:171","msg":"trace[1418006797] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:1148; }","duration":"544.76552ms","start":"2026-02-11T03:18:25.608743Z","end":"2026-02-11T03:18:26.153508Z","steps":["trace[1418006797] 'agreement among raft nodes before linearized reading' (duration: 544.344236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:26.153662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.755296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-02-11T03:18:26.153761Z","caller":"traceutil/trace.go:171","msg":"trace[1900892006] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1148; }","duration":"475.840239ms","start":"2026-02-11T03:18:25.677897Z","end":"2026-02-11T03:18:26.153737Z","steps":["trace[1900892006] 'agreement among raft nodes before linearized reading' (duration: 475.689634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:26.153836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:18:25.677832Z","time spent":"475.995984ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":235,"request content":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" "} {"level":"warn","ts":"2026-02-11T03:18:26.154183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:18:25.608728Z","time spent":"544.958166ms","remote":"127.0.0.1:58142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":317297,"request content":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" "} {"level":"warn","ts":"2026-02-11T03:18:26.154235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.582738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:18:26.154508Z","caller":"traceutil/trace.go:171","msg":"trace[1464782991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"267.906178ms","start":"2026-02-11T03:18:25.886583Z","end":"2026-02-11T03:18:26.15449Z","steps":["trace[1464782991] 'agreement among raft nodes before linearized reading' (duration: 267.63044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:26.154726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.907564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T03:18:26.154861Z","caller":"traceutil/trace.go:171","msg":"trace[1354631036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1148; }","duration":"445.069079ms","start":"2026-02-11T03:18:25.709767Z","end":"2026-02-11T03:18:26.154836Z","steps":["trace[1354631036] 'agreement among raft nodes before linearized reading' (duration: 444.906894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:26.154924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:18:25.709743Z","time spent":"445.173092ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T03:18:26.153477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:18:25.570348Z","time spent":"583.071576ms","remote":"127.0.0.1:58310","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-02-11T03:18:26.381284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.009064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T03:18:26.381377Z","caller":"traceutil/trace.go:171","msg":"trace[87311263] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1201; }","duration":"163.014896ms","start":"2026-02-11T03:18:26.218346Z","end":"2026-02-11T03:18:26.381361Z","steps":["trace[87311263] 'read index received' (duration: 7.345261ms)","trace[87311263] 'applied index is now lower than readState.Index' (duration: 155.668455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:18:26.381775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.395787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T03:18:26.381842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.868378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-02-11T03:18:26.381973Z","caller":"traceutil/trace.go:171","msg":"trace[809916235] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1150; }","duration":"148.014412ms","start":"2026-02-11T03:18:26.233941Z","end":"2026-02-11T03:18:26.381956Z","steps":["trace[809916235] 'agreement among raft nodes before linearized reading' (duration: 147.853877ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:18:26.382054Z","caller":"traceutil/trace.go:171","msg":"trace[691594468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1150; }","duration":"163.571313ms","start":"2026-02-11T03:18:26.218316Z","end":"2026-02-11T03:18:26.381887Z","steps":["trace[691594468] 'agreement among raft nodes before linearized reading' (duration: 163.340476ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:18:26.382924Z","caller":"traceutil/trace.go:171","msg":"trace[579353317] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"213.225572ms","start":"2026-02-11T03:18:26.16968Z","end":"2026-02-11T03:18:26.382906Z","steps":["trace[579353317] 'process raft request' (duration: 87.524934ms)","trace[579353317] 'compare' (duration: 123.790297ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:18:27.253131Z","caller":"traceutil/trace.go:171","msg":"trace[1388347919] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"119.302374ms","start":"2026-02-11T03:18:27.133807Z","end":"2026-02-11T03:18:27.253109Z","steps":["trace[1388347919] 'read index received' (duration: 51.016374ms)","trace[1388347919] 'applied index is now lower than readState.Index' (duration: 68.28446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:18:27.253362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.540641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-11T03:18:27.253454Z","caller":"traceutil/trace.go:171","msg":"trace[387346408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:1150; }","duration":"119.666985ms","start":"2026-02-11T03:18:27.133775Z","end":"2026-02-11T03:18:27.253442Z","steps":["trace[387346408] 'agreement among raft nodes before linearized reading' (duration: 119.49402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:29.61803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.866579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-11T03:18:29.620252Z","caller":"traceutil/trace.go:171","msg":"trace[1950811164] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:1157; }","duration":"123.117641ms","start":"2026-02-11T03:18:29.497063Z","end":"2026-02-11T03:18:29.620181Z","steps":["trace[1950811164] 'range keys from in-memory index tree' (duration: 120.779187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:29.618939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.76176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-11T03:18:29.620614Z","caller":"traceutil/trace.go:171","msg":"trace[38409154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1157; }","duration":"107.423852ms","start":"2026-02-11T03:18:29.513135Z","end":"2026-02-11T03:18:29.620558Z","steps":["trace[38409154] 'range keys from in-memory index tree' (duration: 105.696677ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:18:34.529638Z","caller":"traceutil/trace.go:171","msg":"trace[1519201363] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"178.280049ms","start":"2026-02-11T03:18:34.35133Z","end":"2026-02-11T03:18:34.52961Z","steps":["trace[1519201363] 'read index received' (duration: 135.971602ms)","trace[1519201363] 'applied index is now lower than readState.Index' (duration: 42.307117ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:18:34.529974Z","caller":"traceutil/trace.go:171","msg":"trace[1132415936] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"198.822287ms","start":"2026-02-11T03:18:34.331131Z","end":"2026-02-11T03:18:34.529954Z","steps":["trace[1132415936] 'process raft request' (duration: 156.223151ms)","trace[1132415936] 'compare' (duration: 41.810411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T03:18:34.530254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.909089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T03:18:34.530299Z","caller":"traceutil/trace.go:171","msg":"trace[1875843875] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1172; }","duration":"178.987502ms","start":"2026-02-11T03:18:34.3513Z","end":"2026-02-11T03:18:34.530288Z","steps":["trace[1875843875] 'agreement among raft nodes before linearized reading' (duration: 178.899679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:34.53032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.820752ms","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-02-11T03:18:34.530394Z","caller":"traceutil/trace.go:171","msg":"trace[222654255] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1172; }","duration":"157.927665ms","start":"2026-02-11T03:18:34.372448Z","end":"2026-02-11T03:18:34.530376Z","steps":["trace[222654255] 'agreement among raft nodes before linearized reading' (duration: 157.786391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:34.843579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.691066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:64181"} {"level":"info","ts":"2026-02-11T03:18:34.844043Z","caller":"traceutil/trace.go:171","msg":"trace[2107336966] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:1173; }","duration":"171.179091ms","start":"2026-02-11T03:18:34.672841Z","end":"2026-02-11T03:18:34.84402Z","steps":["trace[2107336966] 'range keys from in-memory index tree' (duration: 170.418797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:35.673301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.498596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rswb8\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-11T03:18:35.673387Z","caller":"traceutil/trace.go:171","msg":"trace[1392348883] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rswb8; range_end:; response_count:1; response_revision:1174; }","duration":"118.629771ms","start":"2026-02-11T03:18:35.554738Z","end":"2026-02-11T03:18:35.673368Z","steps":["trace[1392348883] 'range keys from in-memory index tree' (duration: 118.27793ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T03:18:37.045137Z","caller":"traceutil/trace.go:171","msg":"trace[978960973] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"359.665881ms","start":"2026-02-11T03:18:36.685448Z","end":"2026-02-11T03:18:37.045114Z","steps":["trace[978960973] 'read index received' (duration: 359.465755ms)","trace[978960973] 'applied index is now lower than readState.Index' (duration: 199.156µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T03:18:37.045656Z","caller":"traceutil/trace.go:171","msg":"trace[862285308] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"434.279603ms","start":"2026-02-11T03:18:36.611352Z","end":"2026-02-11T03:18:37.045632Z","steps":["trace[862285308] 'process raft request' (duration: 433.627092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:37.045862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.337304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rswb8\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-11T03:18:37.046019Z","caller":"traceutil/trace.go:171","msg":"trace[229812558] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rswb8; range_end:; response_count:1; response_revision:1178; }","duration":"360.561471ms","start":"2026-02-11T03:18:36.685415Z","end":"2026-02-11T03:18:37.045976Z","steps":["trace[229812558] 'agreement among raft nodes before linearized reading' (duration: 360.21209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T03:18:37.046075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:18:36.685398Z","time spent":"360.664684ms","remote":"127.0.0.1:58220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18081,"request content":"key:\"/registry/pods/kube-system/cilium-rswb8\" "} {"level":"warn","ts":"2026-02-11T03:18:37.047269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T03:18:36.611327Z","time spent":"434.630944ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}