{"level":"warn","ts":"2026-02-10T21:28:15.752774Z","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-10T21:28:15.75285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-10T21:28:15.753121Z","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-10T21:28:15.753137Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-10T21:28:15.753222Z","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-10T21:28:15.753973Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-02-10T21:28:15.754122Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-10T21:28:15.757561Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.125695ms"} {"level":"info","ts":"2026-02-10T21:28:15.782516Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-02-10T21:28:15.782611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:28:15.782631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-02-10T21:28:15.783009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:28:15.783093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-02-10T21:28:15.783382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-02-10T21:28:15.79079Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:28:15.792784Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:28:15.795844Z","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-10T21:28:15.797987Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:28:15.798456Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:28:15.798613Z","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-10T21:28:15.79865Z","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-10T21:28:15.798658Z","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-10T21:28:15.79893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-02-10T21:28:15.799189Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-10T21:28:15.824991Z","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-10T21:28:15.826636Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:28:15.826664Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:28:15.82764Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-10T21:28:15.827656Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-10T21:28:16.085874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:28:16.085928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:28:16.085969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-02-10T21:28:16.086009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:28:16.086019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-10T21:28:16.086028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-02-10T21:28:16.086038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-10T21:28:16.086927Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:28:16.087074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:28:16.087509Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:16.087593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:28:16.088285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:28:16.088302Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:28:16.09149Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T21:28:16.091998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:16.092071Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:16.115001Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:16.115996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-02-10T21:28:34.814149Z","caller":"traceutil/trace.go:171","msg":"trace[1444498625] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"329.599284ms","start":"2026-02-10T21:28:34.484528Z","end":"2026-02-10T21:28:34.814127Z","steps":["trace[1444498625] 'read index received' (duration: 326.174402ms)","trace[1444498625] 'applied index is now lower than readState.Index' (duration: 3.423972ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:28:34.814263Z","caller":"traceutil/trace.go:171","msg":"trace[1790786688] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"348.330799ms","start":"2026-02-10T21:28:34.465923Z","end":"2026-02-10T21:28:34.814253Z","steps":["trace[1790786688] 'process raft request' (duration: 344.880967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:34.814902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:34.465909Z","time spent":"348.381089ms","remote":"127.0.0.1:39242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:28:34.821934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.962931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:28:34.822216Z","caller":"traceutil/trace.go:171","msg":"trace[1527670609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"335.237018ms","start":"2026-02-10T21:28:34.486928Z","end":"2026-02-10T21:28:34.822165Z","steps":["trace[1527670609] 'agreement among raft nodes before linearized reading' (duration: 334.931461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:34.82227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:34.486921Z","time spent":"335.33708ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:28:34.822476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.944372ms","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-10T21:28:34.822557Z","caller":"traceutil/trace.go:171","msg":"trace[792870539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"338.045364ms","start":"2026-02-10T21:28:34.484501Z","end":"2026-02-10T21:28:34.822546Z","steps":["trace[792870539] 'agreement among raft nodes before linearized reading' (duration: 330.75263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:34.82259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:34.484486Z","time spent":"338.096586ms","remote":"127.0.0.1:39322","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-10T21:28:34.822724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.654835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T21:28:34.822741Z","caller":"traceutil/trace.go:171","msg":"trace[866734866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"295.671805ms","start":"2026-02-10T21:28:34.527065Z","end":"2026-02-10T21:28:34.822737Z","steps":["trace[866734866] 'agreement among raft nodes before linearized reading' (duration: 295.639855ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:45.76773Z","caller":"traceutil/trace.go:171","msg":"trace[897664880] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"156.928903ms","start":"2026-02-10T21:28:45.610528Z","end":"2026-02-10T21:28:45.767457Z","steps":["trace[897664880] 'process raft request' (duration: 156.79652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:47.301087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.44747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:28:47.301215Z","caller":"traceutil/trace.go:171","msg":"trace[1018470795] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"312.030623ms","start":"2026-02-10T21:28:46.989165Z","end":"2026-02-10T21:28:47.301195Z","steps":["trace[1018470795] 'process raft request' (duration: 140.230366ms)","trace[1018470795] 'compare' (duration: 171.351746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:47.30132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:46.989153Z","time spent":"312.107436ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:47.301322Z","caller":"traceutil/trace.go:171","msg":"trace[983224121] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"311.87995ms","start":"2026-02-10T21:28:46.989425Z","end":"2026-02-10T21:28:47.301305Z","steps":["trace[983224121] 'process raft request' (duration: 311.801158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:47.301391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:46.989409Z","time spent":"311.949673ms","remote":"127.0.0.1:39322","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-10T21:28:49.161457Z","caller":"traceutil/trace.go:171","msg":"trace[845588096] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"166.02088ms","start":"2026-02-10T21:28:48.995404Z","end":"2026-02-10T21:28:49.161425Z","steps":["trace[845588096] 'process raft request' (duration: 165.884157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:53.404767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.53041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T21:28:53.404835Z","caller":"traceutil/trace.go:171","msg":"trace[371149282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"221.651033ms","start":"2026-02-10T21:28:53.183169Z","end":"2026-02-10T21:28:53.40482Z","steps":["trace[371149282] 'range keys from in-memory index tree' (duration: 221.434958ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:54.06813Z","caller":"traceutil/trace.go:171","msg":"trace[886894995] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"660.334671ms","start":"2026-02-10T21:28:53.40777Z","end":"2026-02-10T21:28:54.068104Z","steps":["trace[886894995] 'process raft request' (duration: 660.193528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:54.069044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:53.407757Z","time spent":"660.459034ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:54.492297Z","caller":"traceutil/trace.go:171","msg":"trace[2098577231] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:506; }","duration":"867.389328ms","start":"2026-02-10T21:28:53.624891Z","end":"2026-02-10T21:28:54.49228Z","steps":["trace[2098577231] 'read index received' (duration: 443.183455ms)","trace[2098577231] 'applied index is now lower than readState.Index' (duration: 424.205233ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:28:54.492503Z","caller":"traceutil/trace.go:171","msg":"trace[257076896] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.084422562s","start":"2026-02-10T21:28:53.408068Z","end":"2026-02-10T21:28:54.492491Z","steps":["trace[257076896] 'process raft request' (duration: 1.071010273s)","trace[257076896] 'compare' (duration: 12.446366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:54.492588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:53.408053Z","time spent":"1.084474834s","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:54.49273Z","caller":"traceutil/trace.go:171","msg":"trace[826951776] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.083585012s","start":"2026-02-10T21:28:53.40913Z","end":"2026-02-10T21:28:54.492715Z","steps":["trace[826951776] 'process raft request' (duration: 1.083105759s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:54.492776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:53.409124Z","time spent":"1.083627903s","remote":"127.0.0.1:39322","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-10T21:28:54.492893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.999643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w6tr9\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T21:28:54.492917Z","caller":"traceutil/trace.go:171","msg":"trace[1271433728] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w6tr9; range_end:; response_count:1; response_revision:493; }","duration":"868.058503ms","start":"2026-02-10T21:28:53.624851Z","end":"2026-02-10T21:28:54.49291Z","steps":["trace[1271433728] 'agreement among raft nodes before linearized reading' (duration: 868.004352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:54.492935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:53.624833Z","time spent":"868.097605ms","remote":"127.0.0.1:39242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-w6tr9\" "} {"level":"warn","ts":"2026-02-10T21:28:54.493066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.310565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:28:54.493085Z","caller":"traceutil/trace.go:171","msg":"trace[940582345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"624.330145ms","start":"2026-02-10T21:28:53.86875Z","end":"2026-02-10T21:28:54.49308Z","steps":["trace[940582345] 'agreement among raft nodes before linearized reading' (duration: 624.296985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:54.493104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:53.868711Z","time spent":"624.389847ms","remote":"127.0.0.1:39070","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-10T21:28:57.21561Z","caller":"traceutil/trace.go:171","msg":"trace[592546054] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"204.030465ms","start":"2026-02-10T21:28:57.011548Z","end":"2026-02-10T21:28:57.215579Z","steps":["trace[592546054] 'process raft request' (duration: 203.747067ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:09.968619Z","caller":"traceutil/trace.go:171","msg":"trace[1440015075] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"105.908951ms","start":"2026-02-10T21:29:09.862694Z","end":"2026-02-10T21:29:09.968603Z","steps":["trace[1440015075] 'read index received' (duration: 105.800718ms)","trace[1440015075] 'applied index is now lower than readState.Index' (duration: 107.723µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:09.968718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.007583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:09.968735Z","caller":"traceutil/trace.go:171","msg":"trace[1026091575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"106.058844ms","start":"2026-02-10T21:29:09.86267Z","end":"2026-02-10T21:29:09.968729Z","steps":["trace[1026091575] 'agreement among raft nodes before linearized reading' (duration: 105.993682ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:09.968993Z","caller":"traceutil/trace.go:171","msg":"trace[1035059633] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"212.089617ms","start":"2026-02-10T21:29:09.756897Z","end":"2026-02-10T21:29:09.968987Z","steps":["trace[1035059633] 'process raft request' (duration: 211.633946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.344421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.940854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:29:11.344484Z","caller":"traceutil/trace.go:171","msg":"trace[1366483150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"666.029377ms","start":"2026-02-10T21:29:10.67844Z","end":"2026-02-10T21:29:11.344469Z","steps":["trace[1366483150] 'range keys from in-memory index tree' (duration: 665.786291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.344514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.67843Z","time spent":"666.077438ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:29:11.34496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121237026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T21:29:11.344988Z","caller":"traceutil/trace.go:171","msg":"trace[2043018652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"1.121286068s","start":"2026-02-10T21:29:10.223695Z","end":"2026-02-10T21:29:11.344981Z","steps":["trace[2043018652] 'range keys from in-memory index tree' (duration: 1.121169285s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.345069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.22368Z","time spent":"1.12137996s","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T21:29:11.34535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.993564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T21:29:11.345376Z","caller":"traceutil/trace.go:171","msg":"trace[23813260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"671.051976ms","start":"2026-02-10T21:29:10.674317Z","end":"2026-02-10T21:29:11.345369Z","steps":["trace[23813260] 'range keys from in-memory index tree' (duration: 670.866131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.345421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.6743Z","time spent":"671.115137ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:29:11.346059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.375241ms","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-10T21:29:11.346091Z","caller":"traceutil/trace.go:171","msg":"trace[1899731475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"665.421832ms","start":"2026-02-10T21:29:10.680663Z","end":"2026-02-10T21:29:11.346084Z","steps":["trace[1899731475] 'range keys from in-memory index tree' (duration: 665.287289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.346174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.680657Z","time spent":"665.510274ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:29:11.346393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.291551ms","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-10T21:29:11.346438Z","caller":"traceutil/trace.go:171","msg":"trace[158896703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"597.366523ms","start":"2026-02-10T21:29:10.749064Z","end":"2026-02-10T21:29:11.34643Z","steps":["trace[158896703] 'range keys from in-memory index tree' (duration: 597.205719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.346458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.749049Z","time spent":"597.403653ms","remote":"127.0.0.1:39322","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-10T21:29:11.34675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.51481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:11.346796Z","caller":"traceutil/trace.go:171","msg":"trace[41196258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"482.598452ms","start":"2026-02-10T21:29:10.86419Z","end":"2026-02-10T21:29:11.346789Z","steps":["trace[41196258] 'range keys from in-memory index tree' (duration: 482.389017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.346833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.864171Z","time spent":"482.638792ms","remote":"127.0.0.1:39070","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-10T21:29:11.902165Z","caller":"traceutil/trace.go:171","msg":"trace[876333157] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"547.544527ms","start":"2026-02-10T21:29:11.3546Z","end":"2026-02-10T21:29:11.902145Z","steps":["trace[876333157] 'process raft request' (duration: 547.397893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:11.902342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:11.354591Z","time spent":"547.644759ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:29:12.193733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.485282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:12.193971Z","caller":"traceutil/trace.go:171","msg":"trace[1656555732] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"336.913625ms","start":"2026-02-10T21:29:11.857043Z","end":"2026-02-10T21:29:12.193956Z","steps":["trace[1656555732] 'process raft request' (duration: 336.872874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:12.194027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:11.857027Z","time spent":"336.974217ms","remote":"127.0.0.1:39130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T21:29:12.19487Z","caller":"traceutil/trace.go:171","msg":"trace[1079183999] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"838.48788ms","start":"2026-02-10T21:29:11.356355Z","end":"2026-02-10T21:29:12.194843Z","steps":["trace[1079183999] 'process raft request' (duration: 837.506796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:12.195013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:11.356349Z","time spent":"838.584222ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:12.195153Z","caller":"traceutil/trace.go:171","msg":"trace[88989703] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"840.380774ms","start":"2026-02-10T21:29:11.354761Z","end":"2026-02-10T21:29:12.195142Z","steps":["trace[88989703] 'process raft request' (duration: 669.421006ms)","trace[88989703] 'compare' (duration: 168.835907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:12.195199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:11.354735Z","time spent":"840.439666ms","remote":"127.0.0.1:39322","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-10T21:29:12.195275Z","caller":"traceutil/trace.go:171","msg":"trace[136941459] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"840.426405ms","start":"2026-02-10T21:29:11.354837Z","end":"2026-02-10T21:29:12.195263Z","steps":["trace[136941459] 'process raft request' (duration: 838.97367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:12.195315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:11.354828Z","time spent":"840.464176ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:12.195453Z","caller":"traceutil/trace.go:171","msg":"trace[1666785062] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"839.044163ms","start":"2026-02-10T21:29:11.356398Z","end":"2026-02-10T21:29:12.195442Z","steps":["trace[1666785062] 'process raft request' (duration: 837.493386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:12.1955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:11.356394Z","time spent":"839.080454ms","remote":"127.0.0.1:39322","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-10T21:29:16.208283Z","caller":"traceutil/trace.go:171","msg":"trace[1544604767] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"264.942203ms","start":"2026-02-10T21:29:15.943314Z","end":"2026-02-10T21:29:16.208256Z","steps":["trace[1544604767] 'process raft request' (duration: 264.79007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:17.557679Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.30457908s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-10T21:29:17.851249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.238077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:17.851604Z","caller":"traceutil/trace.go:171","msg":"trace[345279368] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.591380893s","start":"2026-02-10T21:29:16.260206Z","end":"2026-02-10T21:29:17.851587Z","steps":["trace[345279368] 'process raft request' (duration: 1.591317932s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:17.851668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:16.260199Z","time spent":"1.591436084s","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:17.851986Z","caller":"traceutil/trace.go:171","msg":"trace[935278053] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.598965584s","start":"2026-02-10T21:29:16.253008Z","end":"2026-02-10T21:29:17.851973Z","steps":["trace[935278053] 'process raft request' (duration: 1.304919018s)","trace[935278053] 'compare' (duration: 288.173086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:17.852041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:16.252996Z","time spent":"1.599015895s","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:29:34.81242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.514091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:34.812538Z","caller":"traceutil/trace.go:171","msg":"trace[1661818940] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"223.05368ms","start":"2026-02-10T21:29:34.589473Z","end":"2026-02-10T21:29:34.812527Z","steps":["trace[1661818940] 'process raft request' (duration: 223.022029ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:34.812699Z","caller":"traceutil/trace.go:171","msg":"trace[1429308874] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"224.788901ms","start":"2026-02-10T21:29:34.5879Z","end":"2026-02-10T21:29:34.812689Z","steps":["trace[1429308874] 'process raft request' (duration: 113.951432ms)","trace[1429308874] 'compare' (duration: 110.436939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:37.202397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.062646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:37.202517Z","caller":"traceutil/trace.go:171","msg":"trace[1571727090] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:749; }","duration":"397.225659ms","start":"2026-02-10T21:29:36.805268Z","end":"2026-02-10T21:29:37.202494Z","steps":["trace[1571727090] 'count revisions from in-memory index tree' (duration: 396.956563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:37.202591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:36.80523Z","time spent":"397.328201ms","remote":"127.0.0.1:34962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-10T21:29:37.203013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.536109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T21:29:37.203074Z","caller":"traceutil/trace.go:171","msg":"trace[163970906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:749; }","duration":"386.617251ms","start":"2026-02-10T21:29:36.816442Z","end":"2026-02-10T21:29:37.203059Z","steps":["trace[163970906] 'range keys from in-memory index tree' (duration: 386.326004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:37.203154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:36.816432Z","time spent":"386.707563ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:29:37.203441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.325724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:29:37.203502Z","caller":"traceutil/trace.go:171","msg":"trace[290152753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"386.401956ms","start":"2026-02-10T21:29:36.817082Z","end":"2026-02-10T21:29:37.203484Z","steps":["trace[290152753] 'range keys from in-memory index tree' (duration: 386.226092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:37.203544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:36.817074Z","time spent":"386.459137ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:29:37.203823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.409448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:37.203849Z","caller":"traceutil/trace.go:171","msg":"trace[768226304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"339.46218ms","start":"2026-02-10T21:29:36.86438Z","end":"2026-02-10T21:29:37.203842Z","steps":["trace[768226304] 'range keys from in-memory index tree' (duration: 339.287676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:37.203966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:36.86436Z","time spent":"339.595002ms","remote":"127.0.0.1:39070","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-10T21:29:39.50677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.480122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:29:39.506848Z","caller":"traceutil/trace.go:171","msg":"trace[631790573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"274.579083ms","start":"2026-02-10T21:29:39.232247Z","end":"2026-02-10T21:29:39.506826Z","steps":["trace[631790573] 'range keys from in-memory index tree' (duration: 274.347688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:39.507155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.781201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:39.507204Z","caller":"traceutil/trace.go:171","msg":"trace[124001490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"644.868143ms","start":"2026-02-10T21:29:38.862322Z","end":"2026-02-10T21:29:39.50719Z","steps":["trace[124001490] 'range keys from in-memory index tree' (duration: 644.647738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:39.507232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:38.862303Z","time spent":"644.920505ms","remote":"127.0.0.1:39070","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-10T21:29:39.507635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.438707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T21:29:39.507666Z","caller":"traceutil/trace.go:171","msg":"trace[2038191481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"276.504628ms","start":"2026-02-10T21:29:39.231153Z","end":"2026-02-10T21:29:39.507657Z","steps":["trace[2038191481] 'range keys from in-memory index tree' (duration: 276.335824ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:39.623162Z","caller":"traceutil/trace.go:171","msg":"trace[412511146] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"110.268536ms","start":"2026-02-10T21:29:39.512879Z","end":"2026-02-10T21:29:39.623147Z","steps":["trace[412511146] 'process raft request' (duration: 110.185974ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:40.589546Z","caller":"traceutil/trace.go:171","msg":"trace[1085992278] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"968.267478ms","start":"2026-02-10T21:29:39.621257Z","end":"2026-02-10T21:29:40.589524Z","steps":["trace[1085992278] 'read index received' (duration: 2.834366ms)","trace[1085992278] 'applied index is now lower than readState.Index' (duration: 965.431872ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:40.590711Z","caller":"traceutil/trace.go:171","msg":"trace[733848287] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.077076398s","start":"2026-02-10T21:29:39.513619Z","end":"2026-02-10T21:29:40.590696Z","steps":["trace[733848287] 'process raft request' (duration: 1.075763387s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:40.59094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:39.513602Z","time spent":"1.077247382s","remote":"127.0.0.1:39322","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-10T21:29:40.591539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.276224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T21:29:40.591686Z","caller":"traceutil/trace.go:171","msg":"trace[973403659] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:756; }","duration":"970.452178ms","start":"2026-02-10T21:29:39.621222Z","end":"2026-02-10T21:29:40.591674Z","steps":["trace[973403659] 'agreement among raft nodes before linearized reading' (duration: 970.204142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:40.591839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:39.621203Z","time spent":"970.624032ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"info","ts":"2026-02-10T21:29:40.606551Z","caller":"traceutil/trace.go:171","msg":"trace[372171000] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"860.225823ms","start":"2026-02-10T21:29:39.746311Z","end":"2026-02-10T21:29:40.606537Z","steps":["trace[372171000] 'process raft request' (duration: 860.09414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:40.606641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:39.74628Z","time spent":"860.311625ms","remote":"127.0.0.1:39322","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-10T21:29:40.607473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.045773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:40.608622Z","caller":"traceutil/trace.go:171","msg":"trace[194627108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"746.187329ms","start":"2026-02-10T21:29:39.862383Z","end":"2026-02-10T21:29:40.60857Z","steps":["trace[194627108] 'agreement among raft nodes before linearized reading' (duration: 745.069493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:40.608705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:39.862363Z","time spent":"746.333274ms","remote":"127.0.0.1:39070","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-10T21:29:40.608035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.228114ms","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-10T21:29:40.608916Z","caller":"traceutil/trace.go:171","msg":"trace[2049375508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"394.144607ms","start":"2026-02-10T21:29:40.214762Z","end":"2026-02-10T21:29:40.608907Z","steps":["trace[2049375508] 'agreement among raft nodes before linearized reading' (duration: 393.205574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:40.609003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:40.214744Z","time spent":"394.249469ms","remote":"127.0.0.1:39322","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-10T21:29:40.60809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.122867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T21:29:40.609282Z","caller":"traceutil/trace.go:171","msg":"trace[771533722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"482.354686ms","start":"2026-02-10T21:29:40.126919Z","end":"2026-02-10T21:29:40.609274Z","steps":["trace[771533722] 'agreement among raft nodes before linearized reading' (duration: 481.137597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:40.609404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:40.126894Z","time spent":"482.50083ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T21:29:40.811273Z","caller":"traceutil/trace.go:171","msg":"trace[1519560362] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:785; }","duration":"171.110547ms","start":"2026-02-10T21:29:40.640142Z","end":"2026-02-10T21:29:40.811252Z","steps":["trace[1519560362] 'read index received' (duration: 74.008389ms)","trace[1519560362] 'applied index is now lower than readState.Index' (duration: 97.101238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:40.811589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.426394ms","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-10T21:29:40.811827Z","caller":"traceutil/trace.go:171","msg":"trace[2121424710] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"198.657341ms","start":"2026-02-10T21:29:40.613154Z","end":"2026-02-10T21:29:40.811811Z","steps":["trace[2121424710] 'process raft request' (duration: 100.965549ms)","trace[2121424710] 'compare' (duration: 96.74972ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:40.811632Z","caller":"traceutil/trace.go:171","msg":"trace[2134907864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"171.499416ms","start":"2026-02-10T21:29:40.640122Z","end":"2026-02-10T21:29:40.811621Z","steps":["trace[2134907864] 'agreement among raft nodes before linearized reading' (duration: 171.211609ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:40.812163Z","caller":"traceutil/trace.go:171","msg":"trace[470581411] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"197.610196ms","start":"2026-02-10T21:29:40.614538Z","end":"2026-02-10T21:29:40.812148Z","steps":["trace[470581411] 'process raft request' (duration: 196.627663ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:40.812688Z","caller":"traceutil/trace.go:171","msg":"trace[349230509] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"186.67219ms","start":"2026-02-10T21:29:40.626003Z","end":"2026-02-10T21:29:40.812675Z","steps":["trace[349230509] 'process raft request' (duration: 185.209696ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:54.831081Z","caller":"traceutil/trace.go:171","msg":"trace[646604539] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"104.755207ms","start":"2026-02-10T21:29:54.726311Z","end":"2026-02-10T21:29:54.831066Z","steps":["trace[646604539] 'process raft request' (duration: 104.46656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:55.254526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.155907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:55.254801Z","caller":"traceutil/trace.go:171","msg":"trace[413800813] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"254.763721ms","start":"2026-02-10T21:29:55.000027Z","end":"2026-02-10T21:29:55.254791Z","steps":["trace[413800813] 'process raft request' (duration: 128.291557ms)","trace[413800813] 'compare' (duration: 126.088975ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:55.254869Z","caller":"traceutil/trace.go:171","msg":"trace[1738131259] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"252.439976ms","start":"2026-02-10T21:29:55.002424Z","end":"2026-02-10T21:29:55.254864Z","steps":["trace[1738131259] 'process raft request' (duration: 252.15592ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:56.091945Z","caller":"traceutil/trace.go:171","msg":"trace[1296716355] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"340.093025ms","start":"2026-02-10T21:29:55.751829Z","end":"2026-02-10T21:29:56.091922Z","steps":["trace[1296716355] 'process raft request' (duration: 339.94192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:56.092092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:55.751801Z","time spent":"340.208567ms","remote":"127.0.0.1:39322","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-10T21:29:56.092648Z","caller":"traceutil/trace.go:171","msg":"trace[1398023787] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:884; }","duration":"230.008812ms","start":"2026-02-10T21:29:55.862627Z","end":"2026-02-10T21:29:56.092636Z","steps":["trace[1398023787] 'read index received' (duration: 230.004712ms)","trace[1398023787] 'applied index is now lower than readState.Index' (duration: 3.34µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:56.092713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.078954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:56.09274Z","caller":"traceutil/trace.go:171","msg":"trace[1408162280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"230.130676ms","start":"2026-02-10T21:29:55.862601Z","end":"2026-02-10T21:29:56.092731Z","steps":["trace[1408162280] 'agreement among raft nodes before linearized reading' (duration: 230.078184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:56.436043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.244158ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:29:56.436127Z","caller":"traceutil/trace.go:171","msg":"trace[839720500] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"341.282541ms","start":"2026-02-10T21:29:56.094829Z","end":"2026-02-10T21:29:56.436111Z","steps":["trace[839720500] 'read index received' (duration: 86.92115ms)","trace[839720500] 'applied index is now lower than readState.Index' (duration: 254.360011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:56.436386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.545177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:56.436419Z","caller":"traceutil/trace.go:171","msg":"trace[2041674948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"341.602099ms","start":"2026-02-10T21:29:56.094807Z","end":"2026-02-10T21:29:56.436409Z","steps":["trace[2041674948] 'agreement among raft nodes before linearized reading' (duration: 341.517807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:56.436449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:56.094796Z","time spent":"341.64627ms","remote":"127.0.0.1:39056","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-10T21:29:57.408312Z","caller":"traceutil/trace.go:171","msg":"trace[147991843] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"143.481462ms","start":"2026-02-10T21:29:57.264808Z","end":"2026-02-10T21:29:57.40829Z","steps":["trace[147991843] 'process raft request' (duration: 143.364799ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:57.82627Z","caller":"traceutil/trace.go:171","msg":"trace[201912981] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"115.029277ms","start":"2026-02-10T21:29:57.711215Z","end":"2026-02-10T21:29:57.826244Z","steps":["trace[201912981] 'read index received' (duration: 114.815082ms)","trace[201912981] 'applied index is now lower than readState.Index' (duration: 212.815µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:57.826442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.203211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:29:57.826474Z","caller":"traceutil/trace.go:171","msg":"trace[1567652537] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:856; }","duration":"115.289683ms","start":"2026-02-10T21:29:57.711175Z","end":"2026-02-10T21:29:57.826465Z","steps":["trace[1567652537] 'agreement among raft nodes before linearized reading' (duration: 115.183411ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:57.82662Z","caller":"traceutil/trace.go:171","msg":"trace[1231622012] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"561.65275ms","start":"2026-02-10T21:29:57.264936Z","end":"2026-02-10T21:29:57.826589Z","steps":["trace[1231622012] 'process raft request' (duration: 561.179739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:57.826777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:57.264931Z","time spent":"561.757273ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:29:58.379171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.756103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:29:58.379221Z","caller":"traceutil/trace.go:171","msg":"trace[2062863482] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:856; }","duration":"189.856425ms","start":"2026-02-10T21:29:58.189353Z","end":"2026-02-10T21:29:58.37921Z","steps":["trace[2062863482] 'count revisions from in-memory index tree' (duration: 189.656211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:58.379414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.249653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:29:58.379471Z","caller":"traceutil/trace.go:171","msg":"trace[363072109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"282.355346ms","start":"2026-02-10T21:29:58.097099Z","end":"2026-02-10T21:29:58.379455Z","steps":["trace[363072109] 'range keys from in-memory index tree' (duration: 282.025868ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:58.591779Z","caller":"traceutil/trace.go:171","msg":"trace[1971661356] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"207.743992ms","start":"2026-02-10T21:29:58.38402Z","end":"2026-02-10T21:29:58.591764Z","steps":["trace[1971661356] 'process raft request' (duration: 207.65471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.126958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.88821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:00.127042Z","caller":"traceutil/trace.go:171","msg":"trace[1537474349] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"296.283861ms","start":"2026-02-10T21:29:59.830749Z","end":"2026-02-10T21:30:00.127033Z","steps":["trace[1537474349] 'read index received' (duration: 23.23µs)","trace[1537474349] 'applied index is now lower than readState.Index' (duration: 296.259981ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:00.127087Z","caller":"traceutil/trace.go:171","msg":"trace[615474398] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"401.527809ms","start":"2026-02-10T21:29:59.725554Z","end":"2026-02-10T21:30:00.127081Z","steps":["trace[615474398] 'process raft request' (duration: 37.472965ms)","trace[615474398] 'compare' (duration: 363.821378ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:00.127112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:59.725537Z","time spent":"401.561399ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:00.127263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.513957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T21:30:00.127276Z","caller":"traceutil/trace.go:171","msg":"trace[1912306735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"296.549447ms","start":"2026-02-10T21:29:59.830723Z","end":"2026-02-10T21:30:00.127272Z","steps":["trace[1912306735] 'agreement among raft nodes before linearized reading' (duration: 296.475065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.127368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.070668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:00.127381Z","caller":"traceutil/trace.go:171","msg":"trace[436673194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"267.101839ms","start":"2026-02-10T21:29:59.860275Z","end":"2026-02-10T21:30:00.127377Z","steps":["trace[436673194] 'agreement among raft nodes before linearized reading' (duration: 267.082358ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:00.447707Z","caller":"traceutil/trace.go:171","msg":"trace[129783819] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"311.917345ms","start":"2026-02-10T21:30:00.135774Z","end":"2026-02-10T21:30:00.447691Z","steps":["trace[129783819] 'read index received' (duration: 311.785782ms)","trace[129783819] 'applied index is now lower than readState.Index' (duration: 131.013µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:00.44789Z","caller":"traceutil/trace.go:171","msg":"trace[472982275] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"316.188685ms","start":"2026-02-10T21:30:00.131694Z","end":"2026-02-10T21:30:00.447882Z","steps":["trace[472982275] 'process raft request' (duration: 315.910878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.447961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.131686Z","time spent":"316.222335ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:00.448104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.331405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-10T21:30:00.44813Z","caller":"traceutil/trace.go:171","msg":"trace[1236795384] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:862; }","duration":"312.371597ms","start":"2026-02-10T21:30:00.135752Z","end":"2026-02-10T21:30:00.448124Z","steps":["trace[1236795384] 'agreement among raft nodes before linearized reading' (duration: 312.325546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.44815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.135745Z","time spent":"312.400058ms","remote":"127.0.0.1:39328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-10T21:30:00.448224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.443002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:00.448242Z","caller":"traceutil/trace.go:171","msg":"trace[604086679] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:862; }","duration":"182.494304ms","start":"2026-02-10T21:30:00.265742Z","end":"2026-02-10T21:30:00.448236Z","steps":["trace[604086679] 'agreement among raft nodes before linearized reading' (duration: 182.469213ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:01.067402Z","caller":"traceutil/trace.go:171","msg":"trace[1086792069] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"195.831404ms","start":"2026-02-10T21:30:00.871557Z","end":"2026-02-10T21:30:01.067389Z","steps":["trace[1086792069] 'process raft request' (duration: 195.460544ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:02.777414Z","caller":"traceutil/trace.go:171","msg":"trace[1696847267] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"225.183378ms","start":"2026-02-10T21:30:02.552216Z","end":"2026-02-10T21:30:02.777399Z","steps":["trace[1696847267] 'read index received' (duration: 225.053415ms)","trace[1696847267] 'applied index is now lower than readState.Index' (duration: 129.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:02.777611Z","caller":"traceutil/trace.go:171","msg":"trace[409747659] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"322.994934ms","start":"2026-02-10T21:30:02.454608Z","end":"2026-02-10T21:30:02.777603Z","steps":["trace[409747659] 'process raft request' (duration: 322.720117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:02.777672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:02.454599Z","time spent":"323.030684ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:02.777786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.568147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:30:02.77781Z","caller":"traceutil/trace.go:171","msg":"trace[1969733667] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:868; }","duration":"225.612648ms","start":"2026-02-10T21:30:02.552191Z","end":"2026-02-10T21:30:02.777803Z","steps":["trace[1969733667] 'agreement among raft nodes before linearized reading' (duration: 225.576487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:02.777933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.902225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:30:02.777951Z","caller":"traceutil/trace.go:171","msg":"trace[1236716125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"132.941726ms","start":"2026-02-10T21:30:02.645005Z","end":"2026-02-10T21:30:02.777947Z","steps":["trace[1236716125] 'agreement among raft nodes before linearized reading' (duration: 132.882694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:09.149214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.160571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:09.149264Z","caller":"traceutil/trace.go:171","msg":"trace[583278557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"282.237872ms","start":"2026-02-10T21:30:08.867014Z","end":"2026-02-10T21:30:09.149252Z","steps":["trace[583278557] 'range keys from in-memory index tree' (duration: 282.11506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:09.149436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.282133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:30:09.149457Z","caller":"traceutil/trace.go:171","msg":"trace[66661197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"282.309864ms","start":"2026-02-10T21:30:08.86714Z","end":"2026-02-10T21:30:09.14945Z","steps":["trace[66661197] 'range keys from in-memory index tree' (duration: 282.216292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:09.884478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.027247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T21:30:09.884527Z","caller":"traceutil/trace.go:171","msg":"trace[113947625] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:888; }","duration":"261.104918ms","start":"2026-02-10T21:30:09.623411Z","end":"2026-02-10T21:30:09.884516Z","steps":["trace[113947625] 'range keys from in-memory index tree' (duration: 260.931795ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:10.091821Z","caller":"traceutil/trace.go:171","msg":"trace[1048595737] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"148.012518ms","start":"2026-02-10T21:30:09.943785Z","end":"2026-02-10T21:30:10.091798Z","steps":["trace[1048595737] 'process raft request' (duration: 49.674091ms)","trace[1048595737] 'compare' (duration: 98.251335ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:10.959775Z","caller":"traceutil/trace.go:171","msg":"trace[1760984224] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"115.914159ms","start":"2026-02-10T21:30:10.843846Z","end":"2026-02-10T21:30:10.95976Z","steps":["trace[1760984224] 'process raft request' (duration: 115.672093ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:11.660395Z","caller":"traceutil/trace.go:171","msg":"trace[951826541] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"465.444913ms","start":"2026-02-10T21:30:11.194933Z","end":"2026-02-10T21:30:11.660378Z","steps":["trace[951826541] 'process raft request' (duration: 465.376251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:11.660487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:11.194922Z","time spent":"465.520504ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:11.660695Z","caller":"traceutil/trace.go:171","msg":"trace[1887514570] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:927; }","duration":"437.528221ms","start":"2026-02-10T21:30:11.223161Z","end":"2026-02-10T21:30:11.660689Z","steps":["trace[1887514570] 'read index received' (duration: 437.525931ms)","trace[1887514570] 'applied index is now lower than readState.Index' (duration: 1.95µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:11.660764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.606553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:30:11.660779Z","caller":"traceutil/trace.go:171","msg":"trace[693905593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"437.645773ms","start":"2026-02-10T21:30:11.223127Z","end":"2026-02-10T21:30:11.660773Z","steps":["trace[693905593] 'agreement among raft nodes before linearized reading' (duration: 437.587652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:11.660792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:11.223112Z","time spent":"437.677724ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:30:11.680244Z","caller":"traceutil/trace.go:171","msg":"trace[999865430] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"350.906277ms","start":"2026-02-10T21:30:11.329329Z","end":"2026-02-10T21:30:11.680235Z","steps":["trace[999865430] 'process raft request' (duration: 350.850585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:11.680344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:11.329313Z","time spent":"350.960788ms","remote":"127.0.0.1:39322","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-10T21:30:11.680549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.531468ms","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-10T21:30:11.680568Z","caller":"traceutil/trace.go:171","msg":"trace[800168869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"341.566359ms","start":"2026-02-10T21:30:11.338997Z","end":"2026-02-10T21:30:11.680563Z","steps":["trace[800168869] 'agreement among raft nodes before linearized reading' (duration: 341.507378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:11.680582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:11.33899Z","time spent":"341.5892ms","remote":"127.0.0.1:39322","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-10T21:30:11.680713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.607463ms","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-10T21:30:11.680727Z","caller":"traceutil/trace.go:171","msg":"trace[1823168195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:893; }","duration":"211.644253ms","start":"2026-02-10T21:30:11.469078Z","end":"2026-02-10T21:30:11.680722Z","steps":["trace[1823168195] 'agreement among raft nodes before linearized reading' (duration: 211.588542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.136076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694704667635931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:30:14.138226Z","caller":"traceutil/trace.go:171","msg":"trace[2143482689] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"503.07638ms","start":"2026-02-10T21:30:13.635132Z","end":"2026-02-10T21:30:14.138208Z","steps":["trace[2143482689] 'read index received' (duration: 502.900156ms)","trace[2143482689] 'applied index is now lower than readState.Index' (duration: 175.774µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:14.138562Z","caller":"traceutil/trace.go:171","msg":"trace[935181183] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"503.678505ms","start":"2026-02-10T21:30:13.634875Z","end":"2026-02-10T21:30:14.138554Z","steps":["trace[935181183] 'process raft request' (duration: 503.222493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.138637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.634851Z","time spent":"503.732086ms","remote":"127.0.0.1:39322","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-10T21:30:14.484848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.685047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:30:14.484995Z","caller":"traceutil/trace.go:171","msg":"trace[651522396] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:897; }","duration":"849.851041ms","start":"2026-02-10T21:30:13.635104Z","end":"2026-02-10T21:30:14.484955Z","steps":["trace[651522396] 'agreement among raft nodes before linearized reading' (duration: 503.647224ms)","trace[651522396] 'count revisions from in-memory index tree' (duration: 346.041283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:14.485091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.635095Z","time spent":"849.980213ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-10T21:30:14.485444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.995418ms","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-10T21:30:14.485515Z","caller":"traceutil/trace.go:171","msg":"trace[1150080765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"821.08943ms","start":"2026-02-10T21:30:13.664393Z","end":"2026-02-10T21:30:14.485483Z","steps":["trace[1150080765] 'agreement among raft nodes before linearized reading' (duration: 474.371892ms)","trace[1150080765] 'range keys from in-memory index tree' (duration: 346.573905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:14.485556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.664381Z","time spent":"821.165931ms","remote":"127.0.0.1:39322","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-10T21:30:14.485637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.136787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:30:14.485673Z","caller":"traceutil/trace.go:171","msg":"trace[1619457618] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:897; }","duration":"695.206419ms","start":"2026-02-10T21:30:13.790456Z","end":"2026-02-10T21:30:14.485662Z","steps":["trace[1619457618] 'agreement among raft nodes before linearized reading' (duration: 348.332397ms)","trace[1619457618] 'count revisions from in-memory index tree' (duration: 346.828451ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:14.485726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.790435Z","time spent":"695.28144ms","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-10T21:30:14.485961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.183348ms","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-10T21:30:14.486006Z","caller":"traceutil/trace.go:171","msg":"trace[148037226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"775.248329ms","start":"2026-02-10T21:30:13.710745Z","end":"2026-02-10T21:30:14.485994Z","steps":["trace[148037226] 'agreement among raft nodes before linearized reading' (duration: 428.048938ms)","trace[148037226] 'range keys from in-memory index tree' (duration: 347.102238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:14.486042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.710714Z","time spent":"775.320821ms","remote":"127.0.0.1:39322","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-10T21:30:14.486256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.340595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T21:30:14.486297Z","caller":"traceutil/trace.go:171","msg":"trace[842320408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"794.446497ms","start":"2026-02-10T21:30:13.691839Z","end":"2026-02-10T21:30:14.486286Z","steps":["trace[842320408] 'agreement among raft nodes before linearized reading' (duration: 446.96061ms)","trace[842320408] 'range keys from in-memory index tree' (duration: 347.392245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:14.486337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.691821Z","time spent":"794.507629ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:30:14.486587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.763355ms","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-10T21:30:14.486641Z","caller":"traceutil/trace.go:171","msg":"trace[166850620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"794.868707ms","start":"2026-02-10T21:30:13.691755Z","end":"2026-02-10T21:30:14.486623Z","steps":["trace[166850620] 'agreement among raft nodes before linearized reading' (duration: 447.050243ms)","trace[166850620] 'range keys from in-memory index tree' (duration: 347.677651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:14.486683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:13.691745Z","time spent":"794.929388ms","remote":"127.0.0.1:39322","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-10T21:30:14.756286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.672866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:14.756374Z","caller":"traceutil/trace.go:171","msg":"trace[510408978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"134.776928ms","start":"2026-02-10T21:30:14.621562Z","end":"2026-02-10T21:30:14.756339Z","steps":["trace[510408978] 'range keys from in-memory index tree' (duration: 134.618225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:15.417825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.445954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:30:15.417922Z","caller":"traceutil/trace.go:171","msg":"trace[356180175] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"424.765411ms","start":"2026-02-10T21:30:14.99314Z","end":"2026-02-10T21:30:15.417905Z","steps":["trace[356180175] 'read index received' (duration: 286.194665ms)","trace[356180175] 'applied index is now lower than readState.Index' (duration: 138.569296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:15.418103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.952476ms","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-10T21:30:15.418139Z","caller":"traceutil/trace.go:171","msg":"trace[1719442088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"425.022007ms","start":"2026-02-10T21:30:14.993107Z","end":"2026-02-10T21:30:15.418129Z","steps":["trace[1719442088] 'agreement among raft nodes before linearized reading' (duration: 424.845553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:15.418173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:14.993091Z","time spent":"425.073879ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T21:30:18.096854Z","caller":"traceutil/trace.go:171","msg":"trace[1141024712] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"234.021357ms","start":"2026-02-10T21:30:17.862811Z","end":"2026-02-10T21:30:18.096832Z","steps":["trace[1141024712] 'read index received' (duration: 233.847012ms)","trace[1141024712] 'applied index is now lower than readState.Index' (duration: 173.495µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:18.096987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.15768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:18.09702Z","caller":"traceutil/trace.go:171","msg":"trace[755657536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"234.229811ms","start":"2026-02-10T21:30:17.862779Z","end":"2026-02-10T21:30:18.097009Z","steps":["trace[755657536] 'agreement among raft nodes before linearized reading' (duration: 234.140739ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:18.097351Z","caller":"traceutil/trace.go:171","msg":"trace[780256452] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"593.892182ms","start":"2026-02-10T21:30:17.503448Z","end":"2026-02-10T21:30:18.09734Z","steps":["trace[780256452] 'process raft request' (duration: 593.270548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:18.09743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:17.503439Z","time spent":"593.937644ms","remote":"127.0.0.1:39322","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-10T21:30:19.025912Z","caller":"traceutil/trace.go:171","msg":"trace[1249677048] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"434.792886ms","start":"2026-02-10T21:30:18.591077Z","end":"2026-02-10T21:30:19.02587Z","steps":["trace[1249677048] 'process raft request' (duration: 434.611821ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:19.032396Z","caller":"traceutil/trace.go:171","msg":"trace[846901605] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:953; }","duration":"441.073783ms","start":"2026-02-10T21:30:18.591306Z","end":"2026-02-10T21:30:19.032379Z","steps":["trace[846901605] 'read index received' (duration: 441.065573ms)","trace[846901605] 'applied index is now lower than readState.Index' (duration: 7.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:19.032617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.295948ms","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-10T21:30:19.032646Z","caller":"traceutil/trace.go:171","msg":"trace[214460334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"441.346669ms","start":"2026-02-10T21:30:18.591292Z","end":"2026-02-10T21:30:19.032638Z","steps":["trace[214460334] 'agreement among raft nodes before linearized reading' (duration: 441.185496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:19.032674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:18.591287Z","time spent":"441.38068ms","remote":"127.0.0.1:39322","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-10T21:30:19.032975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.605266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:30:19.033Z","caller":"traceutil/trace.go:171","msg":"trace[71769451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"441.635747ms","start":"2026-02-10T21:30:18.591356Z","end":"2026-02-10T21:30:19.032992Z","steps":["trace[71769451] 'agreement among raft nodes before linearized reading' (duration: 441.570005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:19.033018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:18.591354Z","time spent":"441.659347ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:30:19.033122Z","caller":"traceutil/trace.go:171","msg":"trace[3256911] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"440.53943ms","start":"2026-02-10T21:30:18.592575Z","end":"2026-02-10T21:30:19.033115Z","steps":["trace[3256911] 'process raft request' (duration: 440.294285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:19.033175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:18.592569Z","time spent":"440.571391ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:19.033268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.865444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:19.033289Z","caller":"traceutil/trace.go:171","msg":"trace[106778381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"168.925095ms","start":"2026-02-10T21:30:18.864358Z","end":"2026-02-10T21:30:19.033283Z","steps":["trace[106778381] 'agreement among raft nodes before linearized reading' (duration: 168.890634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:19.02991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:18.591066Z","time spent":"435.101632ms","remote":"127.0.0.1:39322","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-10T21:30:19.532275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.375744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:19.532381Z","caller":"traceutil/trace.go:171","msg":"trace[44846086] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"483.144335ms","start":"2026-02-10T21:30:19.049226Z","end":"2026-02-10T21:30:19.53237Z","steps":["trace[44846086] 'process raft request' (duration: 483.109424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:19.532416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:19.04921Z","time spent":"483.188827ms","remote":"127.0.0.1:39322","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-10T21:30:19.53252Z","caller":"traceutil/trace.go:171","msg":"trace[1160918080] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"487.275462ms","start":"2026-02-10T21:30:19.045221Z","end":"2026-02-10T21:30:19.532496Z","steps":["trace[1160918080] 'process raft request' (duration: 279.626791ms)","trace[1160918080] 'compare' (duration: 207.121549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:19.532627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:19.045203Z","time spent":"487.358194ms","remote":"127.0.0.1:39322","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-10T21:30:20.595017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.3587ms","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-10T21:30:20.595063Z","caller":"traceutil/trace.go:171","msg":"trace[948558587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"486.439691ms","start":"2026-02-10T21:30:20.108612Z","end":"2026-02-10T21:30:20.595052Z","steps":["trace[948558587] 'range keys from in-memory index tree' (duration: 486.243767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:20.595084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:20.108597Z","time spent":"486.482793ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:30:20.595082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.064072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:20.59516Z","caller":"traceutil/trace.go:171","msg":"trace[1438437676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"724.177135ms","start":"2026-02-10T21:30:19.870968Z","end":"2026-02-10T21:30:20.595146Z","steps":["trace[1438437676] 'range keys from in-memory index tree' (duration: 723.992641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:20.595187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:19.870951Z","time spent":"724.230626ms","remote":"127.0.0.1:39070","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-10T21:30:24.045396Z","caller":"traceutil/trace.go:171","msg":"trace[1458356069] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"180.414014ms","start":"2026-02-10T21:30:23.864949Z","end":"2026-02-10T21:30:24.045363Z","steps":["trace[1458356069] 'read index received' (duration: 180.189948ms)","trace[1458356069] 'applied index is now lower than readState.Index' (duration: 223.206µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:24.045724Z","caller":"traceutil/trace.go:171","msg":"trace[1368798888] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"188.281828ms","start":"2026-02-10T21:30:23.857413Z","end":"2026-02-10T21:30:24.045694Z","steps":["trace[1368798888] 'process raft request' (duration: 187.810847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:24.045952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.988207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:24.045977Z","caller":"traceutil/trace.go:171","msg":"trace[1390087908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"181.044629ms","start":"2026-02-10T21:30:23.864925Z","end":"2026-02-10T21:30:24.04597Z","steps":["trace[1390087908] 'agreement among raft nodes before linearized reading' (duration: 180.967967ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:24.946443Z","caller":"traceutil/trace.go:171","msg":"trace[1674169646] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"110.524212ms","start":"2026-02-10T21:30:24.835901Z","end":"2026-02-10T21:30:24.946426Z","steps":["trace[1674169646] 'process raft request' (duration: 109.256612ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:32.02092Z","caller":"traceutil/trace.go:171","msg":"trace[1269987177] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"398.698935ms","start":"2026-02-10T21:30:31.622201Z","end":"2026-02-10T21:30:32.0209Z","steps":["trace[1269987177] 'process raft request' (duration: 398.541611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:32.02105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:31.62219Z","time spent":"398.785717ms","remote":"127.0.0.1:39322","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-10T21:30:32.569895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.517549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:32.570061Z","caller":"traceutil/trace.go:171","msg":"trace[2094599618] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"343.594214ms","start":"2026-02-10T21:30:32.226449Z","end":"2026-02-10T21:30:32.570044Z","steps":["trace[2094599618] 'process raft request' (duration: 343.530313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:32.570126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:32.226418Z","time spent":"343.678186ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:32.570444Z","caller":"traceutil/trace.go:171","msg":"trace[523104723] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"732.089232ms","start":"2026-02-10T21:30:31.838343Z","end":"2026-02-10T21:30:32.570432Z","steps":["trace[523104723] 'process raft request' (duration: 496.967019ms)","trace[523104723] 'compare' (duration: 234.323275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:32.570503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:31.838324Z","time spent":"732.150643ms","remote":"127.0.0.1:39322","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-10T21:32:32.315183Z","caller":"traceutil/trace.go:171","msg":"trace[374521595] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"122.484448ms","start":"2026-02-10T21:32:32.192674Z","end":"2026-02-10T21:32:32.315158Z","steps":["trace[374521595] 'process raft request' (duration: 110.050847ms)","trace[374521595] 'compare' (duration: 12.343569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:32:36.002183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.421291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:32:36.002264Z","caller":"traceutil/trace.go:171","msg":"trace[349152881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1511; }","duration":"141.540933ms","start":"2026-02-10T21:32:35.860705Z","end":"2026-02-10T21:32:36.002246Z","steps":["trace[349152881] 'range keys from in-memory index tree' (duration: 141.369829ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:32:56.729619Z","caller":"traceutil/trace.go:171","msg":"trace[1148608730] linearizableReadLoop","detail":"{readStateIndex:1663; appliedIndex:1662; }","duration":"243.21308ms","start":"2026-02-10T21:32:56.486382Z","end":"2026-02-10T21:32:56.729595Z","steps":["trace[1148608730] 'read index received' (duration: 243.015205ms)","trace[1148608730] 'applied index is now lower than readState.Index' (duration: 197.204µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:32:56.72989Z","caller":"traceutil/trace.go:171","msg":"trace[1439645238] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"334.693971ms","start":"2026-02-10T21:32:56.395186Z","end":"2026-02-10T21:32:56.72988Z","steps":["trace[1439645238] 'process raft request' (duration: 334.269511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:32:56.72998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:32:56.395169Z","time spent":"334.757022ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:32:56.730154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.778532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-10T21:32:56.730177Z","caller":"traceutil/trace.go:171","msg":"trace[1249855864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1594; }","duration":"243.865465ms","start":"2026-02-10T21:32:56.486304Z","end":"2026-02-10T21:32:56.730169Z","steps":["trace[1249855864] 'agreement among raft nodes before linearized reading' (duration: 243.786372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:32:56.730281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.325882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:32:56.730299Z","caller":"traceutil/trace.go:171","msg":"trace[1985827081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1594; }","duration":"133.369733ms","start":"2026-02-10T21:32:56.596923Z","end":"2026-02-10T21:32:56.730293Z","steps":["trace[1985827081] 'agreement among raft nodes before linearized reading' (duration: 133.342202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:32:56.981311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.814515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:32:56.981448Z","caller":"traceutil/trace.go:171","msg":"trace[1708912449] linearizableReadLoop","detail":"{readStateIndex:1664; appliedIndex:1663; }","duration":"142.622701ms","start":"2026-02-10T21:32:56.83881Z","end":"2026-02-10T21:32:56.981433Z","steps":["trace[1708912449] 'read index received' (duration: 34.231µs)","trace[1708912449] 'applied index is now lower than readState.Index' (duration: 142.5872ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:32:56.981663Z","caller":"traceutil/trace.go:171","msg":"trace[1365036196] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"248.333189ms","start":"2026-02-10T21:32:56.733317Z","end":"2026-02-10T21:32:56.98165Z","steps":["trace[1365036196] 'process raft request' (duration: 75.103885ms)","trace[1365036196] 'compare' (duration: 172.305923ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:32:56.981898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.089402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-10T21:32:56.981929Z","caller":"traceutil/trace.go:171","msg":"trace[1468558020] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1595; }","duration":"143.147503ms","start":"2026-02-10T21:32:56.838771Z","end":"2026-02-10T21:32:56.981919Z","steps":["trace[1468558020] 'agreement among raft nodes before linearized reading' (duration: 143.051291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:32:56.982057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.32075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:32:56.982082Z","caller":"traceutil/trace.go:171","msg":"trace[308695576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1595; }","duration":"119.358941ms","start":"2026-02-10T21:32:56.862715Z","end":"2026-02-10T21:32:56.982074Z","steps":["trace[308695576] 'agreement among raft nodes before linearized reading' (duration: 119.32399ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:33:41.062038Z","caller":"traceutil/trace.go:171","msg":"trace[1907096465] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"104.669535ms","start":"2026-02-10T21:33:40.957347Z","end":"2026-02-10T21:33:41.062017Z","steps":["trace[1907096465] 'process raft request' (duration: 101.566001ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:33:43.453312Z","caller":"traceutil/trace.go:171","msg":"trace[325728897] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"149.743029ms","start":"2026-02-10T21:33:43.303543Z","end":"2026-02-10T21:33:43.453286Z","steps":["trace[325728897] 'process raft request' (duration: 149.616486ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:33:43.758172Z","caller":"traceutil/trace.go:171","msg":"trace[1484466492] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"383.286671ms","start":"2026-02-10T21:33:43.374861Z","end":"2026-02-10T21:33:43.758147Z","steps":["trace[1484466492] 'read index received' (duration: 79.09507ms)","trace[1484466492] 'applied index is now lower than readState.Index' (duration: 304.190701ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:33:43.758416Z","caller":"traceutil/trace.go:171","msg":"trace[1520480077] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"454.36766ms","start":"2026-02-10T21:33:43.304032Z","end":"2026-02-10T21:33:43.7584Z","steps":["trace[1520480077] 'process raft request' (duration: 383.793863ms)","trace[1520480077] 'compare' (duration: 70.184488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:33:43.758516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:33:43.30402Z","time spent":"454.425511ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:33:43.758928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.064298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-10T21:33:43.758968Z","caller":"traceutil/trace.go:171","msg":"trace[309761175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1810; }","duration":"384.125899ms","start":"2026-02-10T21:33:43.374829Z","end":"2026-02-10T21:33:43.758955Z","steps":["trace[309761175] 'agreement among raft nodes before linearized reading' (duration: 384.000476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:33:43.758998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:33:43.374813Z","time spent":"384.178041ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T21:33:43.759219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.6523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-10T21:33:43.759249Z","caller":"traceutil/trace.go:171","msg":"trace[1086497728] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1810; }","duration":"257.708952ms","start":"2026-02-10T21:33:43.50153Z","end":"2026-02-10T21:33:43.759239Z","steps":["trace[1086497728] 'agreement among raft nodes before linearized reading' (duration: 257.595159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:33:43.759497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.633658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-10T21:33:43.759525Z","caller":"traceutil/trace.go:171","msg":"trace[217851238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1810; }","duration":"196.686708ms","start":"2026-02-10T21:33:43.56283Z","end":"2026-02-10T21:33:43.759517Z","steps":["trace[217851238] 'agreement among raft nodes before linearized reading' (duration: 196.609017ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:33:43.989241Z","caller":"traceutil/trace.go:171","msg":"trace[174308711] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"221.036314ms","start":"2026-02-10T21:33:43.768185Z","end":"2026-02-10T21:33:43.989221Z","steps":["trace[174308711] 'process raft request' (duration: 220.928121ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:33:43.993779Z","caller":"traceutil/trace.go:171","msg":"trace[1606407142] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"224.489926ms","start":"2026-02-10T21:33:43.769278Z","end":"2026-02-10T21:33:43.993768Z","steps":["trace[1606407142] 'process raft request' (duration: 224.406234ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:33:43.994517Z","caller":"traceutil/trace.go:171","msg":"trace[307167399] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1890; }","duration":"131.462758ms","start":"2026-02-10T21:33:43.863033Z","end":"2026-02-10T21:33:43.994495Z","steps":["trace[307167399] 'read index received' (duration: 126.621724ms)","trace[307167399] 'applied index is now lower than readState.Index' (duration: 4.840014ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:33:43.994724Z","caller":"traceutil/trace.go:171","msg":"trace[1204927593] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"225.219643ms","start":"2026-02-10T21:33:43.769496Z","end":"2026-02-10T21:33:43.994715Z","steps":["trace[1204927593] 'process raft request' (duration: 224.25337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:33:43.994721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.671973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:33:43.994876Z","caller":"traceutil/trace.go:171","msg":"trace[939664041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1813; }","duration":"131.872787ms","start":"2026-02-10T21:33:43.86299Z","end":"2026-02-10T21:33:43.994863Z","steps":["trace[939664041] 'agreement among raft nodes before linearized reading' (duration: 131.670003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:33:47.791883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.977547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T21:33:47.791961Z","caller":"traceutil/trace.go:171","msg":"trace[1275087655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"669.127831ms","start":"2026-02-10T21:33:47.122816Z","end":"2026-02-10T21:33:47.791944Z","steps":["trace[1275087655] 'range keys from in-memory index tree' (duration: 668.827014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:33:47.792003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:33:47.122797Z","time spent":"669.197303ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:33:54.284869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.546809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-02-10T21:33:54.285017Z","caller":"traceutil/trace.go:171","msg":"trace[238268933] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1864; }","duration":"104.703613ms","start":"2026-02-10T21:33:54.180281Z","end":"2026-02-10T21:33:54.284985Z","steps":["trace[238268933] 'range keys from in-memory index tree' (duration: 104.497157ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:34:14.451399Z","caller":"traceutil/trace.go:171","msg":"trace[2011854707] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"107.870425ms","start":"2026-02-10T21:34:14.343509Z","end":"2026-02-10T21:34:14.451379Z","steps":["trace[2011854707] 'process raft request' (duration: 100.627465ms)"],"step_count":1}