{"level":"warn","ts":"2026-02-22T17:50:54.849914Z","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-22T17:50:54.850045Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-22T17:50:54.850132Z","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-22T17:50:54.850151Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-22T17:50:54.850186Z","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-22T17:50:54.851048Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-22T17:50:54.851152Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-22T17:50:54.855865Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.463542ms"} {"level":"info","ts":"2026-02-22T17:50:54.861792Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-22T17:50:54.861858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T17:50:54.861879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-22T17:50:54.861898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T17:50:54.861908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-22T17:50:54.861968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-22T17:50:54.867675Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T17:50:54.869105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T17:50:54.872052Z","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-22T17:50:54.877488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T17:50:54.877755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T17:50:54.878108Z","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-22T17:50:54.878184Z","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-22T17:50:54.8782Z","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-22T17:50:54.878738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-22T17:50:54.87889Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-22T17:50:54.883971Z","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-22T17:50:54.884339Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T17:50:54.884397Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T17:50:54.884512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-22T17:50:54.884533Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-22T17:50:55.563034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T17:50:55.563097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T17:50:55.563139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-22T17:50:55.563159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-22T17:50:55.563176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-22T17:50:55.563193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-22T17:50:55.563207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-22T17:50:55.583313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:55.613029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:50:55.61323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:50:55.613035Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T17:50:55.613423Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T17:50:55.613459Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-22T17:50:55.616083Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-22T17:50:55.617166Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T17:50:55.624351Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:55.62451Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:55.624564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:51:15.423575Z","caller":"traceutil/trace.go:171","msg":"trace[888626601] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"168.211056ms","start":"2026-02-22T17:51:15.255332Z","end":"2026-02-22T17:51:15.423543Z","steps":["trace[888626601] 'process raft request' (duration: 168.076183ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:20.840365Z","caller":"traceutil/trace.go:171","msg":"trace[410949827] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"386.89987ms","start":"2026-02-22T17:51:20.45344Z","end":"2026-02-22T17:51:20.84034Z","steps":["trace[410949827] 'process raft request' (duration: 386.670304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:20.841157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:20.453422Z","time spent":"387.034943ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:21.304423Z","caller":"traceutil/trace.go:171","msg":"trace[898948441] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"571.829275ms","start":"2026-02-22T17:51:20.732556Z","end":"2026-02-22T17:51:21.304385Z","steps":["trace[898948441] 'read index received' (duration: 107.620559ms)","trace[898948441] 'applied index is now lower than readState.Index' (duration: 464.206886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:21.304648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.071811ms","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-22T17:51:21.304738Z","caller":"traceutil/trace.go:171","msg":"trace[2104329548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"572.235824ms","start":"2026-02-22T17:51:20.732486Z","end":"2026-02-22T17:51:21.304722Z","steps":["trace[2104329548] 'agreement among raft nodes before linearized reading' (duration: 572.028679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:21.304796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:20.732453Z","time spent":"572.331367ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T17:51:21.305018Z","caller":"traceutil/trace.go:171","msg":"trace[1247132013] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"689.44515ms","start":"2026-02-22T17:51:20.615536Z","end":"2026-02-22T17:51:21.304982Z","steps":["trace[1247132013] 'process raft request' (duration: 589.851198ms)","trace[1247132013] 'compare' (duration: 98.743231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:21.305217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:20.615507Z","time spent":"689.593984ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:22.287207Z","caller":"traceutil/trace.go:171","msg":"trace[1042130167] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"336.906322ms","start":"2026-02-22T17:51:21.950277Z","end":"2026-02-22T17:51:22.287184Z","steps":["trace[1042130167] 'process raft request' (duration: 336.345009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:22.287343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:21.95025Z","time spent":"337.023436ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:23.531339Z","caller":"traceutil/trace.go:171","msg":"trace[1583128057] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"133.891564ms","start":"2026-02-22T17:51:23.397405Z","end":"2026-02-22T17:51:23.531297Z","steps":["trace[1583128057] 'process raft request' (duration: 93.121974ms)","trace[1583128057] 'compare' (duration: 40.489114ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:23.818164Z","caller":"traceutil/trace.go:171","msg":"trace[158609437] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"186.015131ms","start":"2026-02-22T17:51:23.632115Z","end":"2026-02-22T17:51:23.81813Z","steps":["trace[158609437] 'process raft request' (duration: 142.580867ms)","trace[158609437] 'compare' (duration: 43.285351ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:23.818635Z","caller":"traceutil/trace.go:171","msg":"trace[684280435] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"184.472184ms","start":"2026-02-22T17:51:23.63415Z","end":"2026-02-22T17:51:23.818622Z","steps":["trace[684280435] 'process raft request' (duration: 184.28104ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:23.818985Z","caller":"traceutil/trace.go:171","msg":"trace[670686987] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"170.289387ms","start":"2026-02-22T17:51:23.648676Z","end":"2026-02-22T17:51:23.818965Z","steps":["trace[670686987] 'process raft request' (duration: 169.988669ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:23.819159Z","caller":"traceutil/trace.go:171","msg":"trace[702035820] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"182.381003ms","start":"2026-02-22T17:51:23.636763Z","end":"2026-02-22T17:51:23.819144Z","steps":["trace[702035820] 'process raft request' (duration: 181.774038ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:23.819369Z","caller":"traceutil/trace.go:171","msg":"trace[2095683997] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"172.302517ms","start":"2026-02-22T17:51:23.646999Z","end":"2026-02-22T17:51:23.819301Z","steps":["trace[2095683997] 'process raft request' (duration: 171.59179ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:25.687088Z","caller":"traceutil/trace.go:171","msg":"trace[802132378] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"123.223553ms","start":"2026-02-22T17:51:25.563839Z","end":"2026-02-22T17:51:25.687063Z","steps":["trace[802132378] 'read index received' (duration: 123.067039ms)","trace[802132378] 'applied index is now lower than readState.Index' (duration: 155.614µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:25.687202Z","caller":"traceutil/trace.go:171","msg":"trace[997863141] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"359.736085ms","start":"2026-02-22T17:51:25.327451Z","end":"2026-02-22T17:51:25.687187Z","steps":["trace[997863141] 'process raft request' (duration: 359.475218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:25.687293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:25.32744Z","time spent":"359.787316ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:25.687494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.655694ms","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-22T17:51:25.687539Z","caller":"traceutil/trace.go:171","msg":"trace[762780782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"123.735956ms","start":"2026-02-22T17:51:25.563789Z","end":"2026-02-22T17:51:25.687525Z","steps":["trace[762780782] 'agreement among raft nodes before linearized reading' (duration: 123.624663ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:26.451202Z","caller":"traceutil/trace.go:171","msg":"trace[1545741205] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"145.238872ms","start":"2026-02-22T17:51:26.305943Z","end":"2026-02-22T17:51:26.451182Z","steps":["trace[1545741205] 'process raft request' (duration: 145.093438ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:33.739929Z","caller":"traceutil/trace.go:171","msg":"trace[1393260126] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"422.488761ms","start":"2026-02-22T17:51:33.317414Z","end":"2026-02-22T17:51:33.739903Z","steps":["trace[1393260126] 'process raft request' (duration: 422.324797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:33.7401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:33.317395Z","time spent":"422.626454ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:34.204928Z","caller":"traceutil/trace.go:171","msg":"trace[637036605] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"459.478119ms","start":"2026-02-22T17:51:33.745413Z","end":"2026-02-22T17:51:34.204891Z","steps":["trace[637036605] 'process raft request' (duration: 380.969134ms)","trace[637036605] 'compare' (duration: 78.125065ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:34.205011Z","caller":"traceutil/trace.go:171","msg":"trace[292040971] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"455.259765ms","start":"2026-02-22T17:51:33.749632Z","end":"2026-02-22T17:51:34.204892Z","steps":["trace[292040971] 'read index received' (duration: 376.843642ms)","trace[292040971] 'applied index is now lower than readState.Index' (duration: 78.412323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:34.20512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:33.745393Z","time spent":"459.610772ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:34.370018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.369504ms","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-22T17:51:34.370112Z","caller":"traceutil/trace.go:171","msg":"trace[1897419836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"620.476167ms","start":"2026-02-22T17:51:33.749602Z","end":"2026-02-22T17:51:34.370078Z","steps":["trace[1897419836] 'agreement among raft nodes before linearized reading' (duration: 455.294196ms)","trace[1897419836] 'range keys from in-memory index tree' (duration: 164.642518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:34.370147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:33.749587Z","time spent":"620.551789ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:51:34.372977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.968236ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-22T17:51:35.249609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.32785ms","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-22T17:51:35.249685Z","caller":"traceutil/trace.go:171","msg":"trace[407900419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"763.420883ms","start":"2026-02-22T17:51:34.486248Z","end":"2026-02-22T17:51:35.249669Z","steps":["trace[407900419] 'range keys from in-memory index tree' (duration: 763.108445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:35.249717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:34.486187Z","time spent":"763.523915ms","remote":"127.0.0.1:59724","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-22T17:51:35.249524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.83876ms","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-22T17:51:35.249829Z","caller":"traceutil/trace.go:171","msg":"trace[755568948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"346.180009ms","start":"2026-02-22T17:51:34.903611Z","end":"2026-02-22T17:51:35.249791Z","steps":["trace[755568948] 'range keys from in-memory index tree' (duration: 345.669707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:35.249885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:34.903588Z","time spent":"346.273851ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:51:35.446508Z","caller":"traceutil/trace.go:171","msg":"trace[1601269909] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"192.753987ms","start":"2026-02-22T17:51:35.253737Z","end":"2026-02-22T17:51:35.446491Z","steps":["trace[1601269909] 'process raft request' (duration: 192.663455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:35.83249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.055705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:51:35.8326Z","caller":"traceutil/trace.go:171","msg":"trace[1768077710] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"574.13527ms","start":"2026-02-22T17:51:35.258444Z","end":"2026-02-22T17:51:35.83258Z","steps":["trace[1768077710] 'read index received' (duration: 188.007281ms)","trace[1768077710] 'applied index is now lower than readState.Index' (duration: 386.126609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:35.832687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.238923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:51:35.832723Z","caller":"traceutil/trace.go:171","msg":"trace[117971354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"574.299494ms","start":"2026-02-22T17:51:35.258413Z","end":"2026-02-22T17:51:35.832712Z","steps":["trace[117971354] 'agreement among raft nodes before linearized reading' (duration: 574.223073ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:35.832723Z","caller":"traceutil/trace.go:171","msg":"trace[595080293] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"578.730333ms","start":"2026-02-22T17:51:35.253955Z","end":"2026-02-22T17:51:35.832686Z","steps":["trace[595080293] 'process raft request' (duration: 210.409911ms)","trace[595080293] 'compare' (duration: 367.933732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:35.832757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:35.258401Z","time spent":"574.348495ms","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:51:35.832899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:35.253939Z","time spent":"578.874976ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:36.615681Z","caller":"traceutil/trace.go:171","msg":"trace[1823731807] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"173.604538ms","start":"2026-02-22T17:51:36.442044Z","end":"2026-02-22T17:51:36.615648Z","steps":["trace[1823731807] 'process raft request' (duration: 83.613551ms)","trace[1823731807] 'compare' (duration: 89.852634ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:37.793215Z","caller":"traceutil/trace.go:171","msg":"trace[481848036] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"296.060711ms","start":"2026-02-22T17:51:37.49713Z","end":"2026-02-22T17:51:37.79319Z","steps":["trace[481848036] 'process raft request' (duration: 294.290898ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:47.958567Z","caller":"traceutil/trace.go:171","msg":"trace[1064997115] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"104.819511ms","start":"2026-02-22T17:51:47.853709Z","end":"2026-02-22T17:51:47.958528Z","steps":["trace[1064997115] 'process raft request' (duration: 104.612446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:48.361633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.244942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:51:48.361928Z","caller":"traceutil/trace.go:171","msg":"trace[210359422] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"101.139301ms","start":"2026-02-22T17:51:48.260744Z","end":"2026-02-22T17:51:48.361883Z","steps":["trace[210359422] 'read index received' (duration: 100.484915ms)","trace[210359422] 'applied index is now lower than readState.Index' (duration: 653.316µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:48.362128Z","caller":"traceutil/trace.go:171","msg":"trace[2017113542] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"400.695867ms","start":"2026-02-22T17:51:47.961372Z","end":"2026-02-22T17:51:48.362068Z","steps":["trace[2017113542] 'process raft request' (duration: 82.865721ms)","trace[2017113542] 'compare' (duration: 317.107849ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:48.362226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:47.961358Z","time spent":"400.828401ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:48.362424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.646303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:51:48.362525Z","caller":"traceutil/trace.go:171","msg":"trace[1647786453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"101.797457ms","start":"2026-02-22T17:51:48.260703Z","end":"2026-02-22T17:51:48.3625Z","steps":["trace[1647786453] 'agreement among raft nodes before linearized reading' (duration: 101.546161ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:48.362547Z","caller":"traceutil/trace.go:171","msg":"trace[130948163] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"144.848493ms","start":"2026-02-22T17:51:48.21767Z","end":"2026-02-22T17:51:48.362518Z","steps":["trace[130948163] 'process raft request' (duration: 144.125096ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:53.654097Z","caller":"traceutil/trace.go:171","msg":"trace[684877749] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"109.871345ms","start":"2026-02-22T17:51:53.544192Z","end":"2026-02-22T17:51:53.654063Z","steps":["trace[684877749] 'process raft request' (duration: 109.582018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:54.487241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720195634101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:51:55.576341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.938581ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-22T17:51:55.576418Z","caller":"traceutil/trace.go:171","msg":"trace[937526781] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"1.589852014s","start":"2026-02-22T17:51:53.986552Z","end":"2026-02-22T17:51:55.576404Z","steps":["trace[937526781] 'read index received' (duration: 800.868502ms)","trace[937526781] 'applied index is now lower than readState.Index' (duration: 788.982422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:55.576757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590206622s","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-22T17:51:55.576792Z","caller":"traceutil/trace.go:171","msg":"trace[1712225783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"1.590244533s","start":"2026-02-22T17:51:53.986536Z","end":"2026-02-22T17:51:55.57678Z","steps":["trace[1712225783] 'agreement among raft nodes before linearized reading' (duration: 1.59012394s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:55.576828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:53.986476Z","time spent":"1.590343896s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:51:55.576879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320018066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:51:55.57693Z","caller":"traceutil/trace.go:171","msg":"trace[1026734893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"1.320104218s","start":"2026-02-22T17:51:54.256812Z","end":"2026-02-22T17:51:55.576917Z","steps":["trace[1026734893] 'agreement among raft nodes before linearized reading' (duration: 1.320018876s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:55.576957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:54.256795Z","time spent":"1.320156769s","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:51:55.577115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.297805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-22T17:51:55.577109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189097274s","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-22T17:51:55.577158Z","caller":"traceutil/trace.go:171","msg":"trace[788210976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"828.353326ms","start":"2026-02-22T17:51:54.748782Z","end":"2026-02-22T17:51:55.577135Z","steps":["trace[788210976] 'agreement among raft nodes before linearized reading' (duration: 828.279925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:55.577188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:54.748765Z","time spent":"828.418108ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T17:51:55.577201Z","caller":"traceutil/trace.go:171","msg":"trace[578050466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"1.189213537s","start":"2026-02-22T17:51:54.387965Z","end":"2026-02-22T17:51:55.577178Z","steps":["trace[578050466] 'agreement among raft nodes before linearized reading' (duration: 1.188969262s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:55.577146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192932059s","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-22T17:51:55.577247Z","caller":"traceutil/trace.go:171","msg":"trace[1618046161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"1.193069972s","start":"2026-02-22T17:51:54.384168Z","end":"2026-02-22T17:51:55.577238Z","steps":["trace[1618046161] 'agreement among raft nodes before linearized reading' (duration: 1.192905198s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:55.57725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:54.387949Z","time spent":"1.189291939s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:51:55.577274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:54.384124Z","time spent":"1.193141664s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:51:55.701834Z","caller":"traceutil/trace.go:171","msg":"trace[295002334] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"119.730227ms","start":"2026-02-22T17:51:55.582082Z","end":"2026-02-22T17:51:55.701812Z","steps":["trace[295002334] 'process raft request' (duration: 119.574143ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:55.743396Z","caller":"traceutil/trace.go:171","msg":"trace[2074942907] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:701; }","duration":"156.635992ms","start":"2026-02-22T17:51:55.586734Z","end":"2026-02-22T17:51:55.74337Z","steps":["trace[2074942907] 'read index received' (duration: 115.010021ms)","trace[2074942907] 'applied index is now lower than readState.Index' (duration: 41.624901ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:55.743472Z","caller":"traceutil/trace.go:171","msg":"trace[1214855147] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"159.933212ms","start":"2026-02-22T17:51:55.583503Z","end":"2026-02-22T17:51:55.743437Z","steps":["trace[1214855147] 'process raft request' (duration: 159.786508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:55.743573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.819857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:51:55.743541Z","caller":"traceutil/trace.go:171","msg":"trace[1212858042] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"160.046386ms","start":"2026-02-22T17:51:55.583457Z","end":"2026-02-22T17:51:55.743503Z","steps":["trace[1212858042] 'process raft request' (duration: 159.686587ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:55.743606Z","caller":"traceutil/trace.go:171","msg":"trace[512929570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"156.884008ms","start":"2026-02-22T17:51:55.586712Z","end":"2026-02-22T17:51:55.743596Z","steps":["trace[512929570] 'agreement among raft nodes before linearized reading' (duration: 156.818416ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:55.743512Z","caller":"traceutil/trace.go:171","msg":"trace[1506547003] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"159.875932ms","start":"2026-02-22T17:51:55.583626Z","end":"2026-02-22T17:51:55.743502Z","steps":["trace[1506547003] 'process raft request' (duration: 159.709178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:56.066353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.166572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:51:56.06643Z","caller":"traceutil/trace.go:171","msg":"trace[683154154] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"318.579034ms","start":"2026-02-22T17:51:55.747838Z","end":"2026-02-22T17:51:56.066417Z","steps":["trace[683154154] 'process raft request' (duration: 113.29103ms)","trace[683154154] 'compare' (duration: 205.0959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:56.066471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:55.747816Z","time spent":"318.637565ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:58.286137Z","caller":"traceutil/trace.go:171","msg":"trace[909477809] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"212.997764ms","start":"2026-02-22T17:51:58.07312Z","end":"2026-02-22T17:51:58.286118Z","steps":["trace[909477809] 'process raft request' (duration: 212.85403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.133559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.699286ms","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-22T17:52:20.133664Z","caller":"traceutil/trace.go:171","msg":"trace[1786104782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"265.84544ms","start":"2026-02-22T17:52:19.867797Z","end":"2026-02-22T17:52:20.133643Z","steps":["trace[1786104782] 'range keys from in-memory index tree' (duration: 265.457011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.133742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.657738ms","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-22T17:52:20.133844Z","caller":"traceutil/trace.go:171","msg":"trace[691621740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"255.797352ms","start":"2026-02-22T17:52:19.878022Z","end":"2026-02-22T17:52:20.133819Z","steps":["trace[691621740] 'range keys from in-memory index tree' (duration: 255.439404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.133764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.212107ms","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-22T17:52:20.133958Z","caller":"traceutil/trace.go:171","msg":"trace[579790821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"305.452463ms","start":"2026-02-22T17:52:19.828489Z","end":"2026-02-22T17:52:20.133941Z","steps":["trace[579790821] 'range keys from in-memory index tree' (duration: 305.044744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.13401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:19.828466Z","time spent":"305.530395ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:52:20.134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.53582ms","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-22T17:52:20.134395Z","caller":"traceutil/trace.go:171","msg":"trace[86419520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"256.823437ms","start":"2026-02-22T17:52:19.877323Z","end":"2026-02-22T17:52:20.134147Z","steps":["trace[86419520] 'range keys from in-memory index tree' (duration: 256.325404ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:20.383279Z","caller":"traceutil/trace.go:171","msg":"trace[595227605] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"244.800561ms","start":"2026-02-22T17:52:20.138431Z","end":"2026-02-22T17:52:20.383231Z","steps":["trace[595227605] 'process raft request' (duration: 244.627287ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:20.383415Z","caller":"traceutil/trace.go:171","msg":"trace[80586168] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"244.935595ms","start":"2026-02-22T17:52:20.138452Z","end":"2026-02-22T17:52:20.383387Z","steps":["trace[80586168] 'process raft request' (duration: 244.73703ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:20.402077Z","caller":"traceutil/trace.go:171","msg":"trace[1929309563] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:781; }","duration":"164.927167ms","start":"2026-02-22T17:52:20.23713Z","end":"2026-02-22T17:52:20.402057Z","steps":["trace[1929309563] 'read index received' (duration: 145.92721ms)","trace[1929309563] 'applied index is now lower than readState.Index' (duration: 18.999257ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:20.402138Z","caller":"traceutil/trace.go:171","msg":"trace[156662102] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"263.582894ms","start":"2026-02-22T17:52:20.138534Z","end":"2026-02-22T17:52:20.402116Z","steps":["trace[156662102] 'process raft request' (duration: 263.376699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.402208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.197912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:20.402232Z","caller":"traceutil/trace.go:171","msg":"trace[1576151750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"143.279694ms","start":"2026-02-22T17:52:20.258945Z","end":"2026-02-22T17:52:20.402225Z","steps":["trace[1576151750] 'agreement among raft nodes before linearized reading' (duration: 143.222993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.402268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.107822ms","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-22T17:52:20.402321Z","caller":"traceutil/trace.go:171","msg":"trace[1199800464] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"261.758128ms","start":"2026-02-22T17:52:20.14055Z","end":"2026-02-22T17:52:20.402308Z","steps":["trace[1199800464] 'process raft request' (duration: 261.458631ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:20.402342Z","caller":"traceutil/trace.go:171","msg":"trace[2135438743] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:755; }","duration":"165.252935ms","start":"2026-02-22T17:52:20.23707Z","end":"2026-02-22T17:52:20.402323Z","steps":["trace[2135438743] 'agreement among raft nodes before linearized reading' (duration: 165.102241ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:22.594754Z","caller":"traceutil/trace.go:171","msg":"trace[126237868] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"189.354568ms","start":"2026-02-22T17:52:22.405376Z","end":"2026-02-22T17:52:22.59473Z","steps":["trace[126237868] 'read index received' (duration: 66.930707ms)","trace[126237868] 'applied index is now lower than readState.Index' (duration: 122.422681ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:22.594795Z","caller":"traceutil/trace.go:171","msg":"trace[1715909539] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"203.121487ms","start":"2026-02-22T17:52:22.391637Z","end":"2026-02-22T17:52:22.594759Z","steps":["trace[1715909539] 'process raft request' (duration: 160.133009ms)","trace[1715909539] 'compare' (duration: 42.702851ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:22.594844Z","caller":"traceutil/trace.go:171","msg":"trace[588678601] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"146.320619ms","start":"2026-02-22T17:52:22.448481Z","end":"2026-02-22T17:52:22.594802Z","steps":["trace[588678601] 'process raft request' (duration: 146.192156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:22.595029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.628545ms","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-22T17:52:22.595073Z","caller":"traceutil/trace.go:171","msg":"trace[211148562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"189.717997ms","start":"2026-02-22T17:52:22.405344Z","end":"2026-02-22T17:52:22.595062Z","steps":["trace[211148562] 'agreement among raft nodes before linearized reading' (duration: 189.522173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:22.595139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.545201ms","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-22T17:52:22.595188Z","caller":"traceutil/trace.go:171","msg":"trace[1502680905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"130.652504ms","start":"2026-02-22T17:52:22.464522Z","end":"2026-02-22T17:52:22.595174Z","steps":["trace[1502680905] 'agreement among raft nodes before linearized reading' (duration: 130.449799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:22.595143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.449825ms","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-22T17:52:22.595248Z","caller":"traceutil/trace.go:171","msg":"trace[413334731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"188.592289ms","start":"2026-02-22T17:52:22.406642Z","end":"2026-02-22T17:52:22.595234Z","steps":["trace[413334731] 'agreement among raft nodes before linearized reading' (duration: 188.324582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:41.195396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720195634584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:52:41.695982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720195634584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:52:41.88899Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.306564849s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T17:52:41.889426Z","caller":"traceutil/trace.go:171","msg":"trace[1867460968] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"1.194901502s","start":"2026-02-22T17:52:40.6945Z","end":"2026-02-22T17:52:41.889401Z","steps":["trace[1867460968] 'read index received' (duration: 1.194650756s)","trace[1867460968] 'applied index is now lower than readState.Index' (duration: 249.826µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:41.889571Z","caller":"traceutil/trace.go:171","msg":"trace[798101347] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"1.307191194s","start":"2026-02-22T17:52:40.58234Z","end":"2026-02-22T17:52:41.889531Z","steps":["trace[798101347] 'process raft request' (duration: 1.306896227s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:41.889652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195140657s","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-22T17:52:41.889708Z","caller":"traceutil/trace.go:171","msg":"trace[708619504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"1.195265251s","start":"2026-02-22T17:52:40.694428Z","end":"2026-02-22T17:52:41.889693Z","steps":["trace[708619504] 'agreement among raft nodes before linearized reading' (duration: 1.195085997s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:41.889744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120251525s","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":"warn","ts":"2026-02-22T17:52:41.889658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120337998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-22T17:52:41.88975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.257633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:52:41.889766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:40.69441Z","time spent":"1.195338582s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T17:52:41.889794Z","caller":"traceutil/trace.go:171","msg":"trace[1174254610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"1.120319697s","start":"2026-02-22T17:52:40.769459Z","end":"2026-02-22T17:52:41.889778Z","steps":["trace[1174254610] 'agreement among raft nodes before linearized reading' (duration: 1.120229655s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:41.889824Z","caller":"traceutil/trace.go:171","msg":"trace[1804263267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"630.371527ms","start":"2026-02-22T17:52:41.259432Z","end":"2026-02-22T17:52:41.889804Z","steps":["trace[1804263267] 'agreement among raft nodes before linearized reading' (duration: 630.258183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:41.889828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:40.769446Z","time spent":"1.120375119s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:52:41.88982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120290127s","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-22T17:52:41.889835Z","caller":"traceutil/trace.go:171","msg":"trace[2031597465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"1.120486961s","start":"2026-02-22T17:52:40.7693Z","end":"2026-02-22T17:52:41.889787Z","steps":["trace[2031597465] 'agreement among raft nodes before linearized reading' (duration: 1.120259196s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:41.889859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:41.259414Z","time spent":"630.439559ms","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:52:41.88988Z","caller":"traceutil/trace.go:171","msg":"trace[1747628214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:868; }","duration":"1.120359569s","start":"2026-02-22T17:52:40.769504Z","end":"2026-02-22T17:52:41.889864Z","steps":["trace[1747628214] 'agreement among raft nodes before linearized reading' (duration: 1.120134233s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:41.889893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:40.769257Z","time spent":"1.120615045s","remote":"127.0.0.1:59724","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-22T17:52:41.889924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:40.769501Z","time spent":"1.12041474s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:52:41.890134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:40.582329Z","time spent":"1.307332137s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:52:50.202909Z","caller":"traceutil/trace.go:171","msg":"trace[247090349] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"135.37041ms","start":"2026-02-22T17:52:50.067512Z","end":"2026-02-22T17:52:50.202883Z","steps":["trace[247090349] 'read index received' (duration: 121.297054ms)","trace[247090349] 'applied index is now lower than readState.Index' (duration: 14.072286ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:50.202993Z","caller":"traceutil/trace.go:171","msg":"trace[907297621] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"152.177993ms","start":"2026-02-22T17:52:50.050778Z","end":"2026-02-22T17:52:50.202956Z","steps":["trace[907297621] 'process raft request' (duration: 138.050995ms)","trace[907297621] 'compare' (duration: 13.919033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:50.203109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.589815ms","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-22T17:52:50.20316Z","caller":"traceutil/trace.go:171","msg":"trace[1665224389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"135.681687ms","start":"2026-02-22T17:52:50.067465Z","end":"2026-02-22T17:52:50.203147Z","steps":["trace[1665224389] 'agreement among raft nodes before linearized reading' (duration: 135.543474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:50.214572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.601356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:52:50.214615Z","caller":"traceutil/trace.go:171","msg":"trace[527543217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"146.685418ms","start":"2026-02-22T17:52:50.067919Z","end":"2026-02-22T17:52:50.214605Z","steps":["trace[527543217] 'agreement among raft nodes before linearized reading' (duration: 146.465963ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:53.844311Z","caller":"traceutil/trace.go:171","msg":"trace[1533660264] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"126.358168ms","start":"2026-02-22T17:52:53.717935Z","end":"2026-02-22T17:52:53.844293Z","steps":["trace[1533660264] 'read index received' (duration: 28.645574ms)","trace[1533660264] 'applied index is now lower than readState.Index' (duration: 97.711654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:53.844439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.500282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:52:53.844455Z","caller":"traceutil/trace.go:171","msg":"trace[59545487] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:901; }","duration":"126.575454ms","start":"2026-02-22T17:52:53.717875Z","end":"2026-02-22T17:52:53.84445Z","steps":["trace[59545487] 'agreement among raft nodes before linearized reading' (duration: 126.498532ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:54.195578Z","caller":"traceutil/trace.go:171","msg":"trace[2112672305] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"117.657604ms","start":"2026-02-22T17:52:54.077899Z","end":"2026-02-22T17:52:54.195557Z","steps":["trace[2112672305] 'read index received' (duration: 117.50894ms)","trace[2112672305] 'applied index is now lower than readState.Index' (duration: 147.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:54.195666Z","caller":"traceutil/trace.go:171","msg":"trace[398894930] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"173.406516ms","start":"2026-02-22T17:52:54.02223Z","end":"2026-02-22T17:52:54.195636Z","steps":["trace[398894930] 'process raft request' (duration: 173.17466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:54.195755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.845078ms","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-22T17:52:54.195795Z","caller":"traceutil/trace.go:171","msg":"trace[1057366275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"117.943921ms","start":"2026-02-22T17:52:54.077841Z","end":"2026-02-22T17:52:54.195785Z","steps":["trace[1057366275] 'agreement among raft nodes before linearized reading' (duration: 117.802028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:55.763315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.177904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:52:55.76341Z","caller":"traceutil/trace.go:171","msg":"trace[2062912549] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"504.895609ms","start":"2026-02-22T17:52:55.258497Z","end":"2026-02-22T17:52:55.763393Z","steps":["trace[2062912549] 'read index received' (duration: 106.466179ms)","trace[2062912549] 'applied index is now lower than readState.Index' (duration: 398.42808ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:55.763488Z","caller":"traceutil/trace.go:171","msg":"trace[592347380] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"1.062941006s","start":"2026-02-22T17:52:54.700532Z","end":"2026-02-22T17:52:55.763473Z","steps":["trace[592347380] 'process raft request' (duration: 664.500995ms)","trace[592347380] 'compare' (duration: 397.946939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:55.763557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:54.700523Z","time spent":"1.062989447s","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8757,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:55.763698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.405517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4741"} {"level":"warn","ts":"2026-02-22T17:52:55.7637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.175786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:55.763746Z","caller":"traceutil/trace.go:171","msg":"trace[60387367] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:916; }","duration":"393.485098ms","start":"2026-02-22T17:52:55.370249Z","end":"2026-02-22T17:52:55.763734Z","steps":["trace[60387367] 'agreement among raft nodes before linearized reading' (duration: 393.347655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:55.76377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:55.370234Z","time spent":"393.53168ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4763,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-22T17:52:55.763774Z","caller":"traceutil/trace.go:171","msg":"trace[1028589429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"505.296319ms","start":"2026-02-22T17:52:55.25846Z","end":"2026-02-22T17:52:55.763756Z","steps":["trace[1028589429] 'agreement among raft nodes before linearized reading' (duration: 505.152695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:55.763815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:55.258441Z","time spent":"505.366361ms","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:52:58.346661Z","caller":"traceutil/trace.go:171","msg":"trace[2043557895] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"114.270791ms","start":"2026-02-22T17:52:58.23237Z","end":"2026-02-22T17:52:58.346641Z","steps":["trace[2043557895] 'process raft request' (duration: 114.169379ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:58.588942Z","caller":"traceutil/trace.go:171","msg":"trace[1800032693] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"272.920644ms","start":"2026-02-22T17:52:58.315998Z","end":"2026-02-22T17:52:58.588919Z","steps":["trace[1800032693] 'read index received' (duration: 31.149346ms)","trace[1800032693] 'applied index is now lower than readState.Index' (duration: 241.770188ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:58.589004Z","caller":"traceutil/trace.go:171","msg":"trace[703397589] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"355.34028ms","start":"2026-02-22T17:52:58.233629Z","end":"2026-02-22T17:52:58.58897Z","steps":["trace[703397589] 'process raft request' (duration: 355.140305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:58.589168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:58.233601Z","time spent":"355.480764ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:58.589211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.18101ms","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-22T17:52:58.589251Z","caller":"traceutil/trace.go:171","msg":"trace[794233470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"273.294453ms","start":"2026-02-22T17:52:58.315944Z","end":"2026-02-22T17:52:58.589239Z","steps":["trace[794233470] 'agreement among raft nodes before linearized reading' (duration: 273.111088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:58.589688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.664082ms","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-22T17:52:58.58978Z","caller":"traceutil/trace.go:171","msg":"trace[759001733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"273.799325ms","start":"2026-02-22T17:52:58.315953Z","end":"2026-02-22T17:52:58.589753Z","steps":["trace[759001733] 'agreement among raft nodes before linearized reading' (duration: 273.509818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:58.589937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.543138ms","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-22T17:52:58.58999Z","caller":"traceutil/trace.go:171","msg":"trace[1696901700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"273.621501ms","start":"2026-02-22T17:52:58.31635Z","end":"2026-02-22T17:52:58.589971Z","steps":["trace[1696901700] 'agreement among raft nodes before linearized reading' (duration: 273.071497ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:58.785435Z","caller":"traceutil/trace.go:171","msg":"trace[453607272] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"191.697006ms","start":"2026-02-22T17:52:58.593716Z","end":"2026-02-22T17:52:58.785413Z","steps":["trace[453607272] 'process raft request' (duration: 191.42511ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:58.785453Z","caller":"traceutil/trace.go:171","msg":"trace[1484092362] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"190.826294ms","start":"2026-02-22T17:52:58.594598Z","end":"2026-02-22T17:52:58.785425Z","steps":["trace[1484092362] 'process raft request' (duration: 190.729491ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:58.785543Z","caller":"traceutil/trace.go:171","msg":"trace[216214852] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"189.03102ms","start":"2026-02-22T17:52:58.596495Z","end":"2026-02-22T17:52:58.785526Z","steps":["trace[216214852] 'process raft request' (duration: 188.883846ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:59.061906Z","caller":"traceutil/trace.go:171","msg":"trace[416087543] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:965; }","duration":"323.860315ms","start":"2026-02-22T17:52:58.738022Z","end":"2026-02-22T17:52:59.061883Z","steps":["trace[416087543] 'read index received' (duration: 47.084728ms)","trace[416087543] 'applied index is now lower than readState.Index' (duration: 276.774527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:59.062043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.0083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:59.062081Z","caller":"traceutil/trace.go:171","msg":"trace[557294059] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:929; }","duration":"324.101322ms","start":"2026-02-22T17:52:58.737971Z","end":"2026-02-22T17:52:59.062072Z","steps":["trace[557294059] 'agreement among raft nodes before linearized reading' (duration: 323.994659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:59.062119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:58.737946Z","time spent":"324.164213ms","remote":"127.0.0.1:59680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:52:59.062228Z","caller":"traceutil/trace.go:171","msg":"trace[1517371585] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"332.428697ms","start":"2026-02-22T17:52:58.729765Z","end":"2026-02-22T17:52:59.062194Z","steps":["trace[1517371585] 'process raft request' (duration: 236.768594ms)","trace[1517371585] 'compare' (duration: 95.239043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:59.062401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:58.729734Z","time spent":"332.582241ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:53:00.696946Z","caller":"traceutil/trace.go:171","msg":"trace[56275554] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"342.72125ms","start":"2026-02-22T17:53:00.354198Z","end":"2026-02-22T17:53:00.696919Z","steps":["trace[56275554] 'process raft request' (duration: 342.589377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:00.697119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.354187Z","time spent":"342.839033ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:01.497026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.052743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:53:01.497104Z","caller":"traceutil/trace.go:171","msg":"trace[780577755] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:970; }","duration":"902.88255ms","start":"2026-02-22T17:53:00.59421Z","end":"2026-02-22T17:53:01.497093Z","steps":["trace[780577755] 'read index received' (duration: 102.567974ms)","trace[780577755] 'applied index is now lower than readState.Index' (duration: 800.313866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:01.497493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.373815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-22T17:53:01.497513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.272799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-22T17:53:01.497559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.873705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:53:01.497668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.916794ms","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-22T17:53:01.497566Z","caller":"traceutil/trace.go:171","msg":"trace[747593749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"708.487197ms","start":"2026-02-22T17:53:00.789062Z","end":"2026-02-22T17:53:01.497549Z","steps":["trace[747593749] 'agreement among raft nodes before linearized reading' (duration: 708.293452ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:01.49773Z","caller":"traceutil/trace.go:171","msg":"trace[1142129897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"708.010626ms","start":"2026-02-22T17:53:00.789694Z","end":"2026-02-22T17:53:01.497705Z","steps":["trace[1142129897] 'agreement among raft nodes before linearized reading' (duration: 707.870453ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:01.497732Z","caller":"traceutil/trace.go:171","msg":"trace[597142998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"239.0207ms","start":"2026-02-22T17:53:01.258628Z","end":"2026-02-22T17:53:01.497648Z","steps":["trace[597142998] 'agreement among raft nodes before linearized reading' (duration: 238.910507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:01.497767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.789045Z","time spent":"708.706153ms","remote":"127.0.0.1:59724","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-22T17:53:01.497782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.789671Z","time spent":"708.098639ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T17:53:01.497481Z","caller":"traceutil/trace.go:171","msg":"trace[1765516264] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.059615835s","start":"2026-02-22T17:53:00.437829Z","end":"2026-02-22T17:53:01.497445Z","steps":["trace[1765516264] 'process raft request' (duration: 526.07961ms)","trace[1765516264] 'compare' (duration: 532.92536ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:01.497583Z","caller":"traceutil/trace.go:171","msg":"trace[1940995785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"903.406863ms","start":"2026-02-22T17:53:00.59416Z","end":"2026-02-22T17:53:01.497567Z","steps":["trace[1940995785] 'agreement among raft nodes before linearized reading' (duration: 903.132886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:01.497932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.437803Z","time spent":"1.060071375s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:01.498009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.59414Z","time spent":"903.838464ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:53:01.497505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.579885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:53:01.498151Z","caller":"traceutil/trace.go:171","msg":"trace[994133786] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:932; }","duration":"684.258421ms","start":"2026-02-22T17:53:00.813875Z","end":"2026-02-22T17:53:01.498134Z","steps":["trace[994133786] 'agreement among raft nodes before linearized reading' (duration: 683.579205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:01.498217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.813859Z","time spent":"684.337143ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:53:01.49757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.152206ms","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-22T17:53:01.498296Z","caller":"traceutil/trace.go:171","msg":"trace[1304097631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"705.884753ms","start":"2026-02-22T17:53:00.792373Z","end":"2026-02-22T17:53:01.498257Z","steps":["trace[1304097631] 'agreement among raft nodes before linearized reading' (duration: 705.089654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:01.499339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:00.792359Z","time spent":"706.061348ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T17:53:01.731592Z","caller":"traceutil/trace.go:171","msg":"trace[806239871] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"229.061004ms","start":"2026-02-22T17:53:01.502503Z","end":"2026-02-22T17:53:01.731564Z","steps":["trace[806239871] 'process raft request' (duration: 212.131368ms)","trace[806239871] 'compare' (duration: 16.770832ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:01.734135Z","caller":"traceutil/trace.go:171","msg":"trace[421028207] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"230.948681ms","start":"2026-02-22T17:53:01.503161Z","end":"2026-02-22T17:53:01.73411Z","steps":["trace[421028207] 'process raft request' (duration: 230.91484ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:01.734122Z","caller":"traceutil/trace.go:171","msg":"trace[825957061] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"231.33942ms","start":"2026-02-22T17:53:01.502749Z","end":"2026-02-22T17:53:01.734089Z","steps":["trace[825957061] 'process raft request' (duration: 231.172406ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:01.734158Z","caller":"traceutil/trace.go:171","msg":"trace[412916147] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"231.367301ms","start":"2026-02-22T17:53:01.502764Z","end":"2026-02-22T17:53:01.734132Z","steps":["trace[412916147] 'process raft request' (duration: 231.230618ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:02.40804Z","caller":"traceutil/trace.go:171","msg":"trace[1423582285] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"144.077445ms","start":"2026-02-22T17:53:02.263939Z","end":"2026-02-22T17:53:02.408017Z","steps":["trace[1423582285] 'process raft request' (duration: 143.929631ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:02.878419Z","caller":"traceutil/trace.go:171","msg":"trace[584667312] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"136.417666ms","start":"2026-02-22T17:53:02.74198Z","end":"2026-02-22T17:53:02.878398Z","steps":["trace[584667312] 'process raft request' (duration: 69.161401ms)","trace[584667312] 'compare' (duration: 67.140822ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:03.609564Z","caller":"traceutil/trace.go:171","msg":"trace[681726372] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"349.755173ms","start":"2026-02-22T17:53:03.259608Z","end":"2026-02-22T17:53:03.609363Z","steps":["trace[681726372] 'read index received' (duration: 349.692322ms)","trace[681726372] 'applied index is now lower than readState.Index' (duration: 61.891µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:03.609688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.07063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:03.609718Z","caller":"traceutil/trace.go:171","msg":"trace[404044042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"350.151042ms","start":"2026-02-22T17:53:03.259558Z","end":"2026-02-22T17:53:03.609709Z","steps":["trace[404044042] 'agreement among raft nodes before linearized reading' (duration: 350.074581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:03.609753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:03.259536Z","time spent":"350.209774ms","remote":"127.0.0.1:59506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:53:03.609984Z","caller":"traceutil/trace.go:171","msg":"trace[1977332136] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"659.706108ms","start":"2026-02-22T17:53:02.95025Z","end":"2026-02-22T17:53:03.609957Z","steps":["trace[1977332136] 'process raft request' (duration: 659.000101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:03.610087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:02.950223Z","time spent":"659.79227ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5548,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:04.237699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720195634757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:53:04.738409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349720195634757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:53:05.311604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.15554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:53:05.31186Z","caller":"traceutil/trace.go:171","msg":"trace[1394860499] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"1.979017229s","start":"2026-02-22T17:53:03.332799Z","end":"2026-02-22T17:53:05.311817Z","steps":["trace[1394860499] 'process raft request' (duration: 1.409563632s)","trace[1394860499] 'compare' (duration: 568.079374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:05.312065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:03.332753Z","time spent":"1.979193623s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:05.442517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.782097ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-22T17:53:05.442626Z","caller":"traceutil/trace.go:171","msg":"trace[235751524] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:981; }","duration":"1.705937083s","start":"2026-02-22T17:53:03.736671Z","end":"2026-02-22T17:53:05.442608Z","steps":["trace[235751524] 'read index received' (duration: 1.006111929s)","trace[235751524] 'applied index is now lower than readState.Index' (duration: 699.823744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:05.442875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.771221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:05.442947Z","caller":"traceutil/trace.go:171","msg":"trace[1598516096] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:942; }","duration":"703.951665ms","start":"2026-02-22T17:53:04.738984Z","end":"2026-02-22T17:53:05.442936Z","steps":["trace[1598516096] 'agreement among raft nodes before linearized reading' (duration: 703.777211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.442952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705629616s","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-22T17:53:05.443046Z","caller":"traceutil/trace.go:171","msg":"trace[1234595742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"1.705760109s","start":"2026-02-22T17:53:03.737243Z","end":"2026-02-22T17:53:05.443003Z","steps":["trace[1234595742] 'agreement among raft nodes before linearized reading' (duration: 1.705515713s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.443042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:04.738961Z","time spent":"704.020437ms","remote":"127.0.0.1:59610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:53:05.443093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:03.737218Z","time spent":"1.705866401s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:53:05.443011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339022427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-22T17:53:05.44315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:53:05.443186Z","caller":"traceutil/trace.go:171","msg":"trace[328913435] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:942; }","duration":"1.339235573s","start":"2026-02-22T17:53:04.103937Z","end":"2026-02-22T17:53:05.443173Z","steps":["trace[328913435] 'agreement among raft nodes before linearized reading' (duration: 1.338998717s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:05.443212Z","caller":"traceutil/trace.go:171","msg":"trace[375344875] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:942; }","duration":"435.919492ms","start":"2026-02-22T17:53:05.007276Z","end":"2026-02-22T17:53:05.443195Z","steps":["trace[375344875] 'agreement among raft nodes before linearized reading' (duration: 435.806439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.443224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:04.103916Z","time spent":"1.339296224s","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:53:05.443248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:05.007247Z","time spent":"435.991624ms","remote":"127.0.0.1:37324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:53:05.443399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705015489s","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-22T17:53:05.443432Z","caller":"traceutil/trace.go:171","msg":"trace[1613740395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"1.705073251s","start":"2026-02-22T17:53:03.738349Z","end":"2026-02-22T17:53:05.443422Z","steps":["trace[1613740395] 'agreement among raft nodes before linearized reading' (duration: 1.704974118s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.44345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.913104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-22T17:53:05.44346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:03.73834Z","time spent":"1.705113411s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-22T17:53:05.443484Z","caller":"traceutil/trace.go:171","msg":"trace[2119662186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"642.999956ms","start":"2026-02-22T17:53:04.800474Z","end":"2026-02-22T17:53:05.443474Z","steps":["trace[2119662186] 'agreement among raft nodes before linearized reading' (duration: 642.868133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.443514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:04.800432Z","time spent":"643.074858ms","remote":"127.0.0.1:59724","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-22T17:53:05.443517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706816364s","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-22T17:53:05.443609Z","caller":"traceutil/trace.go:171","msg":"trace[1915664810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:942; }","duration":"1.706927147s","start":"2026-02-22T17:53:03.736658Z","end":"2026-02-22T17:53:05.443585Z","steps":["trace[1915664810] 'agreement among raft nodes before linearized reading' (duration: 1.7066629s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.443643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706162238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-22T17:53:05.443653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:03.736605Z","time spent":"1.707039149s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T17:53:05.443686Z","caller":"traceutil/trace.go:171","msg":"trace[1540714686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"1.70622101s","start":"2026-02-22T17:53:03.737453Z","end":"2026-02-22T17:53:05.443674Z","steps":["trace[1540714686] 'agreement among raft nodes before linearized reading' (duration: 1.706114537s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.443403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187075439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:53:05.443725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:03.737444Z","time spent":"1.706272231s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:53:05.443735Z","caller":"traceutil/trace.go:171","msg":"trace[1078979720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"1.187441418s","start":"2026-02-22T17:53:04.256283Z","end":"2026-02-22T17:53:05.443724Z","steps":["trace[1078979720] 'agreement among raft nodes before linearized reading' (duration: 1.18709374s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.443775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:04.256263Z","time spent":"1.187497619s","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:53:05.747665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.765766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:53:05.747933Z","caller":"traceutil/trace.go:171","msg":"trace[1329961765] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:983; }","duration":"294.383951ms","start":"2026-02-22T17:53:05.453531Z","end":"2026-02-22T17:53:05.747915Z","steps":["trace[1329961765] 'read index received' (duration: 28.748197ms)","trace[1329961765] 'applied index is now lower than readState.Index' (duration: 265.634834ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:05.747922Z","caller":"traceutil/trace.go:171","msg":"trace[50318513] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"298.475481ms","start":"2026-02-22T17:53:05.449415Z","end":"2026-02-22T17:53:05.74789Z","steps":["trace[50318513] 'process raft request' (duration: 298.345688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:05.747999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.462273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:05.748029Z","caller":"traceutil/trace.go:171","msg":"trace[431279287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"294.512554ms","start":"2026-02-22T17:53:05.453505Z","end":"2026-02-22T17:53:05.748018Z","steps":["trace[431279287] 'agreement among raft nodes before linearized reading' (duration: 294.464123ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:05.748019Z","caller":"traceutil/trace.go:171","msg":"trace[833863777] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"298.592615ms","start":"2026-02-22T17:53:05.449399Z","end":"2026-02-22T17:53:05.747991Z","steps":["trace[833863777] 'process raft request' (duration: 187.42046ms)","trace[833863777] 'compare' (duration: 110.644942ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:05.748225Z","caller":"traceutil/trace.go:171","msg":"trace[410499477] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"298.629375ms","start":"2026-02-22T17:53:05.449569Z","end":"2026-02-22T17:53:05.748198Z","steps":["trace[410499477] 'process raft request' (duration: 298.256516ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:05.748278Z","caller":"traceutil/trace.go:171","msg":"trace[1390687498] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"298.43156ms","start":"2026-02-22T17:53:05.449834Z","end":"2026-02-22T17:53:05.748266Z","steps":["trace[1390687498] 'process raft request' (duration: 298.037331ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:08.17772Z","caller":"traceutil/trace.go:171","msg":"trace[2038781932] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"398.569963ms","start":"2026-02-22T17:53:07.779115Z","end":"2026-02-22T17:53:08.177685Z","steps":["trace[2038781932] 'process raft request' (duration: 397.61319ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:08.177723Z","caller":"traceutil/trace.go:171","msg":"trace[1456798255] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"398.072171ms","start":"2026-02-22T17:53:07.779624Z","end":"2026-02-22T17:53:08.177696Z","steps":["trace[1456798255] 'process raft request' (duration: 397.836126ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:08.17778Z","caller":"traceutil/trace.go:171","msg":"trace[1484491100] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"394.636467ms","start":"2026-02-22T17:53:07.783117Z","end":"2026-02-22T17:53:08.177754Z","steps":["trace[1484491100] 'process raft request' (duration: 394.517584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:08.177891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:07.779095Z","time spent":"398.709727ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:08.177965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:07.779602Z","time spent":"398.215434ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:08.178127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:07.78311Z","time spent":"394.734469ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:53:12.098365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.951893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:53:12.098465Z","caller":"traceutil/trace.go:171","msg":"trace[1040642146] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"235.510301ms","start":"2026-02-22T17:53:11.862939Z","end":"2026-02-22T17:53:12.098449Z","steps":["trace[1040642146] 'read index received' (duration: 100.394134ms)","trace[1040642146] 'applied index is now lower than readState.Index' (duration: 135.114797ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:12.098664Z","caller":"traceutil/trace.go:171","msg":"trace[1885962413] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"252.108619ms","start":"2026-02-22T17:53:11.846456Z","end":"2026-02-22T17:53:12.098564Z","steps":["trace[1885962413] 'process raft request' (duration: 116.867089ms)","trace[1885962413] 'compare' (duration: 134.215905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:12.098781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.820809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:12.099084Z","caller":"traceutil/trace.go:171","msg":"trace[661023746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"236.150587ms","start":"2026-02-22T17:53:11.862899Z","end":"2026-02-22T17:53:12.099049Z","steps":["trace[661023746] 'agreement among raft nodes before linearized reading' (duration: 235.820169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:12.18576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.443049ms","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-22T17:53:12.185823Z","caller":"traceutil/trace.go:171","msg":"trace[716648583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"312.553591ms","start":"2026-02-22T17:53:11.873255Z","end":"2026-02-22T17:53:12.185809Z","steps":["trace[716648583] 'agreement among raft nodes before linearized reading' (duration: 312.372267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:12.185854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:53:11.873238Z","time spent":"312.610563ms","remote":"127.0.0.1:59724","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-22T17:53:12.185815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.783809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:12.185988Z","caller":"traceutil/trace.go:171","msg":"trace[1562143758] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:970; }","duration":"132.980274ms","start":"2026-02-22T17:53:12.052976Z","end":"2026-02-22T17:53:12.185956Z","steps":["trace[1562143758] 'agreement among raft nodes before linearized reading' (duration: 132.697227ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:12.563581Z","caller":"traceutil/trace.go:171","msg":"trace[542855526] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"166.281821ms","start":"2026-02-22T17:53:12.397266Z","end":"2026-02-22T17:53:12.563548Z","steps":["trace[542855526] 'process raft request' (duration: 165.294127ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:12.563958Z","caller":"traceutil/trace.go:171","msg":"trace[1108377790] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"108.547715ms","start":"2026-02-22T17:53:12.455395Z","end":"2026-02-22T17:53:12.563943Z","steps":["trace[1108377790] 'read index received' (duration: 107.257964ms)","trace[1108377790] 'applied index is now lower than readState.Index' (duration: 1.288281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:12.564274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.862352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:12.564319Z","caller":"traceutil/trace.go:171","msg":"trace[1827552432] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:991; }","duration":"108.918874ms","start":"2026-02-22T17:53:12.455388Z","end":"2026-02-22T17:53:12.564307Z","steps":["trace[1827552432] 'agreement among raft nodes before linearized reading' (duration: 108.614026ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:12.882055Z","caller":"traceutil/trace.go:171","msg":"trace[248396709] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"109.61524ms","start":"2026-02-22T17:53:12.772413Z","end":"2026-02-22T17:53:12.882028Z","steps":["trace[248396709] 'process raft request' (duration: 32.332122ms)","trace[248396709] 'compare' (duration: 77.160975ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:12.888023Z","caller":"traceutil/trace.go:171","msg":"trace[294846099] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1037; }","duration":"107.332235ms","start":"2026-02-22T17:53:12.78067Z","end":"2026-02-22T17:53:12.888002Z","steps":["trace[294846099] 'read index received' (duration: 24.07321ms)","trace[294846099] 'applied index is now lower than readState.Index' (duration: 83.258215ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:12.888066Z","caller":"traceutil/trace.go:171","msg":"trace[42301891] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"114.377748ms","start":"2026-02-22T17:53:12.773667Z","end":"2026-02-22T17:53:12.888045Z","steps":["trace[42301891] 'process raft request' (duration: 114.170143ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:12.888162Z","caller":"traceutil/trace.go:171","msg":"trace[1112626690] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"112.681715ms","start":"2026-02-22T17:53:12.775434Z","end":"2026-02-22T17:53:12.888115Z","steps":["trace[1112626690] 'process raft request' (duration: 112.524862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:12.8882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.56558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:53:12.888272Z","caller":"traceutil/trace.go:171","msg":"trace[242994577] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:998; }","duration":"107.672814ms","start":"2026-02-22T17:53:12.780587Z","end":"2026-02-22T17:53:12.88826Z","steps":["trace[242994577] 'agreement among raft nodes before linearized reading' (duration: 107.49312ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:13.004442Z","caller":"traceutil/trace.go:171","msg":"trace[52806107] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"103.589663ms","start":"2026-02-22T17:53:12.900823Z","end":"2026-02-22T17:53:13.004412Z","steps":["trace[52806107] 'process raft request' (duration: 102.444495ms)"],"step_count":1}