{"level":"warn","ts":"2026-05-21T19:32:24.491701Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:32:24.491786Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-05-21T19:32:24.49185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:32:24.491865Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-21T19:32:24.491887Z","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-05-21T19:32:24.492704Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-05-21T19:32:24.492781Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-05-21T19:32:24.50075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.776796ms"} {"level":"info","ts":"2026-05-21T19:32:24.526707Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-05-21T19:32:24.526809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:32:24.526829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-05-21T19:32:24.526841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:32:24.526849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-05-21T19:32:24.526908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-05-21T19:32:24.537945Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:32:24.542574Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:32:24.545471Z","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-05-21T19:32:24.551381Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:32:24.551515Z","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-05-21T19:32:24.55155Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:32:24.551613Z","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-05-21T19:32:24.551625Z","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-05-21T19:32:24.552519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-05-21T19:32:24.55477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-05-21T19:32:24.562551Z","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-05-21T19:32:24.562698Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-21T19:32:24.562774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-05-21T19:32:24.563105Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:32:24.563134Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:32:25.028197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:32:25.028266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:32:25.028303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-05-21T19:32:25.028328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:32:25.028343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-21T19:32:25.028358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-05-21T19:32:25.028377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-05-21T19:32:25.029265Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:32:25.029303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:32:25.029298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:32:25.02941Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:25.030113Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:32:25.030149Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:32:25.030733Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:25.030825Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:25.030855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:32:25.030854Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-05-21T19:32:25.032697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T19:32:50.037756Z","caller":"traceutil/trace.go:171","msg":"trace[214508080] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"179.03286ms","start":"2026-05-21T19:32:49.8587Z","end":"2026-05-21T19:32:50.037733Z","steps":["trace[214508080] 'process raft request' (duration: 178.906387ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:00.717226Z","caller":"traceutil/trace.go:171","msg":"trace[2084987880] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"135.840444ms","start":"2026-05-21T19:33:00.581364Z","end":"2026-05-21T19:33:00.717204Z","steps":["trace[2084987880] 'read index received' (duration: 135.70498ms)","trace[2084987880] 'applied index is now lower than readState.Index' (duration: 134.524µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:00.71747Z","caller":"traceutil/trace.go:171","msg":"trace[1534901101] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"636.671964ms","start":"2026-05-21T19:33:00.080786Z","end":"2026-05-21T19:33:00.717458Z","steps":["trace[1534901101] 'process raft request' (duration: 636.049889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:00.717905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.898116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:00.718048Z","caller":"traceutil/trace.go:171","msg":"trace[1654015301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"114.064709ms","start":"2026-05-21T19:33:00.603961Z","end":"2026-05-21T19:33:00.718026Z","steps":["trace[1654015301] 'agreement among raft nodes before linearized reading' (duration: 113.859415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:00.717917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.080761Z","time spent":"636.742646ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:00.72174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.354164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:33:00.721829Z","caller":"traceutil/trace.go:171","msg":"trace[225084286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"140.486328ms","start":"2026-05-21T19:33:00.581315Z","end":"2026-05-21T19:33:00.721801Z","steps":["trace[225084286] 'agreement among raft nodes before linearized reading' (duration: 136.51159ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.260616Z","caller":"traceutil/trace.go:171","msg":"trace[1866715361] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"309.913931ms","start":"2026-05-21T19:33:00.950663Z","end":"2026-05-21T19:33:01.260577Z","steps":["trace[1866715361] 'process raft request' (duration: 309.760017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:01.260821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.95062Z","time spent":"310.081426ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:33:01.269488Z","caller":"traceutil/trace.go:171","msg":"trace[78373362] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"312.576505ms","start":"2026-05-21T19:33:00.956896Z","end":"2026-05-21T19:33:01.269473Z","steps":["trace[78373362] 'process raft request' (duration: 312.513634ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.26953Z","caller":"traceutil/trace.go:171","msg":"trace[768487406] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"301.502115ms","start":"2026-05-21T19:33:00.96801Z","end":"2026-05-21T19:33:01.269512Z","steps":["trace[768487406] 'process raft request' (duration: 301.426103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:01.269597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.95688Z","time spent":"312.665948ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:33:01.269689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.967993Z","time spent":"301.65801ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:33:01.269488Z","caller":"traceutil/trace.go:171","msg":"trace[1923655634] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"312.566335ms","start":"2026-05-21T19:33:00.956894Z","end":"2026-05-21T19:33:01.26946Z","steps":["trace[1923655634] 'process raft request' (duration: 312.422522ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.26972Z","caller":"traceutil/trace.go:171","msg":"trace[1017221381] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"300.563652ms","start":"2026-05-21T19:33:00.969139Z","end":"2026-05-21T19:33:01.269703Z","steps":["trace[1017221381] 'process raft request' (duration: 300.367307ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.269793Z","caller":"traceutil/trace.go:171","msg":"trace[397678230] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"299.63475ms","start":"2026-05-21T19:33:00.970145Z","end":"2026-05-21T19:33:01.26978Z","steps":["trace[397678230] 'process raft request' (duration: 299.429525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:01.269847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.956876Z","time spent":"312.877253ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:33:01.269886Z","caller":"traceutil/trace.go:171","msg":"trace[1528616587] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"297.997379ms","start":"2026-05-21T19:33:00.971881Z","end":"2026-05-21T19:33:01.269878Z","steps":["trace[1528616587] 'process raft request' (duration: 297.784264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:01.269898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.969122Z","time spent":"300.661825ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:33:01.26984Z","caller":"traceutil/trace.go:171","msg":"trace[1931523215] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"299.478186ms","start":"2026-05-21T19:33:00.970351Z","end":"2026-05-21T19:33:01.269829Z","steps":["trace[1931523215] 'process raft request' (duration: 299.258761ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.269651Z","caller":"traceutil/trace.go:171","msg":"trace[798136073] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"301.550366ms","start":"2026-05-21T19:33:00.968064Z","end":"2026-05-21T19:33:01.269614Z","steps":["trace[798136073] 'process raft request' (duration: 301.399892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:01.269995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:00.968061Z","time spent":"301.914975ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:33:01.270076Z","caller":"traceutil/trace.go:171","msg":"trace[2091214707] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"293.192971ms","start":"2026-05-21T19:33:00.976865Z","end":"2026-05-21T19:33:01.270058Z","steps":["trace[2091214707] 'process raft request' (duration: 292.908075ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.270182Z","caller":"traceutil/trace.go:171","msg":"trace[711017274] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"245.388672ms","start":"2026-05-21T19:33:01.024762Z","end":"2026-05-21T19:33:01.27015Z","steps":["trace[711017274] 'process raft request' (duration: 245.151126ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:01.270319Z","caller":"traceutil/trace.go:171","msg":"trace[1542440510] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"161.617323ms","start":"2026-05-21T19:33:01.108682Z","end":"2026-05-21T19:33:01.270299Z","steps":["trace[1542440510] 'process raft request' (duration: 161.402708ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:07.878552Z","caller":"traceutil/trace.go:171","msg":"trace[1228718408] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"799.413275ms","start":"2026-05-21T19:33:07.079104Z","end":"2026-05-21T19:33:07.878517Z","steps":["trace[1228718408] 'process raft request' (duration: 794.015163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:07.8789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:07.07907Z","time spent":"799.60376ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:07.897623Z","caller":"traceutil/trace.go:171","msg":"trace[1085739672] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"292.612577ms","start":"2026-05-21T19:33:07.604947Z","end":"2026-05-21T19:33:07.89756Z","steps":["trace[1085739672] 'read index received' (duration: 266.321484ms)","trace[1085739672] 'applied index is now lower than readState.Index' (duration: 26.289733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:07.897716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.757941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:07.897766Z","caller":"traceutil/trace.go:171","msg":"trace[656656207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"292.846063ms","start":"2026-05-21T19:33:07.60491Z","end":"2026-05-21T19:33:07.897756Z","steps":["trace[656656207] 'agreement among raft nodes before linearized reading' (duration: 292.770721ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:07.897834Z","caller":"traceutil/trace.go:171","msg":"trace[1169919319] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"763.370623ms","start":"2026-05-21T19:33:07.134437Z","end":"2026-05-21T19:33:07.897808Z","steps":["trace[1169919319] 'process raft request' (duration: 763.016375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:07.897963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:07.134409Z","time spent":"763.483516ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:08.086591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.206728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:33:08.086719Z","caller":"traceutil/trace.go:171","msg":"trace[1916990849] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"151.62463ms","start":"2026-05-21T19:33:07.935084Z","end":"2026-05-21T19:33:08.086709Z","steps":["trace[1916990849] 'process raft request' (duration: 151.590789ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:08.086755Z","caller":"traceutil/trace.go:171","msg":"trace[245158232] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"154.0991ms","start":"2026-05-21T19:33:07.932625Z","end":"2026-05-21T19:33:08.086724Z","steps":["trace[245158232] 'process raft request' (duration: 27.573484ms)","trace[245158232] 'compare' (duration: 126.008383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:08.715996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:08.217453Z","time spent":"498.527275ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T19:33:08.716783Z","caller":"traceutil/trace.go:171","msg":"trace[50084340] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"205.883317ms","start":"2026-05-21T19:33:08.510875Z","end":"2026-05-21T19:33:08.716758Z","steps":["trace[50084340] 'process raft request' (duration: 204.464272ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:08.867479Z","caller":"traceutil/trace.go:171","msg":"trace[558616559] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"102.968898ms","start":"2026-05-21T19:33:08.764476Z","end":"2026-05-21T19:33:08.867445Z","steps":["trace[558616559] 'read index received' (duration: 93.516978ms)","trace[558616559] 'applied index is now lower than readState.Index' (duration: 9.45068ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:08.867636Z","caller":"traceutil/trace.go:171","msg":"trace[1682985967] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"107.614632ms","start":"2026-05-21T19:33:08.759995Z","end":"2026-05-21T19:33:08.867609Z","steps":["trace[1682985967] 'process raft request' (duration: 97.985077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:08.867855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.360797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T19:33:08.867915Z","caller":"traceutil/trace.go:171","msg":"trace[1579778557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"103.4651ms","start":"2026-05-21T19:33:08.764435Z","end":"2026-05-21T19:33:08.8679Z","steps":["trace[1579778557] 'agreement among raft nodes before linearized reading' (duration: 103.209944ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:08.870353Z","caller":"traceutil/trace.go:171","msg":"trace[298109190] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"100.248801ms","start":"2026-05-21T19:33:08.770084Z","end":"2026-05-21T19:33:08.870333Z","steps":["trace[298109190] 'process raft request' (duration: 100.043716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:09.94047Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039162891s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:33:10.633854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.070929ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:10.634499Z","caller":"traceutil/trace.go:171","msg":"trace[1451576268] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:641; }","duration":"600.742855ms","start":"2026-05-21T19:33:10.033738Z","end":"2026-05-21T19:33:10.634481Z","steps":["trace[1451576268] 'range keys from in-memory index tree' (duration: 600.046317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:10.634575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.800692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:33:10.634782Z","caller":"traceutil/trace.go:171","msg":"trace[284510550] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.760440754s","start":"2026-05-21T19:33:08.874321Z","end":"2026-05-21T19:33:10.634762Z","steps":["trace[284510550] 'process raft request' (duration: 1.066359155s)","trace[284510550] 'compare' (duration: 693.652149ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:10.634871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:08.874305Z","time spent":"1.760518855s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:10.634933Z","caller":"traceutil/trace.go:171","msg":"trace[416694476] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"1.760572317s","start":"2026-05-21T19:33:08.874342Z","end":"2026-05-21T19:33:10.634915Z","steps":["trace[416694476] 'read index received' (duration: 27.052941ms)","trace[416694476] 'applied index is now lower than readState.Index' (duration: 1.733518336s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:10.635143Z","caller":"traceutil/trace.go:171","msg":"trace[1041704496] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.760760692s","start":"2026-05-21T19:33:08.874367Z","end":"2026-05-21T19:33:10.635127Z","steps":["trace[1041704496] 'process raft request' (duration: 1.760342032s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:10.635269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:08.874347Z","time spent":"1.760841153s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:10.635441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761090749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-21T19:33:10.635491Z","caller":"traceutil/trace.go:171","msg":"trace[164148956] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:643; }","duration":"1.761157851s","start":"2026-05-21T19:33:08.874319Z","end":"2026-05-21T19:33:10.635477Z","steps":["trace[164148956] 'agreement among raft nodes before linearized reading' (duration: 1.761067249s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:10.635525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:08.874305Z","time spent":"1.761211512s","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-21T19:33:10.635673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030732454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:10.635724Z","caller":"traceutil/trace.go:171","msg":"trace[1809360893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.030781895s","start":"2026-05-21T19:33:09.604925Z","end":"2026-05-21T19:33:10.635706Z","steps":["trace[1809360893] 'agreement among raft nodes before linearized reading' (duration: 1.03064154s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:10.635762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:09.604847Z","time spent":"1.030906908s","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:33:12.44797Z","caller":"traceutil/trace.go:171","msg":"trace[1351223529] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"199.216613ms","start":"2026-05-21T19:33:12.248725Z","end":"2026-05-21T19:33:12.447941Z","steps":["trace[1351223529] 'process raft request' (duration: 199.060139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.234746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457527797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:33:31.504139Z","caller":"traceutil/trace.go:171","msg":"trace[1877053759] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"772.21992ms","start":"2026-05-21T19:33:30.731896Z","end":"2026-05-21T19:33:31.504116Z","steps":["trace[1877053759] 'process raft request' (duration: 771.78714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.504277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:30.731879Z","time spent":"772.330963ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:31.741571Z","caller":"traceutil/trace.go:171","msg":"trace[354618358] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"700.362502ms","start":"2026-05-21T19:33:31.041182Z","end":"2026-05-21T19:33:31.741544Z","steps":["trace[354618358] 'process raft request' (duration: 700.123857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.741919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:31.041154Z","time spent":"700.496976ms","remote":"127.0.0.1:52326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5051,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:31.741967Z","caller":"traceutil/trace.go:171","msg":"trace[282329891] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:845; }","duration":"1.007871025s","start":"2026-05-21T19:33:30.734063Z","end":"2026-05-21T19:33:31.741934Z","steps":["trace[282329891] 'read index received' (duration: 769.658398ms)","trace[282329891] 'applied index is now lower than readState.Index' (duration: 238.211737ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:31.742015Z","caller":"traceutil/trace.go:171","msg":"trace[999288978] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"340.676784ms","start":"2026-05-21T19:33:31.401305Z","end":"2026-05-21T19:33:31.741981Z","steps":["trace[999288978] 'process raft request' (duration: 340.409958ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:31.742204Z","caller":"traceutil/trace.go:171","msg":"trace[1566940708] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"557.158039ms","start":"2026-05-21T19:33:31.18471Z","end":"2026-05-21T19:33:31.741868Z","steps":["trace[1566940708] 'process raft request' (duration: 556.7761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.742441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008326536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-21T19:33:31.742594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:31.401278Z","time spent":"340.9035ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:31.742593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.060899ms","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-05-21T19:33:31.742682Z","caller":"traceutil/trace.go:171","msg":"trace[1840229505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"1.008617913s","start":"2026-05-21T19:33:30.734004Z","end":"2026-05-21T19:33:31.742622Z","steps":["trace[1840229505] 'agreement among raft nodes before linearized reading' (duration: 1.008123081s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.742734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:30.733984Z","time spent":"1.008735016s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:33:31.742603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.894527ms","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-05-21T19:33:31.742719Z","caller":"traceutil/trace.go:171","msg":"trace[104619508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"692.222993ms","start":"2026-05-21T19:33:31.050474Z","end":"2026-05-21T19:33:31.742697Z","steps":["trace[104619508] 'agreement among raft nodes before linearized reading' (duration: 691.878045ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:31.742817Z","caller":"traceutil/trace.go:171","msg":"trace[396435359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"688.165774ms","start":"2026-05-21T19:33:31.054633Z","end":"2026-05-21T19:33:31.742799Z","steps":["trace[396435359] 'agreement among raft nodes before linearized reading' (duration: 687.800025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.742868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:31.054622Z","time spent":"688.235246ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:33:31.742865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:31.050463Z","time spent":"692.365146ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:33:31.743068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.575613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:31.743128Z","caller":"traceutil/trace.go:171","msg":"trace[666801239] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:818; }","duration":"404.32045ms","start":"2026-05-21T19:33:31.338796Z","end":"2026-05-21T19:33:31.743117Z","steps":["trace[666801239] 'agreement among raft nodes before linearized reading' (duration: 403.563752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:31.743154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:31.338771Z","time spent":"404.374781ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:33:31.743458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:31.184687Z","time spent":"558.719157ms","remote":"127.0.0.1:52380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:40.381656Z","caller":"traceutil/trace.go:171","msg":"trace[1177402984] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"112.904622ms","start":"2026-05-21T19:33:40.268721Z","end":"2026-05-21T19:33:40.381626Z","steps":["trace[1177402984] 'process raft request' (duration: 98.056999ms)","trace[1177402984] 'compare' (duration: 14.657418ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:40.382023Z","caller":"traceutil/trace.go:171","msg":"trace[1671176267] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"112.767158ms","start":"2026-05-21T19:33:40.269227Z","end":"2026-05-21T19:33:40.381994Z","steps":["trace[1671176267] 'process raft request' (duration: 112.358638ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:40.382237Z","caller":"traceutil/trace.go:171","msg":"trace[1519209284] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"108.064614ms","start":"2026-05-21T19:33:40.274123Z","end":"2026-05-21T19:33:40.382188Z","steps":["trace[1519209284] 'process raft request' (duration: 107.803408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.403608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.17158ms","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-05-21T19:34:00.403732Z","caller":"traceutil/trace.go:171","msg":"trace[748822358] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1008; }","duration":"138.350784ms","start":"2026-05-21T19:34:00.265363Z","end":"2026-05-21T19:34:00.403714Z","steps":["trace[748822358] 'count revisions from in-memory index tree' (duration: 138.092577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.404135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.907869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:00.404211Z","caller":"traceutil/trace.go:171","msg":"trace[1897338804] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1008; }","duration":"101.050262ms","start":"2026-05-21T19:34:00.303143Z","end":"2026-05-21T19:34:00.404193Z","steps":["trace[1897338804] 'count revisions from in-memory index tree' (duration: 100.852517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:04.752177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.445002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:04.75228Z","caller":"traceutil/trace.go:171","msg":"trace[295898217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"148.594445ms","start":"2026-05-21T19:34:04.603653Z","end":"2026-05-21T19:34:04.752247Z","steps":["trace[295898217] 'range keys from in-memory index tree' (duration: 148.294288ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:06.001102Z","caller":"traceutil/trace.go:171","msg":"trace[623292764] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"194.328403ms","start":"2026-05-21T19:34:05.806749Z","end":"2026-05-21T19:34:06.001077Z","steps":["trace[623292764] 'read index received' (duration: 194.143649ms)","trace[623292764] 'applied index is now lower than readState.Index' (duration: 184.014µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:06.001175Z","caller":"traceutil/trace.go:171","msg":"trace[1053436462] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"341.360911ms","start":"2026-05-21T19:34:05.659778Z","end":"2026-05-21T19:34:06.001139Z","steps":["trace[1053436462] 'process raft request' (duration: 341.103545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:06.001255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.479367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:06.001288Z","caller":"traceutil/trace.go:171","msg":"trace[1595616679] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1027; }","duration":"194.59434ms","start":"2026-05-21T19:34:05.806685Z","end":"2026-05-21T19:34:06.00128Z","steps":["trace[1595616679] 'agreement among raft nodes before linearized reading' (duration: 194.500668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:06.001346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:05.65976Z","time spent":"341.498334ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:06.567296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:07.068236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:07.408192Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.357042796s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:34:07.852133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.602411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:07.852382Z","caller":"traceutil/trace.go:171","msg":"trace[78542982] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"1.786114043s","start":"2026-05-21T19:34:06.066248Z","end":"2026-05-21T19:34:07.852362Z","steps":["trace[78542982] 'read index received' (duration: 1.342167313s)","trace[78542982] 'applied index is now lower than readState.Index' (duration: 443.94587ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:07.852505Z","caller":"traceutil/trace.go:171","msg":"trace[199879788] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.801440607s","start":"2026-05-21T19:34:06.051047Z","end":"2026-05-21T19:34:07.852488Z","steps":["trace[199879788] 'process raft request' (duration: 1.357377005s)","trace[199879788] 'compare' (duration: 442.980286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:07.852596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.051031Z","time spent":"1.801503049s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:07.852728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786467601s","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-05-21T19:34:07.852802Z","caller":"traceutil/trace.go:171","msg":"trace[147546296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1029; }","duration":"1.786596624s","start":"2026-05-21T19:34:06.066187Z","end":"2026-05-21T19:34:07.852784Z","steps":["trace[147546296] 'agreement among raft nodes before linearized reading' (duration: 1.786385999s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:07.852842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.066163Z","time spent":"1.786670876s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:07.85294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786692727s","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-05-21T19:34:07.852979Z","caller":"traceutil/trace.go:171","msg":"trace[105330292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1029; }","duration":"1.786779918s","start":"2026-05-21T19:34:06.066186Z","end":"2026-05-21T19:34:07.852966Z","steps":["trace[105330292] 'agreement among raft nodes before linearized reading' (duration: 1.786635125s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:07.853015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.066162Z","time spent":"1.78684575s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:34:07.853025Z","caller":"traceutil/trace.go:171","msg":"trace[652661042] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.36660698s","start":"2026-05-21T19:34:06.486372Z","end":"2026-05-21T19:34:07.852979Z","steps":["trace[652661042] 'process raft request' (duration: 1.365894862s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:07.853132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.486345Z","time spent":"1.366722053s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:07.853179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662716163s","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-05-21T19:34:07.853267Z","caller":"traceutil/trace.go:171","msg":"trace[1836136572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1029; }","duration":"1.662857326s","start":"2026-05-21T19:34:06.190383Z","end":"2026-05-21T19:34:07.853241Z","steps":["trace[1836136572] 'agreement among raft nodes before linearized reading' (duration: 1.66261732s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:07.853312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.19036Z","time spent":"1.662941939s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:07.8534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126067477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:07.853445Z","caller":"traceutil/trace.go:171","msg":"trace[902263114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"1.126153659s","start":"2026-05-21T19:34:06.727281Z","end":"2026-05-21T19:34:07.853435Z","steps":["trace[902263114] 'agreement among raft nodes before linearized reading' (duration: 1.126092897s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:07.853479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.727262Z","time spent":"1.12621129s","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:07.853507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248703186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:07.853566Z","caller":"traceutil/trace.go:171","msg":"trace[493147138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"1.248841579s","start":"2026-05-21T19:34:06.604711Z","end":"2026-05-21T19:34:07.853552Z","steps":["trace[493147138] 'agreement among raft nodes before linearized reading' (duration: 1.248734206s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:07.853601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:06.604683Z","time spent":"1.248910751s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:34:08.366788Z","caller":"traceutil/trace.go:171","msg":"trace[1545030096] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"504.895542ms","start":"2026-05-21T19:34:07.861868Z","end":"2026-05-21T19:34:08.366764Z","steps":["trace[1545030096] 'read index received' (duration: 31.766538ms)","trace[1545030096] 'applied index is now lower than readState.Index' (duration: 473.128044ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:08.367143Z","caller":"traceutil/trace.go:171","msg":"trace[1751795910] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"508.95634ms","start":"2026-05-21T19:34:07.858168Z","end":"2026-05-21T19:34:08.367125Z","steps":["trace[1751795910] 'process raft request' (duration: 508.405347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.36723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.858149Z","time spent":"509.018092ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:08.367387Z","caller":"traceutil/trace.go:171","msg":"trace[1654045973] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"508.641482ms","start":"2026-05-21T19:34:07.858738Z","end":"2026-05-21T19:34:08.36738Z","steps":["trace[1654045973] 'process raft request' (duration: 507.991537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.367426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.85872Z","time spent":"508.687884ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:08.367525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.65592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:08.367555Z","caller":"traceutil/trace.go:171","msg":"trace[1678086065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"505.706201ms","start":"2026-05-21T19:34:07.86184Z","end":"2026-05-21T19:34:08.367546Z","steps":["trace[1678086065] 'agreement among raft nodes before linearized reading' (duration: 505.66501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.367593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.861833Z","time spent":"505.737742ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:08.367905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.967499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-21T19:34:08.368018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.688822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:08.368017Z","caller":"traceutil/trace.go:171","msg":"trace[1710702886] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:1032; }","duration":"455.114012ms","start":"2026-05-21T19:34:07.912879Z","end":"2026-05-21T19:34:08.367993Z","steps":["trace[1710702886] 'agreement among raft nodes before linearized reading' (duration: 454.826265ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:08.368044Z","caller":"traceutil/trace.go:171","msg":"trace[2055867835] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1032; }","duration":"306.756423ms","start":"2026-05-21T19:34:08.061281Z","end":"2026-05-21T19:34:08.368037Z","steps":["trace[2055867835] 'agreement among raft nodes before linearized reading' (duration: 306.696772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.368078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.912868Z","time spent":"455.196265ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.47\" "} {"level":"warn","ts":"2026-05-21T19:34:08.368066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:08.061265Z","time spent":"306.796765ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:08.368195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.635439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:08.368214Z","caller":"traceutil/trace.go:171","msg":"trace[1356191604] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1032; }","duration":"281.674629ms","start":"2026-05-21T19:34:08.086533Z","end":"2026-05-21T19:34:08.368208Z","steps":["trace[1356191604] 'agreement among raft nodes before linearized reading' (duration: 281.645809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.368358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.170464ms","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-05-21T19:34:08.368391Z","caller":"traceutil/trace.go:171","msg":"trace[956955258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"363.242865ms","start":"2026-05-21T19:34:08.005138Z","end":"2026-05-21T19:34:08.368381Z","steps":["trace[956955258] 'agreement among raft nodes before linearized reading' (duration: 363.150703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.36842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:08.005119Z","time spent":"363.295136ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:34:09.6074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138203543s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-21T19:34:09.607766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:08.36931Z","time spent":"1.238452555s","remote":"127.0.0.1:52168","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T19:34:09.607843Z","caller":"traceutil/trace.go:171","msg":"trace[1387648759] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"1.235429302s","start":"2026-05-21T19:34:08.37238Z","end":"2026-05-21T19:34:09.607809Z","steps":["trace[1387648759] 'process raft request' (duration: 1.235133134s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:09.607616Z","caller":"traceutil/trace.go:171","msg":"trace[424565124] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"600.01843ms","start":"2026-05-21T19:34:09.007583Z","end":"2026-05-21T19:34:09.607601Z","steps":["trace[424565124] 'read index received' (duration: 103.761049ms)","trace[424565124] 'applied index is now lower than readState.Index' (duration: 496.256681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:09.608012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:08.37236Z","time spent":"1.235560785s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:09.608079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.483881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:09.608151Z","caller":"traceutil/trace.go:171","msg":"trace[1658706585] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1033; }","duration":"600.613213ms","start":"2026-05-21T19:34:09.007524Z","end":"2026-05-21T19:34:09.608137Z","steps":["trace[1658706585] 'agreement among raft nodes before linearized reading' (duration: 600.503321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:09.608192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.007496Z","time spent":"600.686886ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:09.678958Z","caller":"traceutil/trace.go:171","msg":"trace[405770579] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"262.42195ms","start":"2026-05-21T19:34:09.416523Z","end":"2026-05-21T19:34:09.678945Z","steps":["trace[405770579] 'process raft request' (duration: 262.169984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:10.181116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528281,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:10.682021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528281,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:10.700588Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021944059s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:34:10.709236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.60508ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:10.70933Z","caller":"traceutil/trace.go:171","msg":"trace[524777219] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1034; }","duration":"642.718543ms","start":"2026-05-21T19:34:10.066596Z","end":"2026-05-21T19:34:10.709315Z","steps":["trace[524777219] 'range keys from in-memory index tree' (duration: 642.584129ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:10.709493Z","caller":"traceutil/trace.go:171","msg":"trace[650382769] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"1.100707806s","start":"2026-05-21T19:34:09.608756Z","end":"2026-05-21T19:34:10.709463Z","steps":["trace[650382769] 'process raft request' (duration: 1.092023084s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:10.709563Z","caller":"traceutil/trace.go:171","msg":"trace[1086720051] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"850.556367ms","start":"2026-05-21T19:34:09.858968Z","end":"2026-05-21T19:34:10.709524Z","steps":["trace[1086720051] 'process raft request' (duration: 850.463234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:10.709599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029311569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:10.709638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.608741Z","time spent":"1.100829109s","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:10.709659Z","caller":"traceutil/trace.go:171","msg":"trace[1681330359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"1.029422913s","start":"2026-05-21T19:34:09.680225Z","end":"2026-05-21T19:34:10.709648Z","steps":["trace[1681330359] 'agreement among raft nodes before linearized reading' (duration: 1.02932129s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:10.709508Z","caller":"traceutil/trace.go:171","msg":"trace[1335827556] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1074; }","duration":"1.029220787s","start":"2026-05-21T19:34:09.680267Z","end":"2026-05-21T19:34:10.709488Z","steps":["trace[1335827556] 'read index received' (duration: 1.020527094s)","trace[1335827556] 'applied index is now lower than readState.Index' (duration: 8.692773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:10.70969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.680211Z","time spent":"1.029471123s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:10.709743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.858953Z","time spent":"850.6973ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:10.709949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.32353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-21T19:34:10.71Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.583793ms","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-05-21T19:34:10.710008Z","caller":"traceutil/trace.go:171","msg":"trace[241091084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"332.427222ms","start":"2026-05-21T19:34:10.377571Z","end":"2026-05-21T19:34:10.709998Z","steps":["trace[241091084] 'agreement among raft nodes before linearized reading' (duration: 332.296599ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:10.710022Z","caller":"traceutil/trace.go:171","msg":"trace[1706883840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"811.648194ms","start":"2026-05-21T19:34:09.898367Z","end":"2026-05-21T19:34:10.710015Z","steps":["trace[1706883840] 'agreement among raft nodes before linearized reading' (duration: 811.541652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:10.709997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.122258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:10.71006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.89834Z","time spent":"811.701266ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:34:10.710086Z","caller":"traceutil/trace.go:171","msg":"trace[1119837448] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1036; }","duration":"790.244991ms","start":"2026-05-21T19:34:09.919821Z","end":"2026-05-21T19:34:10.710066Z","steps":["trace[1119837448] 'agreement among raft nodes before linearized reading' (duration: 790.092897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:10.71004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:10.37754Z","time spent":"332.490844ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:10.71011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.747741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T19:34:10.710134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.91981Z","time spent":"790.313372ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:10.710171Z","caller":"traceutil/trace.go:171","msg":"trace[1007131362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"330.827033ms","start":"2026-05-21T19:34:10.379331Z","end":"2026-05-21T19:34:10.710158Z","steps":["trace[1007131362] 'agreement among raft nodes before linearized reading' (duration: 330.600237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:10.710208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:10.379325Z","time spent":"330.876064ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:10.709949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.561951ms","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-05-21T19:34:10.710306Z","caller":"traceutil/trace.go:171","msg":"trace[74751096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"851.93642ms","start":"2026-05-21T19:34:09.858338Z","end":"2026-05-21T19:34:10.710275Z","steps":["trace[74751096] 'agreement among raft nodes before linearized reading' (duration: 851.508179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:10.710339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:09.858315Z","time spent":"852.014462ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:34:11.753294Z","caller":"traceutil/trace.go:171","msg":"trace[464568388] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"138.133499ms","start":"2026-05-21T19:34:11.615133Z","end":"2026-05-21T19:34:11.753266Z","steps":["trace[464568388] 'process raft request' (duration: 137.993936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:32.405601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528472,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:34:32.815428Z","caller":"traceutil/trace.go:171","msg":"trace[766027182] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"910.607406ms","start":"2026-05-21T19:34:31.904795Z","end":"2026-05-21T19:34:32.815403Z","steps":["trace[766027182] 'read index received' (duration: 910.402681ms)","trace[766027182] 'applied index is now lower than readState.Index' (duration: 203.735µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:32.815513Z","caller":"traceutil/trace.go:171","msg":"trace[914665338] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"912.779539ms","start":"2026-05-21T19:34:31.90268Z","end":"2026-05-21T19:34:32.81546Z","steps":["trace[914665338] 'process raft request' (duration: 912.576364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:32.815604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.78852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:32.815646Z","caller":"traceutil/trace.go:171","msg":"trace[160609594] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1107; }","duration":"910.854181ms","start":"2026-05-21T19:34:31.904778Z","end":"2026-05-21T19:34:32.815632Z","steps":["trace[160609594] 'agreement among raft nodes before linearized reading' (duration: 910.753519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:32.815676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:31.902619Z","time spent":"912.972903ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:32.815684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:31.904704Z","time spent":"910.970914ms","remote":"127.0.0.1:52250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:32.815679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.479829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:32.815686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.461271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:34:32.815674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.053269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:32.815732Z","caller":"traceutil/trace.go:171","msg":"trace[1588872513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"210.590131ms","start":"2026-05-21T19:34:32.605129Z","end":"2026-05-21T19:34:32.815719Z","steps":["trace[1588872513] 'agreement among raft nodes before linearized reading' (duration: 210.472109ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:32.815741Z","caller":"traceutil/trace.go:171","msg":"trace[426414317] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1107; }","duration":"744.528393ms","start":"2026-05-21T19:34:32.0712Z","end":"2026-05-21T19:34:32.815728Z","steps":["trace[426414317] 'agreement among raft nodes before linearized reading' (duration: 744.372539ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:32.815762Z","caller":"traceutil/trace.go:171","msg":"trace[216895818] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1107; }","duration":"309.204043ms","start":"2026-05-21T19:34:32.50654Z","end":"2026-05-21T19:34:32.815744Z","steps":["trace[216895818] 'agreement among raft nodes before linearized reading' (duration: 309.056089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:32.815782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:32.071147Z","time spent":"744.626776ms","remote":"127.0.0.1:52656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:32.815804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:32.506518Z","time spent":"309.275315ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:33.137171Z","caller":"traceutil/trace.go:171","msg":"trace[125151193] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"240.059853ms","start":"2026-05-21T19:34:32.897078Z","end":"2026-05-21T19:34:33.137138Z","steps":["trace[125151193] 'read index received' (duration: 187.418275ms)","trace[125151193] 'applied index is now lower than readState.Index' (duration: 52.639998ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:33.137291Z","caller":"traceutil/trace.go:171","msg":"trace[474287135] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"254.921357ms","start":"2026-05-21T19:34:32.882357Z","end":"2026-05-21T19:34:33.137278Z","steps":["trace[474287135] 'process raft request' (duration: 202.203237ms)","trace[474287135] 'compare' (duration: 52.138616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:33.137605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.486223ms","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-05-21T19:34:33.137747Z","caller":"traceutil/trace.go:171","msg":"trace[660257459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"240.676968ms","start":"2026-05-21T19:34:32.897026Z","end":"2026-05-21T19:34:33.137703Z","steps":["trace[660257459] 'agreement among raft nodes before linearized reading' (duration: 240.33293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:33.182148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.307641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-21T19:34:33.182172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.432094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:33.182226Z","caller":"traceutil/trace.go:171","msg":"trace[538983794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1109; }","duration":"254.417263ms","start":"2026-05-21T19:34:32.927792Z","end":"2026-05-21T19:34:33.182209Z","steps":["trace[538983794] 'agreement among raft nodes before linearized reading' (duration: 254.200008ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:33.18226Z","caller":"traceutil/trace.go:171","msg":"trace[292223842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1109; }","duration":"254.556477ms","start":"2026-05-21T19:34:32.927681Z","end":"2026-05-21T19:34:33.182237Z","steps":["trace[292223842] 'agreement among raft nodes before linearized reading' (duration: 254.337332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:33.182724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.324475ms","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-05-21T19:34:33.182785Z","caller":"traceutil/trace.go:171","msg":"trace[436039757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"284.420897ms","start":"2026-05-21T19:34:32.898351Z","end":"2026-05-21T19:34:33.182772Z","steps":["trace[436039757] 'agreement among raft nodes before linearized reading' (duration: 283.595867ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:33.61694Z","caller":"traceutil/trace.go:171","msg":"trace[478176748] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"123.89832ms","start":"2026-05-21T19:34:33.493013Z","end":"2026-05-21T19:34:33.616911Z","steps":["trace[478176748] 'process raft request' (duration: 123.764887ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:34.973566Z","caller":"traceutil/trace.go:171","msg":"trace[917562451] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"149.271781ms","start":"2026-05-21T19:34:34.824265Z","end":"2026-05-21T19:34:34.973537Z","steps":["trace[917562451] 'process raft request' (duration: 149.059696ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:35.472172Z","caller":"traceutil/trace.go:171","msg":"trace[738381894] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"286.065208ms","start":"2026-05-21T19:34:35.186084Z","end":"2026-05-21T19:34:35.47215Z","steps":["trace[738381894] 'read index received' (duration: 210.257644ms)","trace[738381894] 'applied index is now lower than readState.Index' (duration: 75.806504ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:35.472407Z","caller":"traceutil/trace.go:171","msg":"trace[1970721084] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"330.674039ms","start":"2026-05-21T19:34:35.141724Z","end":"2026-05-21T19:34:35.472398Z","steps":["trace[1970721084] 'process raft request' (duration: 254.603309ms)","trace[1970721084] 'compare' (duration: 75.63785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:35.472487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.141705Z","time spent":"330.7244ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:35.472716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.496346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:35.472806Z","caller":"traceutil/trace.go:171","msg":"trace[2063462532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"254.616059ms","start":"2026-05-21T19:34:35.218169Z","end":"2026-05-21T19:34:35.472785Z","steps":["trace[2063462532] 'agreement among raft nodes before linearized reading' (duration: 254.31829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:35.472866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.785586ms","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-05-21T19:34:35.472903Z","caller":"traceutil/trace.go:171","msg":"trace[545887152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1115; }","duration":"286.851228ms","start":"2026-05-21T19:34:35.186045Z","end":"2026-05-21T19:34:35.472896Z","steps":["trace[545887152] 'agreement among raft nodes before linearized reading' (duration: 286.756196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:35.473012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.702071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T19:34:35.473057Z","caller":"traceutil/trace.go:171","msg":"trace[1141065313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1115; }","duration":"254.783573ms","start":"2026-05-21T19:34:35.218261Z","end":"2026-05-21T19:34:35.473044Z","steps":["trace[1141065313] 'agreement among raft nodes before linearized reading' (duration: 254.698871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:35.473934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.891836ms","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-05-21T19:34:35.474005Z","caller":"traceutil/trace.go:171","msg":"trace[2135991658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"254.989588ms","start":"2026-05-21T19:34:35.219Z","end":"2026-05-21T19:34:35.473989Z","steps":["trace[2135991658] 'agreement among raft nodes before linearized reading' (duration: 253.714197ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:35.634728Z","caller":"traceutil/trace.go:171","msg":"trace[235487018] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"157.607185ms","start":"2026-05-21T19:34:35.477083Z","end":"2026-05-21T19:34:35.63469Z","steps":["trace[235487018] 'process raft request' (duration: 157.514183ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:35.634887Z","caller":"traceutil/trace.go:171","msg":"trace[606940989] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"158.031285ms","start":"2026-05-21T19:34:35.476826Z","end":"2026-05-21T19:34:35.634857Z","steps":["trace[606940989] 'process raft request' (duration: 147.667993ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:35.634915Z","caller":"traceutil/trace.go:171","msg":"trace[867108279] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"157.981084ms","start":"2026-05-21T19:34:35.476895Z","end":"2026-05-21T19:34:35.634876Z","steps":["trace[867108279] 'process raft request' (duration: 157.643295ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:37.613293Z","caller":"traceutil/trace.go:171","msg":"trace[1082635163] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"418.365635ms","start":"2026-05-21T19:34:37.194904Z","end":"2026-05-21T19:34:37.61327Z","steps":["trace[1082635163] 'read index received' (duration: 418.294803ms)","trace[1082635163] 'applied index is now lower than readState.Index' (duration: 69.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:37.613362Z","caller":"traceutil/trace.go:171","msg":"trace[1119925591] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"632.150094ms","start":"2026-05-21T19:34:36.981181Z","end":"2026-05-21T19:34:37.613331Z","steps":["trace[1119925591] 'process raft request' (duration: 631.9516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:37.613466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.554809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:37.613516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:36.981159Z","time spent":"632.272407ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:37.613674Z","caller":"traceutil/trace.go:171","msg":"trace[852488144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1120; }","duration":"418.657821ms","start":"2026-05-21T19:34:37.194836Z","end":"2026-05-21T19:34:37.613494Z","steps":["trace[852488144] 'agreement among raft nodes before linearized reading' (duration: 418.550319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:37.613798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.194809Z","time spent":"418.907528ms","remote":"127.0.0.1:44718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:38.035304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.033668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:38.035591Z","caller":"traceutil/trace.go:171","msg":"trace[786729540] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"557.833836ms","start":"2026-05-21T19:34:37.477707Z","end":"2026-05-21T19:34:38.03554Z","steps":["trace[786729540] 'process raft request' (duration: 273.48079ms)","trace[786729540] 'compare' (duration: 283.905675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:38.035746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.477685Z","time spent":"557.982089ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:38.035726Z","caller":"traceutil/trace.go:171","msg":"trace[1735742965] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"422.037813ms","start":"2026-05-21T19:34:37.613362Z","end":"2026-05-21T19:34:38.0354Z","steps":["trace[1735742965] 'read index received' (duration: 137.77485ms)","trace[1735742965] 'applied index is now lower than readState.Index' (duration: 284.261583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:38.035887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.186714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:38.035933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.838219ms","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-05-21T19:34:38.036017Z","caller":"traceutil/trace.go:171","msg":"trace[1946774995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"535.959252ms","start":"2026-05-21T19:34:37.500041Z","end":"2026-05-21T19:34:38.036Z","steps":["trace[1946774995] 'agreement among raft nodes before linearized reading' (duration: 535.734375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.036064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.500024Z","time spent":"536.031223ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:38.036064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.293556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:38.036148Z","caller":"traceutil/trace.go:171","msg":"trace[540973777] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1121; }","duration":"746.45186ms","start":"2026-05-21T19:34:37.289675Z","end":"2026-05-21T19:34:38.036127Z","steps":["trace[540973777] 'agreement among raft nodes before linearized reading' (duration: 746.280596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.036196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.289653Z","time spent":"746.531662ms","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:38.035964Z","caller":"traceutil/trace.go:171","msg":"trace[600937471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"430.337818ms","start":"2026-05-21T19:34:37.605613Z","end":"2026-05-21T19:34:38.035951Z","steps":["trace[600937471] 'agreement among raft nodes before linearized reading' (duration: 430.156772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.036362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.417912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-21T19:34:38.036209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.261889ms","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-05-21T19:34:38.03643Z","caller":"traceutil/trace.go:171","msg":"trace[1438941113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1121; }","duration":"397.539565ms","start":"2026-05-21T19:34:37.638878Z","end":"2026-05-21T19:34:38.036417Z","steps":["trace[1438941113] 'agreement among raft nodes before linearized reading' (duration: 397.370761ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.036498Z","caller":"traceutil/trace.go:171","msg":"trace[697078933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1121; }","duration":"397.592917ms","start":"2026-05-21T19:34:37.638896Z","end":"2026-05-21T19:34:38.036489Z","steps":["trace[697078933] 'agreement among raft nodes before linearized reading' (duration: 397.198527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.036555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.638877Z","time spent":"397.665568ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:34:38.036511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.63886Z","time spent":"397.639007ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:38.036213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.910233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-21T19:34:38.03643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.60559Z","time spent":"430.817789ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:34:38.036692Z","caller":"traceutil/trace.go:171","msg":"trace[1470385441] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:1121; }","duration":"122.420255ms","start":"2026-05-21T19:34:37.914257Z","end":"2026-05-21T19:34:38.036677Z","steps":["trace[1470385441] 'agreement among raft nodes before linearized reading' (duration: 121.758199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.036222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.278459ms","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-05-21T19:34:38.03695Z","caller":"traceutil/trace.go:171","msg":"trace[612185667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"398.049638ms","start":"2026-05-21T19:34:37.638892Z","end":"2026-05-21T19:34:38.036942Z","steps":["trace[612185667] 'agreement among raft nodes before linearized reading' (duration: 397.101635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.036997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.638872Z","time spent":"398.115649ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:39.703719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272673261s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-21T19:34:39.703821Z","caller":"traceutil/trace.go:171","msg":"trace[266613690] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"1.664730622s","start":"2026-05-21T19:34:38.039074Z","end":"2026-05-21T19:34:39.703805Z","steps":["trace[266613690] 'read index received' (duration: 391.909187ms)","trace[266613690] 'applied index is now lower than readState.Index' (duration: 1.272819725s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:39.703889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.038768Z","time spent":"1.665118571s","remote":"127.0.0.1:52168","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:34:39.704107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665024219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:39.704136Z","caller":"traceutil/trace.go:171","msg":"trace[956730227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"1.66508001s","start":"2026-05-21T19:34:38.039046Z","end":"2026-05-21T19:34:39.704126Z","steps":["trace[956730227] 'agreement among raft nodes before linearized reading' (duration: 1.665021509s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.704162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.039032Z","time spent":"1.665125002s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:40.184186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.952551ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:40.184295Z","caller":"traceutil/trace.go:171","msg":"trace[115480689] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1121; }","duration":"151.071114ms","start":"2026-05-21T19:34:40.033206Z","end":"2026-05-21T19:34:40.184277Z","steps":["trace[115480689] 'range keys from in-memory index tree' (duration: 150.936621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:40.204324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528515,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:40.484282Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.053324628s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:34:40.484831Z","caller":"traceutil/trace.go:171","msg":"trace[297966568] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"2.443070522s","start":"2026-05-21T19:34:38.041654Z","end":"2026-05-21T19:34:40.484725Z","steps":["trace[297966568] 'process raft request' (duration: 2.442934829s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.484852Z","caller":"traceutil/trace.go:171","msg":"trace[970620163] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"2.443321548s","start":"2026-05-21T19:34:38.041429Z","end":"2026-05-21T19:34:40.48475Z","steps":["trace[970620163] 'process raft request' (duration: 2.443021801s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:40.48499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.041417Z","time spent":"2.443497742s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:40.485012Z","caller":"traceutil/trace.go:171","msg":"trace[197349716] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"2.442215832s","start":"2026-05-21T19:34:38.042772Z","end":"2026-05-21T19:34:40.484987Z","steps":["trace[197349716] 'process raft request' (duration: 2.442096739s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:40.485026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.041648Z","time spent":"2.443254557s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:40.485026Z","caller":"traceutil/trace.go:171","msg":"trace[1452665950] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"2.443244406s","start":"2026-05-21T19:34:38.04176Z","end":"2026-05-21T19:34:40.485004Z","steps":["trace[1452665950] 'process raft request' (duration: 2.442873747s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:40.485143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.042763Z","time spent":"2.442306214s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:40.485191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.041752Z","time spent":"2.443360109s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:41.564006Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079548958s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:34:41.713092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000884006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-21T19:34:41.713482Z","caller":"traceutil/trace.go:171","msg":"trace[54817022] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000995758s","start":"2026-05-21T19:34:39.71216Z","end":"2026-05-21T19:34:41.713156Z","steps":["trace[54817022] 'agreement among raft nodes before linearized reading' (duration: 2.000886416s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:41.713549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.712149Z","time spent":"2.001384917s","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/21 19:34:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-21T19:34:41.806327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.055841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:41.806562Z","caller":"traceutil/trace.go:171","msg":"trace[263411444] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1167; }","duration":"2.102698868s","start":"2026-05-21T19:34:39.703846Z","end":"2026-05-21T19:34:41.806545Z","steps":["trace[263411444] 'read index received' (duration: 780.609026ms)","trace[263411444] 'applied index is now lower than readState.Index' (duration: 1.322088851s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:41.806613Z","caller":"traceutil/trace.go:171","msg":"trace[1859501513] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"2.101502908s","start":"2026-05-21T19:34:39.704993Z","end":"2026-05-21T19:34:41.806496Z","steps":["trace[1859501513] 'process raft request' (duration: 1.8591888s)","trace[1859501513] 'compare' (duration: 241.861536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:41.806817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.704974Z","time spent":"2.101747864s","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:41.806836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.189216443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:34:41.806857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.896400793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:41.806883Z","caller":"traceutil/trace.go:171","msg":"trace[1088176901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1128; }","duration":"2.189295725s","start":"2026-05-21T19:34:39.617574Z","end":"2026-05-21T19:34:41.80687Z","steps":["trace[1088176901] 'agreement among raft nodes before linearized reading' (duration: 2.189130341s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:41.806894Z","caller":"traceutil/trace.go:171","msg":"trace[716045634] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1128; }","duration":"2.896483875s","start":"2026-05-21T19:34:38.910398Z","end":"2026-05-21T19:34:41.806882Z","steps":["trace[716045634] 'agreement among raft nodes before linearized reading' (duration: 2.896421083s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:41.806922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:39.61756Z","time spent":"2.189354206s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:34:41.806974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.604081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:41.807002Z","caller":"traceutil/trace.go:171","msg":"trace[1419503747] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1128; }","duration":"149.664171ms","start":"2026-05-21T19:34:41.657329Z","end":"2026-05-21T19:34:41.806993Z","steps":["trace[1419503747] 'agreement among raft nodes before linearized reading' (duration: 149.62506ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:41.807115Z","caller":"traceutil/trace.go:171","msg":"trace[2028292947] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"1.763994781s","start":"2026-05-21T19:34:40.043107Z","end":"2026-05-21T19:34:41.807102Z","steps":["trace[2028292947] 'process raft request' (duration: 1.763311083s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:41.807176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:40.043085Z","time spent":"1.764056433s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:41.807341Z","caller":"traceutil/trace.go:171","msg":"trace[383264756] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"1.681883253s","start":"2026-05-21T19:34:40.125446Z","end":"2026-05-21T19:34:41.807329Z","steps":["trace[383264756] 'process raft request' (duration: 1.681060934s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:41.807391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:40.125426Z","time spent":"1.681938474s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:41.806926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:38.910369Z","time spent":"2.896547925s","remote":"127.0.0.1:43668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:42.044082Z","caller":"traceutil/trace.go:171","msg":"trace[741138945] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1174; }","duration":"231.421681ms","start":"2026-05-21T19:34:41.812641Z","end":"2026-05-21T19:34:42.044063Z","steps":["trace[741138945] 'read index received' (duration: 147.653612ms)","trace[741138945] 'applied index is now lower than readState.Index' (duration: 83.767179ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:42.044453Z","caller":"traceutil/trace.go:171","msg":"trace[1456584223] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"232.169628ms","start":"2026-05-21T19:34:41.812253Z","end":"2026-05-21T19:34:42.044423Z","steps":["trace[1456584223] 'process raft request' (duration: 231.701637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:42.0446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.941804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-21T19:34:42.044841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.016392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:42.044921Z","caller":"traceutil/trace.go:171","msg":"trace[1915976852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"229.153596ms","start":"2026-05-21T19:34:41.815752Z","end":"2026-05-21T19:34:42.044906Z","steps":["trace[1915976852] 'agreement among raft nodes before linearized reading' (duration: 229.012503ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:42.044917Z","caller":"traceutil/trace.go:171","msg":"trace[1984221785] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1129; }","duration":"232.281422ms","start":"2026-05-21T19:34:41.812618Z","end":"2026-05-21T19:34:42.0449Z","steps":["trace[1984221785] 'agreement among raft nodes before linearized reading' (duration: 231.906272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:43.493818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.258083ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-21T19:34:45.943146Z","caller":"traceutil/trace.go:171","msg":"trace[501556336] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"110.77299ms","start":"2026-05-21T19:34:45.832349Z","end":"2026-05-21T19:34:45.943122Z","steps":["trace[501556336] 'process raft request' (duration: 110.622576ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:46.653141Z","caller":"traceutil/trace.go:171","msg":"trace[736147048] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"100.668379ms","start":"2026-05-21T19:34:46.55244Z","end":"2026-05-21T19:34:46.653108Z","steps":["trace[736147048] 'process raft request' (duration: 100.502845ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:46.820607Z","caller":"traceutil/trace.go:171","msg":"trace[389046328] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"162.03542ms","start":"2026-05-21T19:34:46.658544Z","end":"2026-05-21T19:34:46.820579Z","steps":["trace[389046328] 'process raft request' (duration: 123.177443ms)","trace[389046328] 'compare' (duration: 38.652482ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:46.820723Z","caller":"traceutil/trace.go:171","msg":"trace[1803883960] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"162.079511ms","start":"2026-05-21T19:34:46.658596Z","end":"2026-05-21T19:34:46.820675Z","steps":["trace[1803883960] 'process raft request' (duration: 161.917097ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:48.212896Z","caller":"traceutil/trace.go:171","msg":"trace[1832992535] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"278.393778ms","start":"2026-05-21T19:34:47.934474Z","end":"2026-05-21T19:34:48.212867Z","steps":["trace[1832992535] 'process raft request' (duration: 257.713509ms)","trace[1832992535] 'compare' (duration: 20.522135ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:48.237089Z","caller":"traceutil/trace.go:171","msg":"trace[1748107065] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1196; }","duration":"150.745798ms","start":"2026-05-21T19:34:48.086264Z","end":"2026-05-21T19:34:48.23701Z","steps":["trace[1748107065] 'read index received' (duration: 105.917026ms)","trace[1748107065] 'applied index is now lower than readState.Index' (duration: 44.827632ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:48.237141Z","caller":"traceutil/trace.go:171","msg":"trace[1060272087] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"288.175125ms","start":"2026-05-21T19:34:47.948938Z","end":"2026-05-21T19:34:48.237114Z","steps":["trace[1060272087] 'process raft request' (duration: 286.232008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:48.237344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.033444ms","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-05-21T19:34:48.237404Z","caller":"traceutil/trace.go:171","msg":"trace[1729158237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"151.180978ms","start":"2026-05-21T19:34:48.08621Z","end":"2026-05-21T19:34:48.237391Z","steps":["trace[1729158237] 'agreement among raft nodes before linearized reading' (duration: 150.976003ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:56.872122Z","caller":"traceutil/trace.go:171","msg":"trace[1600754439] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"173.076506ms","start":"2026-05-21T19:34:56.699022Z","end":"2026-05-21T19:34:56.872099Z","steps":["trace[1600754439] 'read index received' (duration: 172.84548ms)","trace[1600754439] 'applied index is now lower than readState.Index' (duration: 229.466µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:56.872177Z","caller":"traceutil/trace.go:171","msg":"trace[1460688184] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"194.698929ms","start":"2026-05-21T19:34:56.677434Z","end":"2026-05-21T19:34:56.872133Z","steps":["trace[1460688184] 'process raft request' (duration: 194.526315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:56.872479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.034704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:34:56.872491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.426606ms","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-05-21T19:34:56.872526Z","caller":"traceutil/trace.go:171","msg":"trace[83904499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"107.130836ms","start":"2026-05-21T19:34:56.765385Z","end":"2026-05-21T19:34:56.872515Z","steps":["trace[83904499] 'agreement among raft nodes before linearized reading' (duration: 106.875669ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:56.872595Z","caller":"traceutil/trace.go:171","msg":"trace[29758194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"173.585979ms","start":"2026-05-21T19:34:56.698985Z","end":"2026-05-21T19:34:56.872571Z","steps":["trace[29758194] 'agreement among raft nodes before linearized reading' (duration: 173.206529ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:57.010506Z","caller":"traceutil/trace.go:171","msg":"trace[52263695] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"131.911623ms","start":"2026-05-21T19:34:56.878569Z","end":"2026-05-21T19:34:57.010481Z","steps":["trace[52263695] 'process raft request' (duration: 131.413081ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:57.010712Z","caller":"traceutil/trace.go:171","msg":"trace[1736877935] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"127.741713ms","start":"2026-05-21T19:34:56.882875Z","end":"2026-05-21T19:34:57.010617Z","steps":["trace[1736877935] 'process raft request' (duration: 127.249252ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:57.115503Z","caller":"traceutil/trace.go:171","msg":"trace[1168487256] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"100.292ms","start":"2026-05-21T19:34:57.015191Z","end":"2026-05-21T19:34:57.115483Z","steps":["trace[1168487256] 'process raft request' (duration: 92.108173ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:57.696046Z","caller":"traceutil/trace.go:171","msg":"trace[155932377] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"125.158461ms","start":"2026-05-21T19:34:57.57084Z","end":"2026-05-21T19:34:57.695999Z","steps":["trace[155932377] 'process raft request' (duration: 124.985716ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:58.079056Z","caller":"traceutil/trace.go:171","msg":"trace[93464002] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"131.646397ms","start":"2026-05-21T19:34:57.947379Z","end":"2026-05-21T19:34:58.079025Z","steps":["trace[93464002] 'process raft request' (duration: 131.436142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:59.617216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.033876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:59.617278Z","caller":"traceutil/trace.go:171","msg":"trace[1603680036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1235; }","duration":"352.132688ms","start":"2026-05-21T19:34:59.265132Z","end":"2026-05-21T19:34:59.617265Z","steps":["trace[1603680036] 'count revisions from in-memory index tree' (duration: 351.968735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:59.617304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:59.265116Z","time spent":"352.181369ms","remote":"127.0.0.1:44686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:59.617291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.384528ms","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-05-21T19:34:59.617345Z","caller":"traceutil/trace.go:171","msg":"trace[875162919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"602.487521ms","start":"2026-05-21T19:34:59.014846Z","end":"2026-05-21T19:34:59.617334Z","steps":["trace[875162919] 'range keys from in-memory index tree' (duration: 602.236415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:59.617373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:59.014828Z","time spent":"602.537992ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:59.617474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.254354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T19:34:59.617494Z","caller":"traceutil/trace.go:171","msg":"trace[976264876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"556.308106ms","start":"2026-05-21T19:34:59.061179Z","end":"2026-05-21T19:34:59.617487Z","steps":["trace[976264876] 'range keys from in-memory index tree' (duration: 556.120781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:59.617511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:59.061162Z","time spent":"556.345397ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:59.617788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.962674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:59.61781Z","caller":"traceutil/trace.go:171","msg":"trace[419944239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"499.022585ms","start":"2026-05-21T19:34:59.11878Z","end":"2026-05-21T19:34:59.617803Z","steps":["trace[419944239] 'range keys from in-memory index tree' (duration: 498.883431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:59.617836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:59.118765Z","time spent":"499.067476ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:34:59.736993Z","caller":"traceutil/trace.go:171","msg":"trace[645578658] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"116.402649ms","start":"2026-05-21T19:34:59.620569Z","end":"2026-05-21T19:34:59.736972Z","steps":["trace[645578658] 'process raft request' (duration: 116.260205ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:59.748223Z","caller":"traceutil/trace.go:171","msg":"trace[453070841] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"127.293512ms","start":"2026-05-21T19:34:59.620916Z","end":"2026-05-21T19:34:59.74821Z","steps":["trace[453070841] 'process raft request' (duration: 127.247501ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:59.748224Z","caller":"traceutil/trace.go:171","msg":"trace[1423641080] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"127.396394ms","start":"2026-05-21T19:34:59.620804Z","end":"2026-05-21T19:34:59.748201Z","steps":["trace[1423641080] 'process raft request' (duration: 127.256721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:00.845055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:35:01.345798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:35:01.846978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457528769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:35:02.001347Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.701093456s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:35:02.16883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135405198s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:02.168922Z","caller":"traceutil/trace.go:171","msg":"trace[957264581] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1238; }","duration":"2.13550753s","start":"2026-05-21T19:35:00.033402Z","end":"2026-05-21T19:35:02.16891Z","steps":["trace[957264581] 'range keys from in-memory index tree' (duration: 2.135391028s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.169269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.669027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:02.16932Z","caller":"traceutil/trace.go:171","msg":"trace[1773394315] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"1.82473919s","start":"2026-05-21T19:35:00.344572Z","end":"2026-05-21T19:35:02.169311Z","steps":["trace[1773394315] 'read index received' (duration: 1.656906619s)","trace[1773394315] 'applied index is now lower than readState.Index' (duration: 167.831821ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:02.169394Z","caller":"traceutil/trace.go:171","msg":"trace[1766528916] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"1.869190572s","start":"2026-05-21T19:35:00.300176Z","end":"2026-05-21T19:35:02.169367Z","steps":["trace[1766528916] 'process raft request' (duration: 1.701367412s)","trace[1766528916] 'compare' (duration: 166.698313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:02.169481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:00.30016Z","time spent":"1.869282335s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:02.169496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824933014s","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-05-21T19:35:02.169752Z","caller":"traceutil/trace.go:171","msg":"trace[1581192500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"1.825225592s","start":"2026-05-21T19:35:00.344517Z","end":"2026-05-21T19:35:02.169742Z","steps":["trace[1581192500] 'agreement among raft nodes before linearized reading' (duration: 1.824849792s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.169782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:00.344496Z","time spent":"1.825276994s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:35:02.170396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593652392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:02.170428Z","caller":"traceutil/trace.go:171","msg":"trace[751744425] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1239; }","duration":"1.593688303s","start":"2026-05-21T19:35:00.576733Z","end":"2026-05-21T19:35:02.170421Z","steps":["trace[751744425] 'agreement among raft nodes before linearized reading' (duration: 1.593635682s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.170448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:00.576661Z","time spent":"1.593781146s","remote":"127.0.0.1:52232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:35:02.170599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.794844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:02.170714Z","caller":"traceutil/trace.go:171","msg":"trace[730112789] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1239; }","duration":"555.857116ms","start":"2026-05-21T19:35:01.614767Z","end":"2026-05-21T19:35:02.170624Z","steps":["trace[730112789] 'agreement among raft nodes before linearized reading' (duration: 555.807145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.170785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:01.614752Z","time spent":"556.020119ms","remote":"127.0.0.1:44742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:35:02.17101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.7202ms","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-05-21T19:35:02.171039Z","caller":"traceutil/trace.go:171","msg":"trace[1229453513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"419.763851ms","start":"2026-05-21T19:35:01.751266Z","end":"2026-05-21T19:35:02.17103Z","steps":["trace[1229453513] 'agreement among raft nodes before linearized reading' (duration: 419.693719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.171016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227636839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T19:35:02.171058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:01.751257Z","time spent":"419.796512ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:35:02.171087Z","caller":"traceutil/trace.go:171","msg":"trace[803270745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"1.227753612s","start":"2026-05-21T19:35:00.943315Z","end":"2026-05-21T19:35:02.171069Z","steps":["trace[803270745] 'agreement among raft nodes before linearized reading' (duration: 1.227541176s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.171074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565914143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:35:02.171128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:00.943294Z","time spent":"1.227826683s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:35:02.171148Z","caller":"traceutil/trace.go:171","msg":"trace[23391791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"1.566036116s","start":"2026-05-21T19:35:00.605094Z","end":"2026-05-21T19:35:02.17113Z","steps":["trace[23391791] 'agreement among raft nodes before linearized reading' (duration: 1.565883672s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.171174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.099619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T19:35:02.171187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:00.605071Z","time spent":"1.566108538s","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:35:02.171194Z","caller":"traceutil/trace.go:171","msg":"trace[92930325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"420.14294ms","start":"2026-05-21T19:35:01.751045Z","end":"2026-05-21T19:35:02.171188Z","steps":["trace[92930325] 'agreement among raft nodes before linearized reading' (duration: 420.088539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.171217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:01.751032Z","time spent":"420.181141ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:35:02.171302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.303686ms","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-05-21T19:35:02.171318Z","caller":"traceutil/trace.go:171","msg":"trace[2091038267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"430.366407ms","start":"2026-05-21T19:35:01.740946Z","end":"2026-05-21T19:35:02.171313Z","steps":["trace[2091038267] 'agreement among raft nodes before linearized reading' (duration: 430.320626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.171333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:01.740892Z","time spent":"430.437299ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:35:02.171349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.324574ms","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-05-21T19:35:02.171386Z","caller":"traceutil/trace.go:171","msg":"trace[597617969] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1239; }","duration":"797.401286ms","start":"2026-05-21T19:35:01.373971Z","end":"2026-05-21T19:35:02.171373Z","steps":["trace[597617969] 'agreement among raft nodes before linearized reading' (duration: 797.347154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:02.171415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:01.37395Z","time spent":"797.457997ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:35:19.242752Z","caller":"traceutil/trace.go:171","msg":"trace[1503771476] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"115.415055ms","start":"2026-05-21T19:35:19.127318Z","end":"2026-05-21T19:35:19.242733Z","steps":["trace[1503771476] 'process raft request' (duration: 115.279792ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:23.621447Z","caller":"traceutil/trace.go:171","msg":"trace[1485455225] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"390.048653ms","start":"2026-05-21T19:35:23.231368Z","end":"2026-05-21T19:35:23.621416Z","steps":["trace[1485455225] 'read index received' (duration: 389.801367ms)","trace[1485455225] 'applied index is now lower than readState.Index' (duration: 246.106µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:23.621561Z","caller":"traceutil/trace.go:171","msg":"trace[500048021] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"796.37938ms","start":"2026-05-21T19:35:22.825132Z","end":"2026-05-21T19:35:23.621511Z","steps":["trace[500048021] 'process raft request' (duration: 796.106664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:23.621631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.237568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:35:23.621676Z","caller":"traceutil/trace.go:171","msg":"trace[1137188572] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1345; }","duration":"390.3339ms","start":"2026-05-21T19:35:23.231328Z","end":"2026-05-21T19:35:23.621662Z","steps":["trace[1137188572] 'agreement among raft nodes before linearized reading' (duration: 390.208157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:23.621717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:23.231312Z","time spent":"390.392862ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:35:23.621743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:22.825106Z","time spent":"796.541734ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:24.491721Z","caller":"traceutil/trace.go:171","msg":"trace[267323586] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"127.15237ms","start":"2026-05-21T19:35:24.364542Z","end":"2026-05-21T19:35:24.491695Z","steps":["trace[267323586] 'process raft request' (duration: 126.903604ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:24.492133Z","caller":"traceutil/trace.go:171","msg":"trace[1078459197] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"126.306868ms","start":"2026-05-21T19:35:24.365776Z","end":"2026-05-21T19:35:24.492083Z","steps":["trace[1078459197] 'process raft request' (duration: 126.252657ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:24.492205Z","caller":"traceutil/trace.go:171","msg":"trace[2037998793] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"127.315323ms","start":"2026-05-21T19:35:24.364876Z","end":"2026-05-21T19:35:24.492192Z","steps":["trace[2037998793] 'process raft request' (duration: 126.77158ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:24.492305Z","caller":"traceutil/trace.go:171","msg":"trace[1309290419] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"127.393875ms","start":"2026-05-21T19:35:24.364883Z","end":"2026-05-21T19:35:24.492277Z","steps":["trace[1309290419] 'process raft request' (duration: 127.014186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:25.084488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.344348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:25.084583Z","caller":"traceutil/trace.go:171","msg":"trace[1801214166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"479.510933ms","start":"2026-05-21T19:35:24.605051Z","end":"2026-05-21T19:35:25.084562Z","steps":["trace[1801214166] 'range keys from in-memory index tree' (duration: 479.258057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:25.084633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:24.605033Z","time spent":"479.585355ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:35:25.097707Z","caller":"traceutil/trace.go:171","msg":"trace[688034736] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"129.086725ms","start":"2026-05-21T19:35:24.968608Z","end":"2026-05-21T19:35:25.097695Z","steps":["trace[688034736] 'read index received' (duration: 129.010383ms)","trace[688034736] 'applied index is now lower than readState.Index' (duration: 75.402µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:25.09774Z","caller":"traceutil/trace.go:171","msg":"trace[1156889565] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"238.551547ms","start":"2026-05-21T19:35:24.859164Z","end":"2026-05-21T19:35:25.097715Z","steps":["trace[1156889565] 'process raft request' (duration: 238.436705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:25.097819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.208029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:25.097845Z","caller":"traceutil/trace.go:171","msg":"trace[793111698] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1354; }","duration":"129.28202ms","start":"2026-05-21T19:35:24.968555Z","end":"2026-05-21T19:35:25.097837Z","steps":["trace[793111698] 'agreement among raft nodes before linearized reading' (duration: 129.217829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.207709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.242805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:26.207813Z","caller":"traceutil/trace.go:171","msg":"trace[1259837514] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.043594686s","start":"2026-05-21T19:35:25.164199Z","end":"2026-05-21T19:35:26.207794Z","steps":["trace[1259837514] 'process raft request' (duration: 441.200957ms)","trace[1259837514] 'compare' (duration: 602.116372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:26.207889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:25.164181Z","time spent":"1.043685909s","remote":"127.0.0.1:52222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:35:26.579192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.202914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:26.579404Z","caller":"traceutil/trace.go:171","msg":"trace[1406832600] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.412578693s","start":"2026-05-21T19:35:25.166802Z","end":"2026-05-21T19:35:26.57938Z","steps":["trace[1406832600] 'process raft request' (duration: 1.243107342s)","trace[1406832600] 'compare' (duration: 169.03845ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:26.579456Z","caller":"traceutil/trace.go:171","msg":"trace[822641701] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.390658583s","start":"2026-05-21T19:35:25.188789Z","end":"2026-05-21T19:35:26.579448Z","steps":["trace[822641701] 'process raft request' (duration: 1.390501509s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.579509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:25.188766Z","time spent":"1.390713824s","remote":"127.0.0.1:52222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:35:26.57951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:25.166776Z","time spent":"1.412680844s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:26.579421Z","caller":"traceutil/trace.go:171","msg":"trace[1251336645] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"214.21263ms","start":"2026-05-21T19:35:26.365195Z","end":"2026-05-21T19:35:26.579407Z","steps":["trace[1251336645] 'process raft request' (duration: 214.170369ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:26.579931Z","caller":"traceutil/trace.go:171","msg":"trace[1573153537] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1412; }","duration":"1.278305291s","start":"2026-05-21T19:35:25.301604Z","end":"2026-05-21T19:35:26.57991Z","steps":["trace[1573153537] 'read index received' (duration: 303.810242ms)","trace[1573153537] 'applied index is now lower than readState.Index' (duration: 974.493799ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:26.580103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.31882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:35:26.580112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278515347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-21T19:35:26.580141Z","caller":"traceutil/trace.go:171","msg":"trace[2093763094] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1359; }","duration":"801.410802ms","start":"2026-05-21T19:35:25.778721Z","end":"2026-05-21T19:35:26.580132Z","steps":["trace[2093763094] 'agreement among raft nodes before linearized reading' (duration: 801.32004ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:26.580154Z","caller":"traceutil/trace.go:171","msg":"trace[512131707] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1359; }","duration":"1.278598139s","start":"2026-05-21T19:35:25.301543Z","end":"2026-05-21T19:35:26.580141Z","steps":["trace[512131707] 'agreement among raft nodes before linearized reading' (duration: 1.278459675s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.580174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:25.778702Z","time spent":"801.463483ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:35:26.580187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:25.301521Z","time spent":"1.27865928s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T19:35:26.580252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.412125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:35:26.580307Z","caller":"traceutil/trace.go:171","msg":"trace[1135118396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1359; }","duration":"144.496687ms","start":"2026-05-21T19:35:26.435791Z","end":"2026-05-21T19:35:26.580288Z","steps":["trace[1135118396] 'agreement among raft nodes before linearized reading' (duration: 144.366134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.580371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.758029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-21T19:35:26.580408Z","caller":"traceutil/trace.go:171","msg":"trace[1913311187] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1359; }","duration":"351.79445ms","start":"2026-05-21T19:35:26.228604Z","end":"2026-05-21T19:35:26.580399Z","steps":["trace[1913311187] 'agreement among raft nodes before linearized reading' (duration: 351.718238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.580438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.228561Z","time spent":"351.867691ms","remote":"127.0.0.1:52212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-21T19:35:26.5805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.02621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:26.580535Z","caller":"traceutil/trace.go:171","msg":"trace[967757912] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1359; }","duration":"116.088692ms","start":"2026-05-21T19:35:26.464438Z","end":"2026-05-21T19:35:26.580527Z","steps":["trace[967757912] 'agreement among raft nodes before linearized reading' (duration: 116.038161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.580737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.654861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123434"} {"level":"info","ts":"2026-05-21T19:35:26.580779Z","caller":"traceutil/trace.go:171","msg":"trace[597888320] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1359; }","duration":"352.734222ms","start":"2026-05-21T19:35:26.228035Z","end":"2026-05-21T19:35:26.580769Z","steps":["trace[597888320] 'agreement among raft nodes before linearized reading' (duration: 352.552028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:26.580806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.228014Z","time spent":"352.785833ms","remote":"127.0.0.1:52198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":123458,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-21T19:35:27.013873Z","caller":"traceutil/trace.go:171","msg":"trace[734200446] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1416; }","duration":"426.948664ms","start":"2026-05-21T19:35:26.586906Z","end":"2026-05-21T19:35:27.013855Z","steps":["trace[734200446] 'read index received' (duration: 48.679394ms)","trace[734200446] 'applied index is now lower than readState.Index' (duration: 378.26826ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:27.013901Z","caller":"traceutil/trace.go:171","msg":"trace[87071374] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"427.763985ms","start":"2026-05-21T19:35:26.5861Z","end":"2026-05-21T19:35:27.013864Z","steps":["trace[87071374] 'process raft request' (duration: 427.5761ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:27.014008Z","caller":"traceutil/trace.go:171","msg":"trace[489355665] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"426.596666ms","start":"2026-05-21T19:35:26.587383Z","end":"2026-05-21T19:35:27.01398Z","steps":["trace[489355665] 'process raft request' (duration: 426.412391ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:27.013991Z","caller":"traceutil/trace.go:171","msg":"trace[2091822542] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"427.708624ms","start":"2026-05-21T19:35:26.586272Z","end":"2026-05-21T19:35:27.013981Z","steps":["trace[2091822542] 'process raft request' (duration: 427.457258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:27.014089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.586089Z","time spent":"427.911958ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:27.014123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.587366Z","time spent":"426.677228ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:27.014163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.586262Z","time spent":"427.798286ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:27.014179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.246002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-21T19:35:27.014093Z","caller":"traceutil/trace.go:171","msg":"trace[1327693380] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"428.056362ms","start":"2026-05-21T19:35:26.586004Z","end":"2026-05-21T19:35:27.014061Z","steps":["trace[1327693380] 'process raft request' (duration: 427.55533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:27.014461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.585991Z","time spent":"428.40407ms","remote":"127.0.0.1:52222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:35:27.014505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.426425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-21T19:35:27.014537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.745329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:27.014567Z","caller":"traceutil/trace.go:171","msg":"trace[1506415144] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1366; }","duration":"388.511456ms","start":"2026-05-21T19:35:26.626039Z","end":"2026-05-21T19:35:27.014551Z","steps":["trace[1506415144] 'agreement among raft nodes before linearized reading' (duration: 388.305521ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:27.01458Z","caller":"traceutil/trace.go:171","msg":"trace[401517976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"409.813471ms","start":"2026-05-21T19:35:26.604755Z","end":"2026-05-21T19:35:27.014568Z","steps":["trace[401517976] 'agreement among raft nodes before linearized reading' (duration: 409.747419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:27.014609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.626025Z","time spent":"388.575419ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T19:35:27.014611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.604743Z","time spent":"409.862212ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:35:27.014263Z","caller":"traceutil/trace.go:171","msg":"trace[1435590430] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1366; }","duration":"427.379284ms","start":"2026-05-21T19:35:26.586871Z","end":"2026-05-21T19:35:27.014251Z","steps":["trace[1435590430] 'agreement among raft nodes before linearized reading' (duration: 427.140058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:27.0147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.586849Z","time spent":"427.841546ms","remote":"127.0.0.1:52212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-21T19:35:27.014494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.833192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123434"} {"level":"info","ts":"2026-05-21T19:35:27.014743Z","caller":"traceutil/trace.go:171","msg":"trace[1335857375] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1366; }","duration":"427.087628ms","start":"2026-05-21T19:35:26.587643Z","end":"2026-05-21T19:35:27.01473Z","steps":["trace[1335857375] 'agreement among raft nodes before linearized reading' (duration: 426.695838ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:27.013918Z","caller":"traceutil/trace.go:171","msg":"trace[9445661] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"427.558889ms","start":"2026-05-21T19:35:26.586324Z","end":"2026-05-21T19:35:27.013883Z","steps":["trace[9445661] 'process raft request' (duration: 427.443127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:27.014815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.587638Z","time spent":"427.158799ms","remote":"127.0.0.1:52198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":123458,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-21T19:35:27.014841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.586317Z","time spent":"428.500042ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:27.014309Z","caller":"traceutil/trace.go:171","msg":"trace[942925796] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"426.564985ms","start":"2026-05-21T19:35:26.587737Z","end":"2026-05-21T19:35:27.014302Z","steps":["trace[942925796] 'process raft request' (duration: 426.085944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:27.014973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:26.587728Z","time spent":"427.223991ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:27.34858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.523737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:27.348689Z","caller":"traceutil/trace.go:171","msg":"trace[1514171864] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"328.212092ms","start":"2026-05-21T19:35:27.020457Z","end":"2026-05-21T19:35:27.348669Z","steps":["trace[1514171864] 'process raft request' (duration: 94.523231ms)","trace[1514171864] 'compare' (duration: 233.204229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:27.348747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:27.020435Z","time spent":"328.284754ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:29.553435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.787789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:35:29.553544Z","caller":"traceutil/trace.go:171","msg":"trace[535064816] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"534.47453ms","start":"2026-05-21T19:35:29.019052Z","end":"2026-05-21T19:35:29.553527Z","steps":["trace[535064816] 'read index received' (duration: 49.721µs)","trace[535064816] 'applied index is now lower than readState.Index' (duration: 534.423399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:29.553943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.891879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T19:35:29.553968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.244955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:35:29.553969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.135462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:35:29.55401Z","caller":"traceutil/trace.go:171","msg":"trace[855957632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"535.007743ms","start":"2026-05-21T19:35:29.01899Z","end":"2026-05-21T19:35:29.553998Z","steps":["trace[855957632] 'agreement among raft nodes before linearized reading' (duration: 534.827849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.554014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.524358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-21T19:35:29.554047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.018963Z","time spent":"535.075754ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:35:29.554035Z","caller":"traceutil/trace.go:171","msg":"trace[99613405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"534.337396ms","start":"2026-05-21T19:35:29.019681Z","end":"2026-05-21T19:35:29.554018Z","steps":["trace[99613405] 'agreement among raft nodes before linearized reading' (duration: 534.158152ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:29.55404Z","caller":"traceutil/trace.go:171","msg":"trace[1016893553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1370; }","duration":"534.260055ms","start":"2026-05-21T19:35:29.019764Z","end":"2026-05-21T19:35:29.554024Z","steps":["trace[1016893553] 'agreement among raft nodes before linearized reading' (duration: 534.091371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.554087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.019672Z","time spent":"534.406678ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:35:29.554087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.019758Z","time spent":"534.320516ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:35:29.554082Z","caller":"traceutil/trace.go:171","msg":"trace[722904775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"533.641521ms","start":"2026-05-21T19:35:29.020425Z","end":"2026-05-21T19:35:29.554066Z","steps":["trace[722904775] 'agreement among raft nodes before linearized reading' (duration: 533.426575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:29.554135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.020404Z","time spent":"533.722942ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:35:29.55432Z","caller":"traceutil/trace.go:171","msg":"trace[1898014147] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"905.146576ms","start":"2026-05-21T19:35:28.649131Z","end":"2026-05-21T19:35:29.554278Z","steps":["trace[1898014147] 'process raft request' (duration: 47.427034ms)","trace[1898014147] 'compare' (duration: 856.392829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:29.554462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:28.64911Z","time spent":"905.280029ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:30.104524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457529044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:35:30.605583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975767514457529044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:35:30.650181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091873602s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T19:35:30.675523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.694047ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:30.675585Z","caller":"traceutil/trace.go:171","msg":"trace[1357985609] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"1.117568362s","start":"2026-05-21T19:35:29.557998Z","end":"2026-05-21T19:35:30.675567Z","steps":["trace[1357985609] 'process raft request' (duration: 1.092586069s)","trace[1357985609] 'compare' (duration: 24.684036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:30.676954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.557971Z","time spent":"1.118837993s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:30.678705Z","caller":"traceutil/trace.go:171","msg":"trace[1618373314] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1370; }","duration":"641.83523ms","start":"2026-05-21T19:35:30.033799Z","end":"2026-05-21T19:35:30.675634Z","steps":["trace[1618373314] 'range keys from in-memory index tree' (duration: 641.676727ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:30.67896Z","caller":"traceutil/trace.go:171","msg":"trace[1441449383] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1428; }","duration":"1.07484952s","start":"2026-05-21T19:35:29.604091Z","end":"2026-05-21T19:35:30.67894Z","steps":["trace[1441449383] 'read index received' (duration: 1.046510886s)","trace[1441449383] 'applied index is now lower than readState.Index' (duration: 28.337574ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:30.679019Z","caller":"traceutil/trace.go:171","msg":"trace[660922574] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.120739848s","start":"2026-05-21T19:35:29.558262Z","end":"2026-05-21T19:35:30.679001Z","steps":["trace[660922574] 'process raft request' (duration: 1.120494112s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:30.679043Z","caller":"traceutil/trace.go:171","msg":"trace[825367022] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.120726958s","start":"2026-05-21T19:35:29.558304Z","end":"2026-05-21T19:35:30.679031Z","steps":["trace[825367022] 'process raft request' (duration: 1.120546654s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:30.679111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.558283Z","time spent":"1.120783239s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:30.679129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.558252Z","time spent":"1.12080646s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:35:30.67931Z","caller":"traceutil/trace.go:171","msg":"trace[1048395998] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.120513452s","start":"2026-05-21T19:35:29.558783Z","end":"2026-05-21T19:35:30.679297Z","steps":["trace[1048395998] 'process raft request' (duration: 1.120101722s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:30.679368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.55877Z","time spent":"1.120567534s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:35:30.679461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075371423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:30.679497Z","caller":"traceutil/trace.go:171","msg":"trace[763477888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"1.075443035s","start":"2026-05-21T19:35:29.604044Z","end":"2026-05-21T19:35:30.679487Z","steps":["trace[763477888] 'agreement among raft nodes before linearized reading' (duration: 1.075385174s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:30.679524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.604028Z","time spent":"1.075490377s","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:35:30.682239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.019916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:35:30.682271Z","caller":"traceutil/trace.go:171","msg":"trace[1998781323] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1375; }","duration":"797.097338ms","start":"2026-05-21T19:35:29.885167Z","end":"2026-05-21T19:35:30.682264Z","steps":["trace[1998781323] 'agreement among raft nodes before linearized reading' (duration: 797.044257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:30.682292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:29.885146Z","time spent":"797.140749ms","remote":"127.0.0.1:52414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:35:40.935485Z","caller":"traceutil/trace.go:171","msg":"trace[989968666] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"217.09573ms","start":"2026-05-21T19:35:40.718373Z","end":"2026-05-21T19:35:40.935469Z","steps":["trace[989968666] 'read index received' (duration: 216.936436ms)","trace[989968666] 'applied index is now lower than readState.Index' (duration: 158.654µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:40.935658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.270444ms","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-05-21T19:35:40.935686Z","caller":"traceutil/trace.go:171","msg":"trace[800840007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1435; }","duration":"217.348506ms","start":"2026-05-21T19:35:40.71833Z","end":"2026-05-21T19:35:40.935678Z","steps":["trace[800840007] 'agreement among raft nodes before linearized reading' (duration: 217.235583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:40.935793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.45591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:35:40.935834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.055077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:35:40.935866Z","caller":"traceutil/trace.go:171","msg":"trace[2123650012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1435; }","duration":"215.565263ms","start":"2026-05-21T19:35:40.720286Z","end":"2026-05-21T19:35:40.935851Z","steps":["trace[2123650012] 'agreement among raft nodes before linearized reading' (duration: 215.329217ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:40.935903Z","caller":"traceutil/trace.go:171","msg":"trace[450940547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1435; }","duration":"214.156479ms","start":"2026-05-21T19:35:40.721726Z","end":"2026-05-21T19:35:40.935883Z","steps":["trace[450940547] 'agreement among raft nodes before linearized reading' (duration: 213.948934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:40.935947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.268642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T19:35:40.936039Z","caller":"traceutil/trace.go:171","msg":"trace[2066623888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1435; }","duration":"214.410356ms","start":"2026-05-21T19:35:40.721607Z","end":"2026-05-21T19:35:40.936017Z","steps":["trace[2066623888] 'agreement among raft nodes before linearized reading' (duration: 214.144629ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:40.935572Z","caller":"traceutil/trace.go:171","msg":"trace[127498509] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"222.230363ms","start":"2026-05-21T19:35:40.713315Z","end":"2026-05-21T19:35:40.935545Z","steps":["trace[127498509] 'process raft request' (duration: 222.031599ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:55.132274Z","caller":"traceutil/trace.go:171","msg":"trace[1459958570] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"148.346929ms","start":"2026-05-21T19:35:54.983874Z","end":"2026-05-21T19:35:55.132221Z","steps":["trace[1459958570] 'process raft request' (duration: 96.864527ms)","trace[1459958570] 'compare' (duration: 51.296748ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:36:03.37015Z","caller":"traceutil/trace.go:171","msg":"trace[954575148] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"131.948204ms","start":"2026-05-21T19:36:03.238171Z","end":"2026-05-21T19:36:03.370119Z","steps":["trace[954575148] 'process raft request' (duration: 81.577049ms)","trace[954575148] 'compare' (duration: 50.106949ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:36:03.370192Z","caller":"traceutil/trace.go:171","msg":"trace[664280279] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"131.941333ms","start":"2026-05-21T19:36:03.238232Z","end":"2026-05-21T19:36:03.370174Z","steps":["trace[664280279] 'process raft request' (duration: 131.745588ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:03.370281Z","caller":"traceutil/trace.go:171","msg":"trace[532145094] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"131.987734ms","start":"2026-05-21T19:36:03.23824Z","end":"2026-05-21T19:36:03.370228Z","steps":["trace[532145094] 'process raft request' (duration: 131.78626ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:37:55.859334Z","caller":"traceutil/trace.go:171","msg":"trace[383708931] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"168.217972ms","start":"2026-05-21T19:37:55.69109Z","end":"2026-05-21T19:37:55.859308Z","steps":["trace[383708931] 'process raft request' (duration: 167.863254ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:38:00.708094Z","caller":"traceutil/trace.go:171","msg":"trace[647271055] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"102.325192ms","start":"2026-05-21T19:38:00.605748Z","end":"2026-05-21T19:38:00.708073Z","steps":["trace[647271055] 'read index received' (duration: 102.143958ms)","trace[647271055] 'applied index is now lower than readState.Index' (duration: 180.364µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:38:00.708145Z","caller":"traceutil/trace.go:171","msg":"trace[32459646] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"144.545194ms","start":"2026-05-21T19:38:00.563575Z","end":"2026-05-21T19:38:00.70812Z","steps":["trace[32459646] 'process raft request' (duration: 144.36605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:38:00.708212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.450005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:38:00.708248Z","caller":"traceutil/trace.go:171","msg":"trace[194429201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2079; }","duration":"102.521177ms","start":"2026-05-21T19:38:00.605714Z","end":"2026-05-21T19:38:00.708236Z","steps":["trace[194429201] 'agreement among raft nodes before linearized reading' (duration: 102.450375ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:38:01.348546Z","caller":"traceutil/trace.go:171","msg":"trace[391465772] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2167; }","duration":"235.059997ms","start":"2026-05-21T19:38:01.113459Z","end":"2026-05-21T19:38:01.348519Z","steps":["trace[391465772] 'read index received' (duration: 234.842492ms)","trace[391465772] 'applied index is now lower than readState.Index' (duration: 216.345µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:38:01.348592Z","caller":"traceutil/trace.go:171","msg":"trace[689947969] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"253.422646ms","start":"2026-05-21T19:38:01.095143Z","end":"2026-05-21T19:38:01.348565Z","steps":["trace[689947969] 'process raft request' (duration: 253.21651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:38:01.348751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.266153ms","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-05-21T19:38:01.348846Z","caller":"traceutil/trace.go:171","msg":"trace[1175182814] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2080; }","duration":"235.422087ms","start":"2026-05-21T19:38:01.113404Z","end":"2026-05-21T19:38:01.348826Z","steps":["trace[1175182814] 'agreement among raft nodes before linearized reading' (duration: 235.231292ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:39:18.068825Z","caller":"traceutil/trace.go:171","msg":"trace[370474901] linearizableReadLoop","detail":"{readStateIndex:2559; appliedIndex:2558; }","duration":"463.017217ms","start":"2026-05-21T19:39:17.605786Z","end":"2026-05-21T19:39:18.068803Z","steps":["trace[370474901] 'read index received' (duration: 462.877564ms)","trace[370474901] 'applied index is now lower than readState.Index' (duration: 138.523µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:39:18.069089Z","caller":"traceutil/trace.go:171","msg":"trace[1904771568] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"507.406721ms","start":"2026-05-21T19:39:17.56167Z","end":"2026-05-21T19:39:18.069077Z","steps":["trace[1904771568] 'process raft request' (duration: 506.983661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:39:18.069231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:39:17.561646Z","time spent":"507.516914ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:39:18.069422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.642913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:39:18.069464Z","caller":"traceutil/trace.go:171","msg":"trace[1865182423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2455; }","duration":"463.718385ms","start":"2026-05-21T19:39:17.605735Z","end":"2026-05-21T19:39:18.069453Z","steps":["trace[1865182423] 'agreement among raft nodes before linearized reading' (duration: 463.659063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:39:18.06977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.917897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-21T19:39:18.069868Z","caller":"traceutil/trace.go:171","msg":"trace[2095934244] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2455; }","duration":"441.05549ms","start":"2026-05-21T19:39:17.628788Z","end":"2026-05-21T19:39:18.069843Z","steps":["trace[2095934244] 'agreement among raft nodes before linearized reading' (duration: 440.773403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:39:18.070046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:39:17.628769Z","time spent":"441.201356ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T19:39:18.070129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:39:17.605714Z","time spent":"464.399352ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:39:18.070555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.278907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T19:39:18.070611Z","caller":"traceutil/trace.go:171","msg":"trace[1741672287] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:2455; }","duration":"139.365059ms","start":"2026-05-21T19:39:17.931228Z","end":"2026-05-21T19:39:18.070593Z","steps":["trace[1741672287] 'agreement among raft nodes before linearized reading' (duration: 139.245456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:39:18.071041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.544815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-21T19:39:18.071083Z","caller":"traceutil/trace.go:171","msg":"trace[1821110128] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2455; }","duration":"190.620447ms","start":"2026-05-21T19:39:17.880452Z","end":"2026-05-21T19:39:18.071072Z","steps":["trace[1821110128] 'agreement among raft nodes before linearized reading' (duration: 190.515604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:39:18.071245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.312593ms","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:531"} {"level":"info","ts":"2026-05-21T19:39:18.071297Z","caller":"traceutil/trace.go:171","msg":"trace[1384966004] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2455; }","duration":"290.395485ms","start":"2026-05-21T19:39:17.78089Z","end":"2026-05-21T19:39:18.071286Z","steps":["trace[1384966004] 'agreement among raft nodes before linearized reading' (duration: 290.243642ms)"],"step_count":1}