{"level":"warn","ts":"2026-07-16T02:39:39.138381Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T02:39:39.138495Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.75: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.75:2380","--initial-cluster=instance=https://199.19.213.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.75: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-07-16T02:39:39.138576Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T02:39:39.138593Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-07-16T02:39:39.138615Z","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-07-16T02:39:39.139285Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"]} {"level":"info","ts":"2026-07-16T02:39:39.139398Z","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.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.75: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-07-16T02:39:39.14216Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.515819ms"} {"level":"info","ts":"2026-07-16T02:39:39.147441Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"42c325ad8bcf8012","cluster-id":"1e2ab336c559df5e"} {"level":"info","ts":"2026-07-16T02:39:39.147529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T02:39:39.147559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 0"} {"level":"info","ts":"2026-07-16T02:39:39.147582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 42c325ad8bcf8012 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T02:39:39.147591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 1"} {"level":"info","ts":"2026-07-16T02:39:39.147634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"warn","ts":"2026-07-16T02:39:39.152834Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T02:39:39.154085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T02:39:39.155138Z","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-07-16T02:39:39.157081Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"42c325ad8bcf8012","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T02:39:39.157346Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"42c325ad8bcf8012","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T02:39:39.15761Z","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-07-16T02:39:39.157703Z","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-07-16T02:39:39.157715Z","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-07-16T02:39:39.158415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"info","ts":"2026-07-16T02:39:39.160392Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","added-peer-id":"42c325ad8bcf8012","added-peer-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-07-16T02:39:39.161768Z","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-07-16T02:39:39.161966Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-07-16T02:39:39.162014Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-07-16T02:39:39.162082Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"42c325ad8bcf8012","initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T02:39:39.162138Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T02:39:39.848599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T02:39:39.848731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T02:39:39.848769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgPreVoteResp from 42c325ad8bcf8012 at term 1"} {"level":"info","ts":"2026-07-16T02:39:39.848806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became candidate at term 2"} {"level":"info","ts":"2026-07-16T02:39:39.848818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgVoteResp from 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-07-16T02:39:39.84883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became leader at term 2"} {"level":"info","ts":"2026-07-16T02:39:39.848846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 42c325ad8bcf8012 elected leader 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-07-16T02:39:39.850701Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"42c325ad8bcf8012","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.75:2379]}","request-path":"/0/members/42c325ad8bcf8012/attributes","cluster-id":"1e2ab336c559df5e","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T02:39:39.850779Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T02:39:39.851069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T02:39:39.851407Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T02:39:39.852197Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T02:39:39.852275Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T02:39:39.852307Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T02:39:39.85288Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T02:39:39.852901Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T02:39:39.853199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T02:39:39.85486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.75:2379"} {"level":"warn","ts":"2026-07-16T02:39:43.084447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.61675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T02:39:43.084736Z","caller":"traceutil/trace.go:171","msg":"trace[439656203] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"429.800576ms","start":"2026-07-16T02:39:42.654911Z","end":"2026-07-16T02:39:43.084711Z","steps":["trace[439656203] 'process raft request' (duration: 429.710824ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:39:43.084677Z","caller":"traceutil/trace.go:171","msg":"trace[762720938] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"777.352152ms","start":"2026-07-16T02:39:42.307271Z","end":"2026-07-16T02:39:43.084623Z","steps":["trace[762720938] 'process raft request' (duration: 360.965604ms)","trace[762720938] 'compare' (duration: 415.382974ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:39:43.084864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:39:42.654876Z","time spent":"429.93553ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T02:39:43.085092Z","caller":"traceutil/trace.go:171","msg":"trace[808344168] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"719.833607ms","start":"2026-07-16T02:39:42.364935Z","end":"2026-07-16T02:39:43.084768Z","steps":["trace[808344168] 'read index received' (duration: 303.329357ms)","trace[808344168] 'applied index is now lower than readState.Index' (duration: 416.50069ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:39:43.085152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:39:42.307225Z","time spent":"777.715381ms","remote":"127.0.0.1:50192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2012,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T02:39:43.085382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.38965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T02:39:43.085504Z","caller":"traceutil/trace.go:171","msg":"trace[1458777525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"720.578395ms","start":"2026-07-16T02:39:42.364901Z","end":"2026-07-16T02:39:43.085479Z","steps":["trace[1458777525] 'agreement among raft nodes before linearized reading' (duration: 720.206737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:39:43.085549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:39:42.364888Z","time spent":"720.649827ms","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T02:40:17.192227Z","caller":"traceutil/trace.go:171","msg":"trace[854147943] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"108.597396ms","start":"2026-07-16T02:40:17.083576Z","end":"2026-07-16T02:40:17.192173Z","steps":["trace[854147943] 'process raft request' (duration: 38.259298ms)","trace[854147943] 'compare' (duration: 70.097142ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:40:17.221852Z","caller":"traceutil/trace.go:171","msg":"trace[757353816] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"113.604145ms","start":"2026-07-16T02:40:17.108224Z","end":"2026-07-16T02:40:17.221828Z","steps":["trace[757353816] 'process raft request' (duration: 113.354949ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:17.253158Z","caller":"traceutil/trace.go:171","msg":"trace[1378248072] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"130.20364ms","start":"2026-07-16T02:40:17.122925Z","end":"2026-07-16T02:40:17.253128Z","steps":["trace[1378248072] 'process raft request' (duration: 129.522003ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:17.628137Z","caller":"traceutil/trace.go:171","msg":"trace[411335441] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"331.70836ms","start":"2026-07-16T02:40:17.296387Z","end":"2026-07-16T02:40:17.628095Z","steps":["trace[411335441] 'process raft request' (duration: 330.567332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:40:17.628575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:40:17.296367Z","time spent":"331.869364ms","remote":"127.0.0.1:49898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:40:17.63839Z","caller":"traceutil/trace.go:171","msg":"trace[1804636978] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"290.660956ms","start":"2026-07-16T02:40:17.347705Z","end":"2026-07-16T02:40:17.638366Z","steps":["trace[1804636978] 'process raft request' (duration: 290.089113ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:17.638524Z","caller":"traceutil/trace.go:171","msg":"trace[1436330463] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"105.890522ms","start":"2026-07-16T02:40:17.532612Z","end":"2026-07-16T02:40:17.638503Z","steps":["trace[1436330463] 'process raft request' (duration: 105.661007ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:22.062833Z","caller":"traceutil/trace.go:171","msg":"trace[1482522334] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"111.404333ms","start":"2026-07-16T02:40:21.95139Z","end":"2026-07-16T02:40:22.062795Z","steps":["trace[1482522334] 'process raft request' (duration: 89.892222ms)","trace[1482522334] 'compare' (duration: 20.62347ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:40:22.335126Z","caller":"traceutil/trace.go:171","msg":"trace[1129434612] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"259.838463ms","start":"2026-07-16T02:40:22.075251Z","end":"2026-07-16T02:40:22.33509Z","steps":["trace[1129434612] 'process raft request' (duration: 259.616558ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:22.340373Z","caller":"traceutil/trace.go:171","msg":"trace[1748730211] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"209.064569ms","start":"2026-07-16T02:40:22.131283Z","end":"2026-07-16T02:40:22.340347Z","steps":["trace[1748730211] 'read index received' (duration: 203.62258ms)","trace[1748730211] 'applied index is now lower than readState.Index' (duration: 5.441079ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:40:22.340451Z","caller":"traceutil/trace.go:171","msg":"trace[145479837] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"221.238678ms","start":"2026-07-16T02:40:22.119186Z","end":"2026-07-16T02:40:22.340424Z","steps":["trace[145479837] 'process raft request' (duration: 221.009662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:40:22.340788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.485119ms","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-07-16T02:40:22.340841Z","caller":"traceutil/trace.go:171","msg":"trace[1938685307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"209.610392ms","start":"2026-07-16T02:40:22.131218Z","end":"2026-07-16T02:40:22.340828Z","steps":["trace[1938685307] 'agreement among raft nodes before linearized reading' (duration: 209.407807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:40:22.994207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.198713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-46vbz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-16T02:40:22.994335Z","caller":"traceutil/trace.go:171","msg":"trace[2075873077] range","detail":"{range_begin:/registry/pods/kube-system/cilium-46vbz; range_end:; response_count:1; response_revision:629; }","duration":"429.381867ms","start":"2026-07-16T02:40:22.564919Z","end":"2026-07-16T02:40:22.994301Z","steps":["trace[2075873077] 'range keys from in-memory index tree' (duration: 429.06209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:40:22.994393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:40:22.564895Z","time spent":"429.4857ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-46vbz\" "} {"level":"info","ts":"2026-07-16T02:40:23.026308Z","caller":"traceutil/trace.go:171","msg":"trace[884723730] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"245.667479ms","start":"2026-07-16T02:40:22.780581Z","end":"2026-07-16T02:40:23.026249Z","steps":["trace[884723730] 'process raft request' (duration: 244.576173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:40:38.133039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.042216ms","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-07-16T02:40:38.133181Z","caller":"traceutil/trace.go:171","msg":"trace[2065877635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"121.286972ms","start":"2026-07-16T02:40:38.011868Z","end":"2026-07-16T02:40:38.133155Z","steps":["trace[2065877635] 'range keys from in-memory index tree' (duration: 120.72928ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:38.355392Z","caller":"traceutil/trace.go:171","msg":"trace[846559442] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"179.474915ms","start":"2026-07-16T02:40:38.17589Z","end":"2026-07-16T02:40:38.355365Z","steps":["trace[846559442] 'read index received' (duration: 179.207519ms)","trace[846559442] 'applied index is now lower than readState.Index' (duration: 266.116µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:40:38.355448Z","caller":"traceutil/trace.go:171","msg":"trace[235814466] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"218.394861ms","start":"2026-07-16T02:40:38.137001Z","end":"2026-07-16T02:40:38.355396Z","steps":["trace[235814466] 'process raft request' (duration: 218.200316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:40:38.355682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.765383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-16T02:40:38.355696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.007839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-16T02:40:38.355744Z","caller":"traceutil/trace.go:171","msg":"trace[1398777850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"179.873145ms","start":"2026-07-16T02:40:38.17585Z","end":"2026-07-16T02:40:38.355724Z","steps":["trace[1398777850] 'agreement among raft nodes before linearized reading' (duration: 179.67599ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:40:38.35575Z","caller":"traceutil/trace.go:171","msg":"trace[945705737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"103.08284ms","start":"2026-07-16T02:40:38.252652Z","end":"2026-07-16T02:40:38.355735Z","steps":["trace[945705737] 'agreement among raft nodes before linearized reading' (duration: 102.954907ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:41:11.968059Z","caller":"traceutil/trace.go:171","msg":"trace[659332694] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"104.523064ms","start":"2026-07-16T02:41:11.863481Z","end":"2026-07-16T02:41:11.968004Z","steps":["trace[659332694] 'process raft request' (duration: 104.288139ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:41:56.946466Z","caller":"traceutil/trace.go:171","msg":"trace[1231468440] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"129.159849ms","start":"2026-07-16T02:41:56.817274Z","end":"2026-07-16T02:41:56.946434Z","steps":["trace[1231468440] 'read index received' (duration: 128.959344ms)","trace[1231468440] 'applied index is now lower than readState.Index' (duration: 199.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:41:56.946781Z","caller":"traceutil/trace.go:171","msg":"trace[1689625273] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"135.119111ms","start":"2026-07-16T02:41:56.811648Z","end":"2026-07-16T02:41:56.946767Z","steps":["trace[1689625273] 'process raft request' (duration: 134.65782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:56.947079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.781304ms","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-07-16T02:41:56.947113Z","caller":"traceutil/trace.go:171","msg":"trace[362483194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"129.852296ms","start":"2026-07-16T02:41:56.81725Z","end":"2026-07-16T02:41:56.947102Z","steps":["trace[362483194] 'agreement among raft nodes before linearized reading' (duration: 129.709213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:56.948097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.74112ms","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-07-16T02:41:56.948146Z","caller":"traceutil/trace.go:171","msg":"trace[268279653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"123.806732ms","start":"2026-07-16T02:41:56.824328Z","end":"2026-07-16T02:41:56.948135Z","steps":["trace[268279653] 'agreement among raft nodes before linearized reading' (duration: 123.691439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:56.948828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.976957ms","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-07-16T02:41:56.948879Z","caller":"traceutil/trace.go:171","msg":"trace[1269649473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"127.040629ms","start":"2026-07-16T02:41:56.821827Z","end":"2026-07-16T02:41:56.948868Z","steps":["trace[1269649473] 'agreement among raft nodes before linearized reading' (duration: 126.920716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:56.947284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.11559ms","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-07-16T02:41:56.949217Z","caller":"traceutil/trace.go:171","msg":"trace[1893790176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"129.058327ms","start":"2026-07-16T02:41:56.820148Z","end":"2026-07-16T02:41:56.949206Z","steps":["trace[1893790176] 'agreement among raft nodes before linearized reading' (duration: 127.08002ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:41:57.247375Z","caller":"traceutil/trace.go:171","msg":"trace[1334953699] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"295.215509ms","start":"2026-07-16T02:41:56.952126Z","end":"2026-07-16T02:41:57.247342Z","steps":["trace[1334953699] 'process raft request' (duration: 279.855213ms)","trace[1334953699] 'compare' (duration: 15.057549ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:41:57.254559Z","caller":"traceutil/trace.go:171","msg":"trace[2000955615] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"300.823983ms","start":"2026-07-16T02:41:56.953712Z","end":"2026-07-16T02:41:57.254536Z","steps":["trace[2000955615] 'process raft request' (duration: 300.670259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:57.254727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:41:56.953691Z","time spent":"300.936465ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:41:57.254814Z","caller":"traceutil/trace.go:171","msg":"trace[726094175] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"301.017097ms","start":"2026-07-16T02:41:56.953785Z","end":"2026-07-16T02:41:57.254803Z","steps":["trace[726094175] 'process raft request' (duration: 300.72108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:57.254929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:41:56.953778Z","time spent":"301.066338ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:41:57.25499Z","caller":"traceutil/trace.go:171","msg":"trace[1186822710] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1260; }","duration":"208.090911ms","start":"2026-07-16T02:41:57.046857Z","end":"2026-07-16T02:41:57.254948Z","steps":["trace[1186822710] 'read index received' (duration: 185.133214ms)","trace[1186822710] 'applied index is now lower than readState.Index' (duration: 22.955657ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:41:57.255058Z","caller":"traceutil/trace.go:171","msg":"trace[1642344418] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"301.038857ms","start":"2026-07-16T02:41:56.953983Z","end":"2026-07-16T02:41:57.255022Z","steps":["trace[1642344418] 'process raft request' (duration: 300.75537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:41:57.255228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:41:56.953975Z","time spent":"301.166951ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:41:57.255107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.233875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:41:57.25531Z","caller":"traceutil/trace.go:171","msg":"trace[1966194716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"208.486001ms","start":"2026-07-16T02:41:57.046815Z","end":"2026-07-16T02:41:57.255301Z","steps":["trace[1966194716] 'agreement among raft nodes before linearized reading' (duration: 208.231605ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:42:13.703895Z","caller":"traceutil/trace.go:171","msg":"trace[360697671] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"219.839802ms","start":"2026-07-16T02:42:13.484017Z","end":"2026-07-16T02:42:13.703857Z","steps":["trace[360697671] 'read index received' (duration: 168.57562ms)","trace[360697671] 'applied index is now lower than readState.Index' (duration: 51.262862ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:42:13.704018Z","caller":"traceutil/trace.go:171","msg":"trace[215181364] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"220.049947ms","start":"2026-07-16T02:42:13.483922Z","end":"2026-07-16T02:42:13.703971Z","steps":["trace[215181364] 'process raft request' (duration: 168.720373ms)","trace[215181364] 'compare' (duration: 50.938915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:42:13.704413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.367285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-16T02:42:13.704512Z","caller":"traceutil/trace.go:171","msg":"trace[1340464785] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1293; }","duration":"220.532288ms","start":"2026-07-16T02:42:13.483967Z","end":"2026-07-16T02:42:13.704499Z","steps":["trace[1340464785] 'agreement among raft nodes before linearized reading' (duration: 220.088118ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:42:14.039913Z","caller":"traceutil/trace.go:171","msg":"trace[1375278799] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"265.399728ms","start":"2026-07-16T02:42:13.774479Z","end":"2026-07-16T02:42:14.039878Z","steps":["trace[1375278799] 'read index received' (duration: 241.719404ms)","trace[1375278799] 'applied index is now lower than readState.Index' (duration: 23.679284ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:42:14.040003Z","caller":"traceutil/trace.go:171","msg":"trace[1826716622] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"330.402638ms","start":"2026-07-16T02:42:13.709553Z","end":"2026-07-16T02:42:14.039955Z","steps":["trace[1826716622] 'process raft request' (duration: 306.677822ms)","trace[1826716622] 'compare' (duration: 23.283826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:42:14.040146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.663844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-07-16T02:42:14.040181Z","caller":"traceutil/trace.go:171","msg":"trace[1013912507] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:1294; }","duration":"265.769186ms","start":"2026-07-16T02:42:13.774404Z","end":"2026-07-16T02:42:14.040173Z","steps":["trace[1013912507] 'agreement among raft nodes before linearized reading' (duration: 265.611813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:14.040168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:42:13.70953Z","time spent":"330.540732ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:42:14.040342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.210179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:42:14.040516Z","caller":"traceutil/trace.go:171","msg":"trace[1144998391] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1294; }","duration":"183.570267ms","start":"2026-07-16T02:42:13.856923Z","end":"2026-07-16T02:42:14.040493Z","steps":["trace[1144998391] 'agreement among raft nodes before linearized reading' (duration: 183.196308ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:42:27.123458Z","caller":"traceutil/trace.go:171","msg":"trace[1389909513] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"224.408941ms","start":"2026-07-16T02:42:26.899012Z","end":"2026-07-16T02:42:27.123421Z","steps":["trace[1389909513] 'process raft request' (duration: 224.243967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:27.462458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.774893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:42:27.462546Z","caller":"traceutil/trace.go:171","msg":"trace[1165112392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"337.956317ms","start":"2026-07-16T02:42:27.124572Z","end":"2026-07-16T02:42:27.462528Z","steps":["trace[1165112392] 'range keys from in-memory index tree' (duration: 337.65666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:27.462588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:42:27.124539Z","time spent":"338.039899ms","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T02:42:27.575079Z","caller":"traceutil/trace.go:171","msg":"trace[1515847986] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"134.950487ms","start":"2026-07-16T02:42:27.440101Z","end":"2026-07-16T02:42:27.575051Z","steps":["trace[1515847986] 'read index received' (duration: 134.678211ms)","trace[1515847986] 'applied index is now lower than readState.Index' (duration: 270.786µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:42:27.575381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.259045ms","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-07-16T02:42:27.575442Z","caller":"traceutil/trace.go:171","msg":"trace[257786109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1382; }","duration":"135.406878ms","start":"2026-07-16T02:42:27.440024Z","end":"2026-07-16T02:42:27.575431Z","steps":["trace[257786109] 'agreement among raft nodes before linearized reading' (duration: 135.178083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:27.575564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.972351ms","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-07-16T02:42:27.575387Z","caller":"traceutil/trace.go:171","msg":"trace[380032983] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"445.889411ms","start":"2026-07-16T02:42:27.129373Z","end":"2026-07-16T02:42:27.575262Z","steps":["trace[380032983] 'process raft request' (duration: 445.507092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:27.575727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.961419ms","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-07-16T02:42:27.575759Z","caller":"traceutil/trace.go:171","msg":"trace[1832031346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1382; }","duration":"117.00742ms","start":"2026-07-16T02:42:27.458744Z","end":"2026-07-16T02:42:27.575751Z","steps":["trace[1832031346] 'agreement among raft nodes before linearized reading' (duration: 116.927388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:27.575749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.288543ms","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-07-16T02:42:27.575948Z","caller":"traceutil/trace.go:171","msg":"trace[679694781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"116.530179ms","start":"2026-07-16T02:42:27.459374Z","end":"2026-07-16T02:42:27.575904Z","steps":["trace[679694781] 'agreement among raft nodes before linearized reading' (duration: 116.045257ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:42:27.575647Z","caller":"traceutil/trace.go:171","msg":"trace[982819508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1382; }","duration":"120.096754ms","start":"2026-07-16T02:42:27.455532Z","end":"2026-07-16T02:42:27.575629Z","steps":["trace[982819508] 'agreement among raft nodes before linearized reading' (duration: 119.842298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:42:27.576664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:42:27.129339Z","time spent":"447.229303ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:01.007282Z","caller":"traceutil/trace.go:171","msg":"trace[1666934598] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"139.895501ms","start":"2026-07-16T02:44:00.867353Z","end":"2026-07-16T02:44:01.007248Z","steps":["trace[1666934598] 'read index received' (duration: 139.551743ms)","trace[1666934598] 'applied index is now lower than readState.Index' (duration: 342.798µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:01.00768Z","caller":"traceutil/trace.go:171","msg":"trace[426744853] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"238.601373ms","start":"2026-07-16T02:44:00.769028Z","end":"2026-07-16T02:44:01.00763Z","steps":["trace[426744853] 'process raft request' (duration: 238.040569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:01.007755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.373323ms","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-07-16T02:44:01.007885Z","caller":"traceutil/trace.go:171","msg":"trace[775521760] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1811; }","duration":"140.576188ms","start":"2026-07-16T02:44:00.867281Z","end":"2026-07-16T02:44:01.007857Z","steps":["trace[775521760] 'agreement among raft nodes before linearized reading' (duration: 140.220919ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:01.153842Z","caller":"traceutil/trace.go:171","msg":"trace[2071570100] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1885; }","duration":"112.774196ms","start":"2026-07-16T02:44:01.041048Z","end":"2026-07-16T02:44:01.153822Z","steps":["trace[2071570100] 'read index received' (duration: 1.521067ms)","trace[2071570100] 'applied index is now lower than readState.Index' (duration: 111.252129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:01.153996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.929559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T02:44:01.154033Z","caller":"traceutil/trace.go:171","msg":"trace[97355855] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1813; }","duration":"113.002541ms","start":"2026-07-16T02:44:01.041016Z","end":"2026-07-16T02:44:01.154019Z","steps":["trace[97355855] 'agreement among raft nodes before linearized reading' (duration: 112.928079ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:01.154005Z","caller":"traceutil/trace.go:171","msg":"trace[58511118] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"141.686894ms","start":"2026-07-16T02:44:01.012273Z","end":"2026-07-16T02:44:01.15396Z","steps":["trace[58511118] 'process raft request' (duration: 69.910945ms)","trace[58511118] 'compare' (duration: 71.517943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:01.154067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.973198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:44:01.154147Z","caller":"traceutil/trace.go:171","msg":"trace[1732044260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1813; }","duration":"107.103631ms","start":"2026-07-16T02:44:01.047024Z","end":"2026-07-16T02:44:01.154128Z","steps":["trace[1732044260] 'agreement among raft nodes before linearized reading' (duration: 106.968518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:06.329913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.573385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:06.330106Z","caller":"traceutil/trace.go:171","msg":"trace[1742884640] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"100.869493ms","start":"2026-07-16T02:44:06.229207Z","end":"2026-07-16T02:44:06.330077Z","steps":["trace[1742884640] 'read index received' (duration: 37.481µs)","trace[1742884640] 'applied index is now lower than readState.Index' (duration: 100.829761ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:06.330165Z","caller":"traceutil/trace.go:171","msg":"trace[2006719794] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"164.367765ms","start":"2026-07-16T02:44:06.165757Z","end":"2026-07-16T02:44:06.330125Z","steps":["trace[2006719794] 'process raft request' (duration: 58.33813ms)","trace[2006719794] 'compare' (duration: 105.378469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:06.330325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.105298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-16T02:44:06.330415Z","caller":"traceutil/trace.go:171","msg":"trace[525639218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"101.256531ms","start":"2026-07-16T02:44:06.229138Z","end":"2026-07-16T02:44:06.330394Z","steps":["trace[525639218] 'agreement among raft nodes before linearized reading' (duration: 101.043606ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:06.532239Z","caller":"traceutil/trace.go:171","msg":"trace[381150325] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"197.690369ms","start":"2026-07-16T02:44:06.33452Z","end":"2026-07-16T02:44:06.532211Z","steps":["trace[381150325] 'process raft request' (duration: 101.481678ms)","trace[381150325] 'compare' (duration: 95.999436ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:06.532746Z","caller":"traceutil/trace.go:171","msg":"trace[1399109980] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"197.072953ms","start":"2026-07-16T02:44:06.335634Z","end":"2026-07-16T02:44:06.532707Z","steps":["trace[1399109980] 'process raft request' (duration: 196.92027ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:06.532776Z","caller":"traceutil/trace.go:171","msg":"trace[1786511813] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"197.378971ms","start":"2026-07-16T02:44:06.33536Z","end":"2026-07-16T02:44:06.532739Z","steps":["trace[1786511813] 'process raft request' (duration: 196.806297ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:07.287562Z","caller":"traceutil/trace.go:171","msg":"trace[593731975] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"167.215422ms","start":"2026-07-16T02:44:07.120316Z","end":"2026-07-16T02:44:07.287531Z","steps":["trace[593731975] 'process raft request' (duration: 166.784651ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:09.412459Z","caller":"traceutil/trace.go:171","msg":"trace[1142321311] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"117.944149ms","start":"2026-07-16T02:44:09.294467Z","end":"2026-07-16T02:44:09.412412Z","steps":["trace[1142321311] 'process raft request' (duration: 117.707143ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:09.58566Z","caller":"traceutil/trace.go:171","msg":"trace[1247656300] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"168.421081ms","start":"2026-07-16T02:44:09.417203Z","end":"2026-07-16T02:44:09.585624Z","steps":["trace[1247656300] 'process raft request' (duration: 129.571986ms)","trace[1247656300] 'compare' (duration: 38.64221ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:09.586878Z","caller":"traceutil/trace.go:171","msg":"trace[1446469390] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"166.343612ms","start":"2026-07-16T02:44:09.420493Z","end":"2026-07-16T02:44:09.586837Z","steps":["trace[1446469390] 'process raft request' (duration: 166.162647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:10.675222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.511593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-16T02:44:10.675428Z","caller":"traceutil/trace.go:171","msg":"trace[882147263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1852; }","duration":"121.747419ms","start":"2026-07-16T02:44:10.553619Z","end":"2026-07-16T02:44:10.675366Z","steps":["trace[882147263] 'range keys from in-memory index tree' (duration: 121.275628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:10.675539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.938209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-16T02:44:10.675718Z","caller":"traceutil/trace.go:171","msg":"trace[1735868377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"181.339249ms","start":"2026-07-16T02:44:10.494354Z","end":"2026-07-16T02:44:10.675693Z","steps":["trace[1735868377] 'range keys from in-memory index tree' (duration: 180.620982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:10.675223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.182375ms","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-07-16T02:44:10.675928Z","caller":"traceutil/trace.go:171","msg":"trace[1438283691] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1852; }","duration":"100.904023ms","start":"2026-07-16T02:44:10.574998Z","end":"2026-07-16T02:44:10.675902Z","steps":["trace[1438283691] 'range keys from in-memory index tree' (duration: 100.157195ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:10.802387Z","caller":"traceutil/trace.go:171","msg":"trace[2015752689] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"121.79996ms","start":"2026-07-16T02:44:10.680553Z","end":"2026-07-16T02:44:10.802353Z","steps":["trace[2015752689] 'process raft request' (duration: 121.601155ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:10.958394Z","caller":"traceutil/trace.go:171","msg":"trace[90125829] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"277.721493ms","start":"2026-07-16T02:44:10.680636Z","end":"2026-07-16T02:44:10.958358Z","steps":["trace[90125829] 'process raft request' (duration: 277.453816ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:10.958828Z","caller":"traceutil/trace.go:171","msg":"trace[2141010433] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"277.892697ms","start":"2026-07-16T02:44:10.680896Z","end":"2026-07-16T02:44:10.958788Z","steps":["trace[2141010433] 'process raft request' (duration: 277.415516ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:10.958881Z","caller":"traceutil/trace.go:171","msg":"trace[219395840] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"277.5541ms","start":"2026-07-16T02:44:10.681303Z","end":"2026-07-16T02:44:10.958857Z","steps":["trace[219395840] 'process raft request' (duration: 277.322014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:11.695789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.349593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T02:44:11.695909Z","caller":"traceutil/trace.go:171","msg":"trace[829579275] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1859; }","duration":"190.532298ms","start":"2026-07-16T02:44:11.505356Z","end":"2026-07-16T02:44:11.695889Z","steps":["trace[829579275] 'count revisions from in-memory index tree' (duration: 190.242421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:11.69602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.157748ms","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:532"} {"level":"info","ts":"2026-07-16T02:44:11.696145Z","caller":"traceutil/trace.go:171","msg":"trace[189464341] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1859; }","duration":"106.336542ms","start":"2026-07-16T02:44:11.589779Z","end":"2026-07-16T02:44:11.696116Z","steps":["trace[189464341] 'range keys from in-memory index tree' (duration: 105.867901ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:12.508689Z","caller":"traceutil/trace.go:171","msg":"trace[971860656] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"107.572153ms","start":"2026-07-16T02:44:12.401086Z","end":"2026-07-16T02:44:12.508658Z","steps":["trace[971860656] 'process raft request' (duration: 107.412699ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:12.509173Z","caller":"traceutil/trace.go:171","msg":"trace[793352166] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1937; }","duration":"104.709634ms","start":"2026-07-16T02:44:12.40441Z","end":"2026-07-16T02:44:12.509119Z","steps":["trace[793352166] 'read index received' (duration: 104.694023ms)","trace[793352166] 'applied index is now lower than readState.Index' (duration: 11.591µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:12.509407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.979121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T02:44:12.509506Z","caller":"traceutil/trace.go:171","msg":"trace[788206227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1861; }","duration":"105.088883ms","start":"2026-07-16T02:44:12.404391Z","end":"2026-07-16T02:44:12.50948Z","steps":["trace[788206227] 'agreement among raft nodes before linearized reading' (duration: 104.832827ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:15.162704Z","caller":"traceutil/trace.go:171","msg":"trace[1710536974] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1950; }","duration":"166.534666ms","start":"2026-07-16T02:44:14.99614Z","end":"2026-07-16T02:44:15.162675Z","steps":["trace[1710536974] 'read index received' (duration: 46.218191ms)","trace[1710536974] 'applied index is now lower than readState.Index' (duration: 120.315525ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:15.162875Z","caller":"traceutil/trace.go:171","msg":"trace[1199589859] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"167.558581ms","start":"2026-07-16T02:44:14.995302Z","end":"2026-07-16T02:44:15.162861Z","steps":["trace[1199589859] 'process raft request' (duration: 84.310308ms)","trace[1199589859] 'compare' (duration: 82.907245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:15.163014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.263877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:44:15.163107Z","caller":"traceutil/trace.go:171","msg":"trace[1602066870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"118.395679ms","start":"2026-07-16T02:44:15.044688Z","end":"2026-07-16T02:44:15.163083Z","steps":["trace[1602066870] 'agreement among raft nodes before linearized reading' (duration: 118.247846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:15.16316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.993667ms","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-07-16T02:44:15.163196Z","caller":"traceutil/trace.go:171","msg":"trace[824652872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1875; }","duration":"167.062129ms","start":"2026-07-16T02:44:14.996124Z","end":"2026-07-16T02:44:15.163186Z","steps":["trace[824652872] 'agreement among raft nodes before linearized reading' (duration: 166.912395ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:18.005938Z","caller":"traceutil/trace.go:171","msg":"trace[293063123] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"162.405408ms","start":"2026-07-16T02:44:17.843499Z","end":"2026-07-16T02:44:18.005904Z","steps":["trace[293063123] 'process raft request' (duration: 162.207833ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.222549Z","caller":"traceutil/trace.go:171","msg":"trace[1383398745] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"230.814446ms","start":"2026-07-16T02:44:20.991702Z","end":"2026-07-16T02:44:21.222516Z","steps":["trace[1383398745] 'read index received' (duration: 230.56994ms)","trace[1383398745] 'applied index is now lower than readState.Index' (duration: 242.916µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:21.222615Z","caller":"traceutil/trace.go:171","msg":"trace[1105201062] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"536.277492ms","start":"2026-07-16T02:44:20.686298Z","end":"2026-07-16T02:44:21.222576Z","steps":["trace[1105201062] 'process raft request' (duration: 535.991635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:21.222826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:20.686277Z","time spent":"536.431565ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:44:21.222894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.902099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T02:44:21.222871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.136544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-16T02:44:21.222912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.918697ms","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-07-16T02:44:21.222949Z","caller":"traceutil/trace.go:171","msg":"trace[1725278014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"176.118064ms","start":"2026-07-16T02:44:21.046817Z","end":"2026-07-16T02:44:21.222935Z","steps":["trace[1725278014] 'agreement among raft nodes before linearized reading' (duration: 175.905039ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.222981Z","caller":"traceutil/trace.go:171","msg":"trace[816672551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1898; }","duration":"231.313549ms","start":"2026-07-16T02:44:20.991643Z","end":"2026-07-16T02:44:21.222957Z","steps":["trace[816672551] 'agreement among raft nodes before linearized reading' (duration: 231.045842ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.222991Z","caller":"traceutil/trace.go:171","msg":"trace[1946004168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"102.021979ms","start":"2026-07-16T02:44:21.120949Z","end":"2026-07-16T02:44:21.222971Z","steps":["trace[1946004168] 'agreement among raft nodes before linearized reading' (duration: 101.853355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:21.460273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.872982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:21.460428Z","caller":"traceutil/trace.go:171","msg":"trace[797288152] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1976; }","duration":"226.535535ms","start":"2026-07-16T02:44:21.233865Z","end":"2026-07-16T02:44:21.460401Z","steps":["trace[797288152] 'read index received' (duration: 55.86491ms)","trace[797288152] 'applied index is now lower than readState.Index' (duration: 170.668915ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:21.460506Z","caller":"traceutil/trace.go:171","msg":"trace[657467077] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"231.892382ms","start":"2026-07-16T02:44:21.22857Z","end":"2026-07-16T02:44:21.460463Z","steps":["trace[657467077] 'process raft request' (duration: 104.696283ms)","trace[657467077] 'compare' (duration: 126.697258ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:21.460896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.019716ms","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:508"} {"level":"info","ts":"2026-07-16T02:44:21.460947Z","caller":"traceutil/trace.go:171","msg":"trace[1581751354] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1900; }","duration":"227.082829ms","start":"2026-07-16T02:44:21.233848Z","end":"2026-07-16T02:44:21.460931Z","steps":["trace[1581751354] 'agreement among raft nodes before linearized reading' (duration: 226.758581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:21.461054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.531893ms","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-07-16T02:44:21.461103Z","caller":"traceutil/trace.go:171","msg":"trace[1313224889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1900; }","duration":"173.618955ms","start":"2026-07-16T02:44:21.287471Z","end":"2026-07-16T02:44:21.46109Z","steps":["trace[1313224889] 'agreement among raft nodes before linearized reading' (duration: 173.432971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:21.461412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.317043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-16T02:44:21.461636Z","caller":"traceutil/trace.go:171","msg":"trace[2090805739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1900; }","duration":"198.58466ms","start":"2026-07-16T02:44:21.263017Z","end":"2026-07-16T02:44:21.461602Z","steps":["trace[2090805739] 'agreement among raft nodes before linearized reading' (duration: 197.74847ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.739845Z","caller":"traceutil/trace.go:171","msg":"trace[163503041] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"273.955144ms","start":"2026-07-16T02:44:21.465828Z","end":"2026-07-16T02:44:21.739783Z","steps":["trace[163503041] 'process raft request' (duration: 224.048076ms)","trace[163503041] 'compare' (duration: 49.617571ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:21.740076Z","caller":"traceutil/trace.go:171","msg":"trace[1923127299] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"274.138328ms","start":"2026-07-16T02:44:21.465895Z","end":"2026-07-16T02:44:21.740033Z","steps":["trace[1923127299] 'process raft request' (duration: 273.834661ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.741221Z","caller":"traceutil/trace.go:171","msg":"trace[1227628517] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"274.942167ms","start":"2026-07-16T02:44:21.466256Z","end":"2026-07-16T02:44:21.741198Z","steps":["trace[1227628517] 'process raft request' (duration: 273.646056ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.741325Z","caller":"traceutil/trace.go:171","msg":"trace[1515664711] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"151.667482ms","start":"2026-07-16T02:44:21.589618Z","end":"2026-07-16T02:44:21.741286Z","steps":["trace[1515664711] 'process raft request' (duration: 151.402376ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:21.741288Z","caller":"traceutil/trace.go:171","msg":"trace[307521881] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"148.104347ms","start":"2026-07-16T02:44:21.593065Z","end":"2026-07-16T02:44:21.741169Z","steps":["trace[307521881] 'process raft request' (duration: 148.046436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:22.296213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.22494ms","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-07-16T02:44:22.296321Z","caller":"traceutil/trace.go:171","msg":"trace[402609610] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1905; }","duration":"177.396373ms","start":"2026-07-16T02:44:22.1189Z","end":"2026-07-16T02:44:22.296297Z","steps":["trace[402609610] 'range keys from in-memory index tree' (duration: 176.978484ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:22.934564Z","caller":"traceutil/trace.go:171","msg":"trace[880371257] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"392.152459ms","start":"2026-07-16T02:44:22.54238Z","end":"2026-07-16T02:44:22.934533Z","steps":["trace[880371257] 'read index received' (duration: 392.069697ms)","trace[880371257] 'applied index is now lower than readState.Index' (duration: 81.601µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:22.934935Z","caller":"traceutil/trace.go:171","msg":"trace[896256007] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"634.169471ms","start":"2026-07-16T02:44:22.300715Z","end":"2026-07-16T02:44:22.934885Z","steps":["trace[896256007] 'process raft request' (duration: 633.615688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:22.93517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:22.300684Z","time spent":"634.373246ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:44:22.935507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.133482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-16T02:44:22.935561Z","caller":"traceutil/trace.go:171","msg":"trace[2032052537] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1906; }","duration":"393.318136ms","start":"2026-07-16T02:44:22.542226Z","end":"2026-07-16T02:44:22.935544Z","steps":["trace[2032052537] 'agreement among raft nodes before linearized reading' (duration: 392.561469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:22.935598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:22.542197Z","time spent":"393.389948ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-16T02:44:22.934993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.249863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T02:44:22.935839Z","caller":"traceutil/trace.go:171","msg":"trace[1107657532] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1906; }","duration":"239.130134ms","start":"2026-07-16T02:44:22.696638Z","end":"2026-07-16T02:44:22.935769Z","steps":["trace[1107657532] 'agreement among raft nodes before linearized reading' (duration: 238.224632ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:23.107398Z","caller":"traceutil/trace.go:171","msg":"trace[1521141935] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"166.704409ms","start":"2026-07-16T02:44:22.940663Z","end":"2026-07-16T02:44:23.107368Z","steps":["trace[1521141935] 'process raft request' (duration: 76.279276ms)","trace[1521141935] 'compare' (duration: 90.199618ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:23.996532Z","caller":"traceutil/trace.go:171","msg":"trace[1755451429] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"248.55655ms","start":"2026-07-16T02:44:23.747951Z","end":"2026-07-16T02:44:23.996507Z","steps":["trace[1755451429] 'process raft request' (duration: 235.634502ms)","trace[1755451429] 'compare' (duration: 12.819036ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:24.023492Z","caller":"traceutil/trace.go:171","msg":"trace[634371784] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1989; }","duration":"242.27402ms","start":"2026-07-16T02:44:23.781197Z","end":"2026-07-16T02:44:24.023471Z","steps":["trace[634371784] 'read index received' (duration: 202.3796ms)","trace[634371784] 'applied index is now lower than readState.Index' (duration: 39.89312ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:24.023581Z","caller":"traceutil/trace.go:171","msg":"trace[1732986366] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"272.023548ms","start":"2026-07-16T02:44:23.751529Z","end":"2026-07-16T02:44:24.023553Z","steps":["trace[1732986366] 'process raft request' (duration: 271.899775ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:24.023582Z","caller":"traceutil/trace.go:171","msg":"trace[1192259153] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"272.844619ms","start":"2026-07-16T02:44:23.750688Z","end":"2026-07-16T02:44:24.023532Z","steps":["trace[1192259153] 'process raft request' (duration: 272.613913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:24.023715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.491035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-07-16T02:44:24.023748Z","caller":"traceutil/trace.go:171","msg":"trace[2019509336] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:1914; }","duration":"242.571917ms","start":"2026-07-16T02:44:23.781169Z","end":"2026-07-16T02:44:24.023741Z","steps":["trace[2019509336] 'agreement among raft nodes before linearized reading' (duration: 242.379522ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:24.214611Z","caller":"traceutil/trace.go:171","msg":"trace[1571036377] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"139.185035ms","start":"2026-07-16T02:44:24.075384Z","end":"2026-07-16T02:44:24.214569Z","steps":["trace[1571036377] 'process raft request' (duration: 86.660814ms)","trace[1571036377] 'compare' (duration: 52.303825ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:25.094904Z","caller":"traceutil/trace.go:171","msg":"trace[287002529] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"154.420736ms","start":"2026-07-16T02:44:24.940453Z","end":"2026-07-16T02:44:25.094874Z","steps":["trace[287002529] 'read index received' (duration: 154.058778ms)","trace[287002529] 'applied index is now lower than readState.Index' (duration: 360.698µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:25.095023Z","caller":"traceutil/trace.go:171","msg":"trace[1999300406] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"357.820631ms","start":"2026-07-16T02:44:24.73715Z","end":"2026-07-16T02:44:25.094971Z","steps":["trace[1999300406] 'process raft request' (duration: 357.485933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:25.095191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.720494ms","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-07-16T02:44:25.095237Z","caller":"traceutil/trace.go:171","msg":"trace[1951596335] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1916; }","duration":"154.844187ms","start":"2026-07-16T02:44:24.940381Z","end":"2026-07-16T02:44:25.095225Z","steps":["trace[1951596335] 'agreement among raft nodes before linearized reading' (duration: 154.655652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:25.095243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:24.737112Z","time spent":"358.002325ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:25.322107Z","caller":"traceutil/trace.go:171","msg":"trace[2000051530] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"221.932885ms","start":"2026-07-16T02:44:25.100139Z","end":"2026-07-16T02:44:25.322072Z","steps":["trace[2000051530] 'process raft request' (duration: 149.654183ms)","trace[2000051530] 'compare' (duration: 72.129168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:25.695684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.614217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:25.695939Z","caller":"traceutil/trace.go:171","msg":"trace[521618536] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"343.300243ms","start":"2026-07-16T02:44:25.352615Z","end":"2026-07-16T02:44:25.695915Z","steps":["trace[521618536] 'read index received' (duration: 131.299837ms)","trace[521618536] 'applied index is now lower than readState.Index' (duration: 211.998796ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:25.695967Z","caller":"traceutil/trace.go:171","msg":"trace[2057461070] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"360.698017ms","start":"2026-07-16T02:44:25.335225Z","end":"2026-07-16T02:44:25.695923Z","steps":["trace[2057461070] 'process raft request' (duration: 148.737842ms)","trace[2057461070] 'compare' (duration: 211.464404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:25.696137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.511708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-16T02:44:25.696171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:25.335199Z","time spent":"360.855441ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:44:25.696274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.30967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T02:44:25.696185Z","caller":"traceutil/trace.go:171","msg":"trace[645254082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1918; }","duration":"343.581459ms","start":"2026-07-16T02:44:25.352586Z","end":"2026-07-16T02:44:25.696168Z","steps":["trace[645254082] 'agreement among raft nodes before linearized reading' (duration: 343.427176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:25.696382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.415577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T02:44:25.696419Z","caller":"traceutil/trace.go:171","msg":"trace[875064582] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1918; }","duration":"115.477719ms","start":"2026-07-16T02:44:25.580933Z","end":"2026-07-16T02:44:25.696411Z","steps":["trace[875064582] 'agreement among raft nodes before linearized reading' (duration: 115.410357ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:25.696312Z","caller":"traceutil/trace.go:171","msg":"trace[885235197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1918; }","duration":"174.400442ms","start":"2026-07-16T02:44:25.521902Z","end":"2026-07-16T02:44:25.696302Z","steps":["trace[885235197] 'agreement among raft nodes before linearized reading' (duration: 174.312679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:25.696789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:25.352576Z","time spent":"343.792345ms","remote":"127.0.0.1:50134","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-07-16T02:44:26.205739Z","caller":"traceutil/trace.go:171","msg":"trace[954992205] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"140.255651ms","start":"2026-07-16T02:44:26.065456Z","end":"2026-07-16T02:44:26.205711Z","steps":["trace[954992205] 'process raft request' (duration: 104.503999ms)","trace[954992205] 'compare' (duration: 35.524906ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:26.250472Z","caller":"traceutil/trace.go:171","msg":"trace[1874744168] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"184.411142ms","start":"2026-07-16T02:44:26.06603Z","end":"2026-07-16T02:44:26.250441Z","steps":["trace[1874744168] 'process raft request' (duration: 184.231868ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:26.908553Z","caller":"traceutil/trace.go:171","msg":"trace[1263208996] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"158.948125ms","start":"2026-07-16T02:44:26.749569Z","end":"2026-07-16T02:44:26.908517Z","steps":["trace[1263208996] 'process raft request' (duration: 158.766631ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:27.816786Z","caller":"traceutil/trace.go:171","msg":"trace[133592909] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"102.304016ms","start":"2026-07-16T02:44:27.714457Z","end":"2026-07-16T02:44:27.816761Z","steps":["trace[133592909] 'read index received' (duration: 102.125462ms)","trace[133592909] 'applied index is now lower than readState.Index' (duration: 177.734µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:27.816978Z","caller":"traceutil/trace.go:171","msg":"trace[640801460] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"108.182997ms","start":"2026-07-16T02:44:27.70878Z","end":"2026-07-16T02:44:27.816963Z","steps":["trace[640801460] 'process raft request' (duration: 107.822849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:27.817535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.023313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T02:44:27.81767Z","caller":"traceutil/trace.go:171","msg":"trace[1000823983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1927; }","duration":"103.219038ms","start":"2026-07-16T02:44:27.714429Z","end":"2026-07-16T02:44:27.817648Z","steps":["trace[1000823983] 'agreement among raft nodes before linearized reading' (duration: 102.808939ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:28.467963Z","caller":"traceutil/trace.go:171","msg":"trace[1827952465] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"420.454302ms","start":"2026-07-16T02:44:28.04747Z","end":"2026-07-16T02:44:28.467924Z","steps":["trace[1827952465] 'read index received' (duration: 420.128444ms)","trace[1827952465] 'applied index is now lower than readState.Index' (duration: 324.888µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:28.468037Z","caller":"traceutil/trace.go:171","msg":"trace[1120333953] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"643.683148ms","start":"2026-07-16T02:44:27.824306Z","end":"2026-07-16T02:44:28.467989Z","steps":["trace[1120333953] 'process raft request' (duration: 643.383131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:28.468139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.649197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:44:28.468603Z","caller":"traceutil/trace.go:171","msg":"trace[57194561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1929; }","duration":"421.18866ms","start":"2026-07-16T02:44:28.047399Z","end":"2026-07-16T02:44:28.468587Z","steps":["trace[57194561] 'agreement among raft nodes before linearized reading' (duration: 420.676288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:28.468671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.047369Z","time spent":"421.288422ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T02:44:28.468236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:27.824279Z","time spent":"643.844943ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:28.687776Z","caller":"traceutil/trace.go:171","msg":"trace[872364430] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"219.699703ms","start":"2026-07-16T02:44:28.468042Z","end":"2026-07-16T02:44:28.687742Z","steps":["trace[872364430] 'read index received' (duration: 148.566869ms)","trace[872364430] 'applied index is now lower than readState.Index' (duration: 71.131424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:28.688032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.976432ms","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":"warn","ts":"2026-07-16T02:44:28.688141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.185159ms","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-07-16T02:44:28.688188Z","caller":"traceutil/trace.go:171","msg":"trace[2122114845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1930; }","duration":"478.306652ms","start":"2026-07-16T02:44:28.209868Z","end":"2026-07-16T02:44:28.688175Z","steps":["trace[2122114845] 'agreement among raft nodes before linearized reading' (duration: 478.099357ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:28.688197Z","caller":"traceutil/trace.go:171","msg":"trace[856783888] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1930; }","duration":"454.237868ms","start":"2026-07-16T02:44:28.233906Z","end":"2026-07-16T02:44:28.688144Z","steps":["trace[856783888] 'agreement among raft nodes before linearized reading' (duration: 453.936491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:28.688234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.209839Z","time spent":"478.385834ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T02:44:28.688303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.233881Z","time spent":"454.358501ms","remote":"127.0.0.1:50348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-16T02:44:28.688373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.091735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:44:28.688435Z","caller":"traceutil/trace.go:171","msg":"trace[1391103896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1930; }","duration":"218.155466ms","start":"2026-07-16T02:44:28.470265Z","end":"2026-07-16T02:44:28.688421Z","steps":["trace[1391103896] 'agreement among raft nodes before linearized reading' (duration: 218.061554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:28.688626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.432249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-16T02:44:28.68877Z","caller":"traceutil/trace.go:171","msg":"trace[1031915089] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1930; }","duration":"576.642594ms","start":"2026-07-16T02:44:28.112093Z","end":"2026-07-16T02:44:28.688735Z","steps":["trace[1031915089] 'agreement among raft nodes before linearized reading' (duration: 576.036669ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:28.688597Z","caller":"traceutil/trace.go:171","msg":"trace[1341923121] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"620.519078ms","start":"2026-07-16T02:44:28.068026Z","end":"2026-07-16T02:44:28.688545Z","steps":["trace[1341923121] 'process raft request' (duration: 548.508633ms)","trace[1341923121] 'compare' (duration: 71.057183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:28.688828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.247872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-16T02:44:28.688915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.338177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-16T02:44:28.688933Z","caller":"traceutil/trace.go:171","msg":"trace[186088426] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1930; }","duration":"523.430316ms","start":"2026-07-16T02:44:28.165481Z","end":"2026-07-16T02:44:28.688912Z","steps":["trace[186088426] 'agreement among raft nodes before linearized reading' (duration: 523.18939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:28.68893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.067975Z","time spent":"620.873687ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:28.688973Z","caller":"traceutil/trace.go:171","msg":"trace[1546920265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1930; }","duration":"432.46731ms","start":"2026-07-16T02:44:28.256494Z","end":"2026-07-16T02:44:28.688961Z","steps":["trace[1546920265] 'agreement among raft nodes before linearized reading' (duration: 432.234005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:28.688991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.165454Z","time spent":"523.526259ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-16T02:44:28.688897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.112074Z","time spent":"576.808027ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-16T02:44:28.689021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:28.256468Z","time spent":"432.544152ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T02:44:28.941332Z","caller":"traceutil/trace.go:171","msg":"trace[140304420] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"247.518505ms","start":"2026-07-16T02:44:28.693788Z","end":"2026-07-16T02:44:28.941306Z","steps":["trace[140304420] 'process raft request' (duration: 243.742235ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:28.94144Z","caller":"traceutil/trace.go:171","msg":"trace[500468178] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"247.3146ms","start":"2026-07-16T02:44:28.694085Z","end":"2026-07-16T02:44:28.9414Z","steps":["trace[500468178] 'process raft request' (duration: 247.177897ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:28.941411Z","caller":"traceutil/trace.go:171","msg":"trace[667938526] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2009; }","duration":"224.360193ms","start":"2026-07-16T02:44:28.71703Z","end":"2026-07-16T02:44:28.941391Z","steps":["trace[667938526] 'read index received' (duration: 68.218794ms)","trace[667938526] 'applied index is now lower than readState.Index' (duration: 156.139809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:28.941754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.706001ms","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-07-16T02:44:28.941806Z","caller":"traceutil/trace.go:171","msg":"trace[207631237] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1933; }","duration":"224.836594ms","start":"2026-07-16T02:44:28.716956Z","end":"2026-07-16T02:44:28.941793Z","steps":["trace[207631237] 'agreement among raft nodes before linearized reading' (duration: 224.492596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:29.441752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.753412ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-16T02:44:29.442044Z","caller":"traceutil/trace.go:171","msg":"trace[1970542479] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"390.856377ms","start":"2026-07-16T02:44:29.051159Z","end":"2026-07-16T02:44:29.442015Z","steps":["trace[1970542479] 'read index received' (duration: 6.001393ms)","trace[1970542479] 'applied index is now lower than readState.Index' (duration: 384.853044ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:29.44229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.112752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T02:44:29.442381Z","caller":"traceutil/trace.go:171","msg":"trace[150426114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1934; }","duration":"391.264787ms","start":"2026-07-16T02:44:29.051094Z","end":"2026-07-16T02:44:29.442359Z","steps":["trace[150426114] 'agreement among raft nodes before linearized reading' (duration: 391.100983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:29.442429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:29.05107Z","time spent":"391.351248ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T02:44:29.606283Z","caller":"traceutil/trace.go:171","msg":"trace[1092688029] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"157.354937ms","start":"2026-07-16T02:44:29.448894Z","end":"2026-07-16T02:44:29.606249Z","steps":["trace[1092688029] 'process raft request' (duration: 157.126432ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:30.041997Z","caller":"traceutil/trace.go:171","msg":"trace[1670616948] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"130.340884ms","start":"2026-07-16T02:44:29.911617Z","end":"2026-07-16T02:44:30.041958Z","steps":["trace[1670616948] 'process raft request' (duration: 82.378961ms)","trace[1670616948] 'compare' (duration: 47.800919ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:30.374537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.380706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:30.374663Z","caller":"traceutil/trace.go:171","msg":"trace[1133097143] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"271.236249ms","start":"2026-07-16T02:44:30.103407Z","end":"2026-07-16T02:44:30.374643Z","steps":["trace[1133097143] 'process raft request' (duration: 74.645517ms)","trace[1133097143] 'compare' (duration: 196.229503ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:30.808372Z","caller":"traceutil/trace.go:171","msg":"trace[2016179722] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"113.927913ms","start":"2026-07-16T02:44:30.694387Z","end":"2026-07-16T02:44:30.808315Z","steps":["trace[2016179722] 'process raft request' (duration: 112.989281ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:30.972944Z","caller":"traceutil/trace.go:171","msg":"trace[1256020373] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"159.683982ms","start":"2026-07-16T02:44:30.813204Z","end":"2026-07-16T02:44:30.972888Z","steps":["trace[1256020373] 'process raft request' (duration: 63.41552ms)","trace[1256020373] 'compare' (duration: 96.022556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:31.373174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.091578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:31.373342Z","caller":"traceutil/trace.go:171","msg":"trace[1696310770] linearizableReadLoop","detail":"{readStateIndex:2022; appliedIndex:2021; }","duration":"325.8594ms","start":"2026-07-16T02:44:31.047454Z","end":"2026-07-16T02:44:31.373313Z","steps":["trace[1696310770] 'read index received' (duration: 64.413124ms)","trace[1696310770] 'applied index is now lower than readState.Index' (duration: 261.444256ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:31.373414Z","caller":"traceutil/trace.go:171","msg":"trace[947083169] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"396.623195ms","start":"2026-07-16T02:44:30.97676Z","end":"2026-07-16T02:44:31.373383Z","steps":["trace[947083169] 'process raft request' (duration: 135.192669ms)","trace[947083169] 'compare' (duration: 260.800611ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:31.373443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.997113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T02:44:31.373551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:30.976727Z","time spent":"396.763319ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:31.373601Z","caller":"traceutil/trace.go:171","msg":"trace[2068237116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1942; }","duration":"326.202529ms","start":"2026-07-16T02:44:31.047381Z","end":"2026-07-16T02:44:31.373584Z","steps":["trace[2068237116] 'agreement among raft nodes before linearized reading' (duration: 326.007224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:31.373652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:31.047353Z","time spent":"326.2855ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T02:44:32.094772Z","caller":"traceutil/trace.go:171","msg":"trace[200884591] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"107.851618ms","start":"2026-07-16T02:44:31.986889Z","end":"2026-07-16T02:44:32.09474Z","steps":["trace[200884591] 'process raft request' (duration: 107.669644ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:32.302352Z","caller":"traceutil/trace.go:171","msg":"trace[652321136] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2023; }","duration":"252.75222ms","start":"2026-07-16T02:44:32.049584Z","end":"2026-07-16T02:44:32.302337Z","steps":["trace[652321136] 'read index received' (duration: 45.000482ms)","trace[652321136] 'applied index is now lower than readState.Index' (duration: 207.749938ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:32.302418Z","caller":"traceutil/trace.go:171","msg":"trace[920220319] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"312.251166ms","start":"2026-07-16T02:44:31.990119Z","end":"2026-07-16T02:44:32.30237Z","steps":["trace[920220319] 'process raft request' (duration: 312.0287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:32.302587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:31.990107Z","time spent":"312.372449ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:44:32.302693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.098838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T02:44:32.302817Z","caller":"traceutil/trace.go:171","msg":"trace[710199714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1946; }","duration":"253.281072ms","start":"2026-07-16T02:44:32.049523Z","end":"2026-07-16T02:44:32.302804Z","steps":["trace[710199714] 'agreement among raft nodes before linearized reading' (duration: 252.939654ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:32.302263Z","caller":"traceutil/trace.go:171","msg":"trace[1199318589] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"312.971573ms","start":"2026-07-16T02:44:31.98925Z","end":"2026-07-16T02:44:32.302222Z","steps":["trace[1199318589] 'process raft request' (duration: 312.793659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:32.303211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:31.989228Z","time spent":"313.867315ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:32.709722Z","caller":"traceutil/trace.go:171","msg":"trace[152361591] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"147.84862ms","start":"2026-07-16T02:44:32.561843Z","end":"2026-07-16T02:44:32.709692Z","steps":["trace[152361591] 'process raft request' (duration: 147.634585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:33.100775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.02132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-16T02:44:33.100874Z","caller":"traceutil/trace.go:171","msg":"trace[748607283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1949; }","duration":"123.186554ms","start":"2026-07-16T02:44:32.977666Z","end":"2026-07-16T02:44:33.100853Z","steps":["trace[748607283] 'range keys from in-memory index tree' (duration: 122.789034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:34.58023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.153515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:34.580355Z","caller":"traceutil/trace.go:171","msg":"trace[1642699141] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2035; }","duration":"272.727024ms","start":"2026-07-16T02:44:34.307609Z","end":"2026-07-16T02:44:34.580336Z","steps":["trace[1642699141] 'read index received' (duration: 40.771µs)","trace[1642699141] 'applied index is now lower than readState.Index' (duration: 272.684993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:34.580645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.036031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T02:44:34.580676Z","caller":"traceutil/trace.go:171","msg":"trace[485343388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1955; }","duration":"273.104773ms","start":"2026-07-16T02:44:34.307562Z","end":"2026-07-16T02:44:34.580667Z","steps":["trace[485343388] 'agreement among raft nodes before linearized reading' (duration: 272.96358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:34.580749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.088497ms","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-07-16T02:44:34.580834Z","caller":"traceutil/trace.go:171","msg":"trace[745451572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1955; }","duration":"206.2035ms","start":"2026-07-16T02:44:34.374603Z","end":"2026-07-16T02:44:34.580807Z","steps":["trace[745451572] 'agreement among raft nodes before linearized reading' (duration: 205.964934ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:34.580403Z","caller":"traceutil/trace.go:171","msg":"trace[798293977] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"523.389334ms","start":"2026-07-16T02:44:34.056985Z","end":"2026-07-16T02:44:34.580374Z","steps":["trace[798293977] 'process raft request' (duration: 123.993373ms)","trace[798293977] 'compare' (duration: 398.957931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:34.581323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:34.056942Z","time spent":"524.202924ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:34.810939Z","caller":"traceutil/trace.go:171","msg":"trace[1905510222] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"226.346581ms","start":"2026-07-16T02:44:34.584556Z","end":"2026-07-16T02:44:34.810902Z","steps":["trace[1905510222] 'process raft request' (duration: 207.925702ms)","trace[1905510222] 'compare' (duration: 18.285485ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:34.837249Z","caller":"traceutil/trace.go:171","msg":"trace[1331963304] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2036; }","duration":"123.542851ms","start":"2026-07-16T02:44:34.713687Z","end":"2026-07-16T02:44:34.83723Z","steps":["trace[1331963304] 'read index received' (duration: 78.756755ms)","trace[1331963304] 'applied index is now lower than readState.Index' (duration: 44.784756ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:34.837472Z","caller":"traceutil/trace.go:171","msg":"trace[1986080171] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"252.147305ms","start":"2026-07-16T02:44:34.585269Z","end":"2026-07-16T02:44:34.837416Z","steps":["trace[1986080171] 'process raft request' (duration: 251.842127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:34.837688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.988892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-16T02:44:34.837737Z","caller":"traceutil/trace.go:171","msg":"trace[132732928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1957; }","duration":"124.117385ms","start":"2026-07-16T02:44:34.713608Z","end":"2026-07-16T02:44:34.837725Z","steps":["trace[132732928] 'agreement among raft nodes before linearized reading' (duration: 123.694815ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:35.268554Z","caller":"traceutil/trace.go:171","msg":"trace[911939955] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"425.80709ms","start":"2026-07-16T02:44:34.842721Z","end":"2026-07-16T02:44:35.268528Z","steps":["trace[911939955] 'process raft request' (duration: 425.632846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:35.26883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:34.8427Z","time spent":"425.939823ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:35.526673Z","caller":"traceutil/trace.go:171","msg":"trace[2094012272] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2039; }","duration":"479.853657ms","start":"2026-07-16T02:44:35.04679Z","end":"2026-07-16T02:44:35.526644Z","steps":["trace[2094012272] 'read index received' (duration: 221.72694ms)","trace[2094012272] 'applied index is now lower than readState.Index' (duration: 258.124727ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:35.526733Z","caller":"traceutil/trace.go:171","msg":"trace[1250005147] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"613.780297ms","start":"2026-07-16T02:44:34.912927Z","end":"2026-07-16T02:44:35.526708Z","steps":["trace[1250005147] 'process raft request' (duration: 613.426728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:35.526941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.192255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T02:44:35.527001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:34.912897Z","time spent":"613.989232ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:35.527015Z","caller":"traceutil/trace.go:171","msg":"trace[3637268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1959; }","duration":"480.335139ms","start":"2026-07-16T02:44:35.046664Z","end":"2026-07-16T02:44:35.526999Z","steps":["trace[3637268] 'agreement among raft nodes before linearized reading' (duration: 480.134164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:35.527253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:35.04663Z","time spent":"480.544764ms","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T02:44:35.527286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.39785ms","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-07-16T02:44:35.527348Z","caller":"traceutil/trace.go:171","msg":"trace[1306752955] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1959; }","duration":"147.544304ms","start":"2026-07-16T02:44:35.379791Z","end":"2026-07-16T02:44:35.527335Z","steps":["trace[1306752955] 'agreement among raft nodes before linearized reading' (duration: 147.266957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:35.528112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.299434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-16T02:44:35.528254Z","caller":"traceutil/trace.go:171","msg":"trace[1802286159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1959; }","duration":"382.515469ms","start":"2026-07-16T02:44:35.145716Z","end":"2026-07-16T02:44:35.528231Z","steps":["trace[1802286159] 'agreement among raft nodes before linearized reading' (duration: 381.472244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:35.528366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:35.145688Z","time spent":"382.664753ms","remote":"127.0.0.1:50134","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-07-16T02:44:36.927977Z","caller":"traceutil/trace.go:171","msg":"trace[1189995389] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"108.009992ms","start":"2026-07-16T02:44:36.819943Z","end":"2026-07-16T02:44:36.927953Z","steps":["trace[1189995389] 'process raft request' (duration: 107.586192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:37.880913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.008724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:37.881068Z","caller":"traceutil/trace.go:171","msg":"trace[909402892] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"547.255793ms","start":"2026-07-16T02:44:37.333785Z","end":"2026-07-16T02:44:37.881041Z","steps":["trace[909402892] 'process raft request' (duration: 94.976732ms)","trace[909402892] 'compare' (duration: 451.560324ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:37.881153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:37.333763Z","time spent":"547.349955ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:37.965349Z","caller":"traceutil/trace.go:171","msg":"trace[247214936] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"444.199708ms","start":"2026-07-16T02:44:37.521122Z","end":"2026-07-16T02:44:37.965321Z","steps":["trace[247214936] 'process raft request' (duration: 444.015074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:37.965686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:37.52109Z","time spent":"444.354632ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:38.095709Z","caller":"traceutil/trace.go:171","msg":"trace[1108236521] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2048; }","duration":"545.794098ms","start":"2026-07-16T02:44:37.549891Z","end":"2026-07-16T02:44:38.095685Z","steps":["trace[1108236521] 'read index received' (duration: 415.268479ms)","trace[1108236521] 'applied index is now lower than readState.Index' (duration: 130.524429ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:38.095844Z","caller":"traceutil/trace.go:171","msg":"trace[248172168] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"561.959652ms","start":"2026-07-16T02:44:37.53383Z","end":"2026-07-16T02:44:38.09579Z","steps":["trace[248172168] 'process raft request' (duration: 561.703006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:38.096086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:37.533794Z","time spent":"562.149297ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:44:38.096287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.351831ms","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-07-16T02:44:38.09641Z","caller":"traceutil/trace.go:171","msg":"trace[223589461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1969; }","duration":"546.573047ms","start":"2026-07-16T02:44:37.549817Z","end":"2026-07-16T02:44:38.09639Z","steps":["trace[223589461] 'agreement among raft nodes before linearized reading' (duration: 546.098785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:38.09655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:37.549802Z","time spent":"546.73463ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T02:44:38.096652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.110961ms","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:532"} {"level":"info","ts":"2026-07-16T02:44:38.096758Z","caller":"traceutil/trace.go:171","msg":"trace[784768116] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1969; }","duration":"183.516071ms","start":"2026-07-16T02:44:37.91322Z","end":"2026-07-16T02:44:38.096736Z","steps":["trace[784768116] 'agreement among raft nodes before linearized reading' (duration: 182.910106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:38.097139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.512755ms","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-07-16T02:44:38.09724Z","caller":"traceutil/trace.go:171","msg":"trace[375142115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1969; }","duration":"546.669218ms","start":"2026-07-16T02:44:37.550544Z","end":"2026-07-16T02:44:38.097213Z","steps":["trace[375142115] 'agreement among raft nodes before linearized reading' (duration: 545.510291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:38.097298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:37.550526Z","time spent":"546.762391ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-16T02:44:38.469747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.562295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:38.470019Z","caller":"traceutil/trace.go:171","msg":"trace[857636193] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"367.305047ms","start":"2026-07-16T02:44:38.102687Z","end":"2026-07-16T02:44:38.469992Z","steps":["trace[857636193] 'process raft request' (duration: 367.186514ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:38.46999Z","caller":"traceutil/trace.go:171","msg":"trace[881075274] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"367.710807ms","start":"2026-07-16T02:44:38.102233Z","end":"2026-07-16T02:44:38.469944Z","steps":["trace[881075274] 'process raft request' (duration: 98.817743ms)","trace[881075274] 'compare' (duration: 268.384391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:38.470132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:38.102652Z","time spent":"367.42086ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:38.470137Z","caller":"traceutil/trace.go:171","msg":"trace[2027907145] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"367.060771ms","start":"2026-07-16T02:44:38.103045Z","end":"2026-07-16T02:44:38.470106Z","steps":["trace[2027907145] 'process raft request' (duration: 366.896968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:38.470208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:38.102207Z","time spent":"367.892491ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T02:44:38.470327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:38.10303Z","time spent":"367.200224ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T02:44:39.180239Z","caller":"traceutil/trace.go:171","msg":"trace[432660912] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"127.605709ms","start":"2026-07-16T02:44:39.052605Z","end":"2026-07-16T02:44:39.180211Z","steps":["trace[432660912] 'read index received' (duration: 91.675453ms)","trace[432660912] 'applied index is now lower than readState.Index' (duration: 35.929136ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:39.180486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.849385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-16T02:44:39.180493Z","caller":"traceutil/trace.go:171","msg":"trace[454643453] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"202.780239ms","start":"2026-07-16T02:44:38.977673Z","end":"2026-07-16T02:44:39.180453Z","steps":["trace[454643453] 'process raft request' (duration: 166.72947ms)","trace[454643453] 'compare' (duration: 35.67208ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:39.18053Z","caller":"traceutil/trace.go:171","msg":"trace[1076079474] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1974; }","duration":"127.981168ms","start":"2026-07-16T02:44:39.052536Z","end":"2026-07-16T02:44:39.180517Z","steps":["trace[1076079474] 'agreement among raft nodes before linearized reading' (duration: 127.779214ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:39.468604Z","caller":"traceutil/trace.go:171","msg":"trace[451140607] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2055; }","duration":"267.162372ms","start":"2026-07-16T02:44:39.201404Z","end":"2026-07-16T02:44:39.468566Z","steps":["trace[451140607] 'read index received' (duration: 190.521867ms)","trace[451140607] 'applied index is now lower than readState.Index' (duration: 76.639045ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:39.468866Z","caller":"traceutil/trace.go:171","msg":"trace[657708695] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"283.854ms","start":"2026-07-16T02:44:39.184998Z","end":"2026-07-16T02:44:39.468852Z","steps":["trace[657708695] 'process raft request' (duration: 206.991649ms)","trace[657708695] 'compare' (duration: 76.41155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:39.469142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.720266ms","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-07-16T02:44:39.469265Z","caller":"traceutil/trace.go:171","msg":"trace[382950207] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1975; }","duration":"267.89391ms","start":"2026-07-16T02:44:39.201325Z","end":"2026-07-16T02:44:39.469219Z","steps":["trace[382950207] 'agreement among raft nodes before linearized reading' (duration: 267.726316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:39.469284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.64422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-16T02:44:39.469646Z","caller":"traceutil/trace.go:171","msg":"trace[490603587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1975; }","duration":"136.05622ms","start":"2026-07-16T02:44:39.33355Z","end":"2026-07-16T02:44:39.469606Z","steps":["trace[490603587] 'agreement among raft nodes before linearized reading' (duration: 135.489217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:39.787599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.389193ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-16T02:44:39.787887Z","caller":"traceutil/trace.go:171","msg":"trace[513515619] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2057; }","duration":"201.414066ms","start":"2026-07-16T02:44:39.586449Z","end":"2026-07-16T02:44:39.787863Z","steps":["trace[513515619] 'read index received' (duration: 39.692875ms)","trace[513515619] 'applied index is now lower than readState.Index' (duration: 161.719161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:39.788185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.732284ms","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:524"} {"level":"info","ts":"2026-07-16T02:44:39.788262Z","caller":"traceutil/trace.go:171","msg":"trace[1535357078] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1976; }","duration":"201.865787ms","start":"2026-07-16T02:44:39.586383Z","end":"2026-07-16T02:44:39.788249Z","steps":["trace[1535357078] 'agreement among raft nodes before linearized reading' (duration: 201.604621ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:39.92191Z","caller":"traceutil/trace.go:171","msg":"trace[1128722968] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"128.171661ms","start":"2026-07-16T02:44:39.793706Z","end":"2026-07-16T02:44:39.921877Z","steps":["trace[1128722968] 'process raft request' (duration: 127.936735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:40.360595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.732152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T02:44:40.360795Z","caller":"traceutil/trace.go:171","msg":"trace[1693367647] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"258.920377ms","start":"2026-07-16T02:44:40.101837Z","end":"2026-07-16T02:44:40.360758Z","steps":["trace[1693367647] 'process raft request' (duration: 117.866027ms)","trace[1693367647] 'compare' (duration: 140.379153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:40.926739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.76446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-16T02:44:40.926843Z","caller":"traceutil/trace.go:171","msg":"trace[1979305792] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1979; }","duration":"378.958095ms","start":"2026-07-16T02:44:40.54786Z","end":"2026-07-16T02:44:40.926818Z","steps":["trace[1979305792] 'range keys from in-memory index tree' (duration: 378.35311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:40.926941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:40.547788Z","time spent":"379.138299ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-16T02:44:40.926902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.449449ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T02:44:40.926902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.39993ms","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-07-16T02:44:40.927064Z","caller":"traceutil/trace.go:171","msg":"trace[1302548205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1979; }","duration":"351.633054ms","start":"2026-07-16T02:44:40.575399Z","end":"2026-07-16T02:44:40.927032Z","steps":["trace[1302548205] 'range keys from in-memory index tree' (duration: 351.422719ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:40.927178Z","caller":"traceutil/trace.go:171","msg":"trace[1293846468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1979; }","duration":"451.704497ms","start":"2026-07-16T02:44:40.475417Z","end":"2026-07-16T02:44:40.927121Z","steps":["trace[1293846468] 'range keys from in-memory index tree' (duration: 451.151794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:40.927206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.750408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-16T02:44:40.927313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:40.475391Z","time spent":"451.882822ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-16T02:44:40.927073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.457831ms","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:532"} {"level":"info","ts":"2026-07-16T02:44:40.927342Z","caller":"traceutil/trace.go:171","msg":"trace[1606577954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1979; }","duration":"451.949373ms","start":"2026-07-16T02:44:40.475371Z","end":"2026-07-16T02:44:40.92732Z","steps":["trace[1606577954] 'range keys from in-memory index tree' (duration: 451.444051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:40.927475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:40.47534Z","time spent":"452.121347ms","remote":"127.0.0.1:50134","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-07-16T02:44:40.927448Z","caller":"traceutil/trace.go:171","msg":"trace[1855712091] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1979; }","duration":"451.82254ms","start":"2026-07-16T02:44:40.475584Z","end":"2026-07-16T02:44:40.927406Z","steps":["trace[1855712091] 'range keys from in-memory index tree' (duration: 451.350459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:40.927604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:44:40.475575Z","time spent":"451.976013ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-16T02:44:41.115114Z","caller":"traceutil/trace.go:171","msg":"trace[1603853394] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"181.448911ms","start":"2026-07-16T02:44:40.933635Z","end":"2026-07-16T02:44:41.115084Z","steps":["trace[1603853394] 'process raft request' (duration: 181.231216ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:41.115613Z","caller":"traceutil/trace.go:171","msg":"trace[150698413] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"181.795399ms","start":"2026-07-16T02:44:40.933764Z","end":"2026-07-16T02:44:41.115559Z","steps":["trace[150698413] 'process raft request' (duration: 181.272737ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:41.115848Z","caller":"traceutil/trace.go:171","msg":"trace[1622546286] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2061; }","duration":"147.442781ms","start":"2026-07-16T02:44:40.968357Z","end":"2026-07-16T02:44:41.1158Z","steps":["trace[1622546286] 'read index received' (duration: 50.801019ms)","trace[1622546286] 'applied index is now lower than readState.Index' (duration: 96.640282ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:41.116048Z","caller":"traceutil/trace.go:171","msg":"trace[141484029] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"181.83494ms","start":"2026-07-16T02:44:40.934198Z","end":"2026-07-16T02:44:41.116033Z","steps":["trace[141484029] 'process raft request' (duration: 181.297597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:44:41.116404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.028055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T02:44:41.116485Z","caller":"traceutil/trace.go:171","msg":"trace[339320311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1983; }","duration":"148.155048ms","start":"2026-07-16T02:44:40.968317Z","end":"2026-07-16T02:44:41.116472Z","steps":["trace[339320311] 'agreement among raft nodes before linearized reading' (duration: 147.961323ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:41.267487Z","caller":"traceutil/trace.go:171","msg":"trace[1721837491] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"145.895515ms","start":"2026-07-16T02:44:41.121546Z","end":"2026-07-16T02:44:41.267442Z","steps":["trace[1721837491] 'process raft request' (duration: 84.654836ms)","trace[1721837491] 'compare' (duration: 60.811718ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:41.387993Z","caller":"traceutil/trace.go:171","msg":"trace[1643852934] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"115.644664ms","start":"2026-07-16T02:44:41.27231Z","end":"2026-07-16T02:44:41.387954Z","steps":["trace[1643852934] 'process raft request' (duration: 101.004446ms)","trace[1643852934] 'compare' (duration: 14.466164ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:43.284693Z","caller":"traceutil/trace.go:171","msg":"trace[1666005700] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"155.332389ms","start":"2026-07-16T02:44:43.129322Z","end":"2026-07-16T02:44:43.284654Z","steps":["trace[1666005700] 'process raft request' (duration: 102.170703ms)","trace[1666005700] 'compare' (duration: 52.88214ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:51.243384Z","caller":"traceutil/trace.go:171","msg":"trace[1038410137] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"115.46052ms","start":"2026-07-16T02:44:51.127874Z","end":"2026-07-16T02:44:51.243334Z","steps":["trace[1038410137] 'process raft request' (duration: 115.123262ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:53.441812Z","caller":"traceutil/trace.go:171","msg":"trace[1991118873] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"107.362387ms","start":"2026-07-16T02:44:53.334398Z","end":"2026-07-16T02:44:53.441761Z","steps":["trace[1991118873] 'process raft request' (duration: 70.344435ms)","trace[1991118873] 'compare' (duration: 36.749506ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:53.599326Z","caller":"traceutil/trace.go:171","msg":"trace[600229847] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"151.345025ms","start":"2026-07-16T02:44:53.44794Z","end":"2026-07-16T02:44:53.599285Z","steps":["trace[600229847] 'process raft request' (duration: 120.305246ms)","trace[600229847] 'compare' (duration: 30.677491ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:55.799079Z","caller":"traceutil/trace.go:171","msg":"trace[87916345] linearizableReadLoop","detail":"{readStateIndex:2134; appliedIndex:2133; }","duration":"102.642415ms","start":"2026-07-16T02:44:55.6964Z","end":"2026-07-16T02:44:55.799042Z","steps":["trace[87916345] 'read index received' (duration: 77.971037ms)","trace[87916345] 'applied index is now lower than readState.Index' (duration: 24.670238ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:55.799128Z","caller":"traceutil/trace.go:171","msg":"trace[76282580] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"119.825764ms","start":"2026-07-16T02:44:55.679249Z","end":"2026-07-16T02:44:55.799075Z","steps":["trace[76282580] 'process raft request' (duration: 95.122295ms)","trace[76282580] 'compare' (duration: 24.526964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:55.799327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.907241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T02:44:55.799386Z","caller":"traceutil/trace.go:171","msg":"trace[35538741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2049; }","duration":"102.983743ms","start":"2026-07-16T02:44:55.696385Z","end":"2026-07-16T02:44:55.799369Z","steps":["trace[35538741] 'agreement among raft nodes before linearized reading' (duration: 102.757057ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:58.326062Z","caller":"traceutil/trace.go:171","msg":"trace[700066971] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"146.199591ms","start":"2026-07-16T02:44:58.179597Z","end":"2026-07-16T02:44:58.325797Z","steps":["trace[700066971] 'process raft request' (duration: 146.002037ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:44:59.850322Z","caller":"traceutil/trace.go:171","msg":"trace[1084672819] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"125.5304ms","start":"2026-07-16T02:44:59.72476Z","end":"2026-07-16T02:44:59.850291Z","steps":["trace[1084672819] 'read index received' (duration: 29.085083ms)","trace[1084672819] 'applied index is now lower than readState.Index' (duration: 96.444587ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:44:59.850421Z","caller":"traceutil/trace.go:171","msg":"trace[1317448719] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"173.448821ms","start":"2026-07-16T02:44:59.676918Z","end":"2026-07-16T02:44:59.850367Z","steps":["trace[1317448719] 'process raft request' (duration: 76.930642ms)","trace[1317448719] 'compare' (duration: 96.226722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:44:59.850513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.731364ms","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:532"} {"level":"info","ts":"2026-07-16T02:44:59.850544Z","caller":"traceutil/trace.go:171","msg":"trace[1677566379] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2065; }","duration":"125.837357ms","start":"2026-07-16T02:44:59.724698Z","end":"2026-07-16T02:44:59.850536Z","steps":["trace[1677566379] 'agreement among raft nodes before linearized reading' (duration: 125.691833ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:45:02.251025Z","caller":"traceutil/trace.go:171","msg":"trace[498128928] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"178.903211ms","start":"2026-07-16T02:45:02.07208Z","end":"2026-07-16T02:45:02.250983Z","steps":["trace[498128928] 'process raft request' (duration: 113.222377ms)","trace[498128928] 'compare' (duration: 65.47554ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T02:45:02.251252Z","caller":"traceutil/trace.go:171","msg":"trace[996999378] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"178.730127ms","start":"2026-07-16T02:45:02.072488Z","end":"2026-07-16T02:45:02.251218Z","steps":["trace[996999378] 'process raft request' (duration: 178.586264ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:47:05.452432Z","caller":"traceutil/trace.go:171","msg":"trace[1852344842] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"124.44761ms","start":"2026-07-16T02:47:05.327944Z","end":"2026-07-16T02:47:05.452392Z","steps":["trace[1852344842] 'process raft request' (duration: 123.833446ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T02:47:06.305063Z","caller":"traceutil/trace.go:171","msg":"trace[1318475774] transaction","detail":"{read_only:false; response_revision:2663; number_of_response:1; }","duration":"154.437342ms","start":"2026-07-16T02:47:06.150594Z","end":"2026-07-16T02:47:06.305031Z","steps":["trace[1318475774] 'process raft request' (duration: 154.272558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:47:07.736179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.093328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-16T02:47:07.736259Z","caller":"traceutil/trace.go:171","msg":"trace[1288587226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2667; }","duration":"820.185671ms","start":"2026-07-16T02:47:06.916054Z","end":"2026-07-16T02:47:07.73624Z","steps":["trace[1288587226] 'range keys from in-memory index tree' (duration: 819.845532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:47:07.736323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:47:06.916007Z","time spent":"820.296593ms","remote":"127.0.0.1:50134","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-07-16T02:47:07.736335Z","caller":"traceutil/trace.go:171","msg":"trace[24910875] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"275.275329ms","start":"2026-07-16T02:47:07.461011Z","end":"2026-07-16T02:47:07.736287Z","steps":["trace[24910875] 'process raft request' (duration: 225.514025ms)","trace[24910875] 'compare' (duration: 49.209701ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:47:07.736389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.078546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T02:47:07.736472Z","caller":"traceutil/trace.go:171","msg":"trace[820448658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2667; }","duration":"801.22099ms","start":"2026-07-16T02:47:06.935229Z","end":"2026-07-16T02:47:07.73645Z","steps":["trace[820448658] 'range keys from in-memory index tree' (duration: 800.690617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:47:07.736512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:47:06.935204Z","time spent":"801.298931ms","remote":"127.0.0.1:50134","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-07-16T02:47:13.04066Z","caller":"traceutil/trace.go:171","msg":"trace[758517337] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"163.364626ms","start":"2026-07-16T02:47:12.87726Z","end":"2026-07-16T02:47:13.040625Z","steps":["trace[758517337] 'process raft request' (duration: 95.210805ms)","trace[758517337] 'compare' (duration: 68.005007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:47:19.536181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.421064ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-16T02:47:19.536356Z","caller":"traceutil/trace.go:171","msg":"trace[1296552568] linearizableReadLoop","detail":"{readStateIndex:2845; appliedIndex:2844; }","duration":"307.26151ms","start":"2026-07-16T02:47:19.229069Z","end":"2026-07-16T02:47:19.536331Z","steps":["trace[1296552568] 'read index received' (duration: 48.091µs)","trace[1296552568] 'applied index is now lower than readState.Index' (duration: 307.211159ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T02:47:19.536461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.378052ms","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-07-16T02:47:19.536531Z","caller":"traceutil/trace.go:171","msg":"trace[954722811] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2730; }","duration":"307.497585ms","start":"2026-07-16T02:47:19.229017Z","end":"2026-07-16T02:47:19.536515Z","steps":["trace[954722811] 'agreement among raft nodes before linearized reading' (duration: 307.371522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T02:47:19.536558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T02:47:19.228997Z","time spent":"307.556377ms","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-16T02:47:33.428918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.514406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-07-16T02:47:33.429027Z","caller":"traceutil/trace.go:171","msg":"trace[1251971964] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:2801; }","duration":"109.70484ms","start":"2026-07-16T02:47:33.319299Z","end":"2026-07-16T02:47:33.429004Z","steps":["trace[1251971964] 'range keys from in-memory index tree' (duration: 109.385802ms)"],"step_count":1}