{"level":"warn","ts":"2026-07-22T22:08:24.450687Z","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-22T22:08:24.450794Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-22T22:08:24.450884Z","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-22T22:08:24.450901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-22T22:08:24.450937Z","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-22T22:08:24.451758Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-22T22:08:24.451902Z","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.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-22T22:08:24.454385Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.195815ms"} {"level":"info","ts":"2026-07-22T22:08:24.4596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-22T22:08:24.459771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:08:24.459789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-22T22:08:24.459814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:08:24.459822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-22T22:08:24.459907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-22T22:08:24.46563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:08:24.466789Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:08:24.467804Z","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-22T22:08:24.470151Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:08:24.470649Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:08:24.470776Z","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-22T22:08:24.470847Z","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-22T22:08:24.470855Z","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-22T22:08:24.474231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-22T22:08:24.474448Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-22T22:08:24.478052Z","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-22T22:08:24.478288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:08:24.478319Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:08:24.479199Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-22T22:08:24.479252Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-22T22:08:25.160695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:08:25.160756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:08:25.160792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-22T22:08:25.160833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:08:25.16085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-22T22:08:25.160871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-22T22:08:25.160889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-22T22:08:25.201273Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:08:25.201552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:08:25.201601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:08:25.202112Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:08:25.202213Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:08:25.20222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:08:25.203687Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-07-22T22:08:25.205069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T22:08:25.388126Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:08:25.388321Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:08:25.388582Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:08:25.388637Z","caller":"traceutil/trace.go:171","msg":"trace[1134027197] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"133.954649ms","start":"2026-07-22T22:08:25.254555Z","end":"2026-07-22T22:08:25.388509Z","steps":["trace[1134027197] 'read index received' (duration: 133.512208ms)","trace[1134027197] 'applied index is now lower than readState.Index' (duration: 441.221µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:08:25.388839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.260566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T22:08:25.388914Z","caller":"traceutil/trace.go:171","msg":"trace[168742855] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"134.362878ms","start":"2026-07-22T22:08:25.25453Z","end":"2026-07-22T22:08:25.388892Z","steps":["trace[168742855] 'agreement among raft nodes before linearized reading' (duration: 134.180804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:25.388866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.203535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T22:08:25.389104Z","caller":"traceutil/trace.go:171","msg":"trace[1364654542] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"134.466871ms","start":"2026-07-22T22:08:25.254603Z","end":"2026-07-22T22:08:25.389069Z","steps":["trace[1364654542] 'agreement among raft nodes before linearized reading' (duration: 134.191604ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:00.106824Z","caller":"traceutil/trace.go:171","msg":"trace[1928607053] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"101.477963ms","start":"2026-07-22T22:09:00.005266Z","end":"2026-07-22T22:09:00.106744Z","steps":["trace[1928607053] 'process raft request' (duration: 68.015574ms)","trace[1928607053] 'compare' (duration: 33.205133ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:00.113858Z","caller":"traceutil/trace.go:171","msg":"trace[238852271] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"101.457193ms","start":"2026-07-22T22:09:00.012348Z","end":"2026-07-22T22:09:00.113805Z","steps":["trace[238852271] 'process raft request' (duration: 101.225087ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:00.277935Z","caller":"traceutil/trace.go:171","msg":"trace[1910324057] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"104.97657ms","start":"2026-07-22T22:09:00.172933Z","end":"2026-07-22T22:09:00.27791Z","steps":["trace[1910324057] 'process raft request' (duration: 104.588851ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:00.712588Z","caller":"traceutil/trace.go:171","msg":"trace[28299016] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"105.937515ms","start":"2026-07-22T22:09:00.606581Z","end":"2026-07-22T22:09:00.712519Z","steps":["trace[28299016] 'process raft request' (duration: 92.471591ms)","trace[28299016] 'compare' (duration: 12.975851ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:01.057659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.444043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:09:01.057939Z","caller":"traceutil/trace.go:171","msg":"trace[2100342944] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"249.856069ms","start":"2026-07-22T22:09:00.80802Z","end":"2026-07-22T22:09:01.057876Z","steps":["trace[2100342944] 'process raft request' (duration: 105.39576ms)","trace[2100342944] 'compare' (duration: 143.103955ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:01.058363Z","caller":"traceutil/trace.go:171","msg":"trace[757535517] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"244.298892ms","start":"2026-07-22T22:09:00.814038Z","end":"2026-07-22T22:09:01.058337Z","steps":["trace[757535517] 'process raft request' (duration: 244.056676ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:01.060095Z","caller":"traceutil/trace.go:171","msg":"trace[94781410] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"158.6296ms","start":"2026-07-22T22:09:00.901441Z","end":"2026-07-22T22:09:01.06007Z","steps":["trace[94781410] 'process raft request' (duration: 157.393539ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:05.851345Z","caller":"traceutil/trace.go:171","msg":"trace[165851532] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"287.198894ms","start":"2026-07-22T22:09:05.564123Z","end":"2026-07-22T22:09:05.851322Z","steps":["trace[165851532] 'read index received' (duration: 286.392035ms)","trace[165851532] 'applied index is now lower than readState.Index' (duration: 805.989µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:05.851456Z","caller":"traceutil/trace.go:171","msg":"trace[1531343026] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"318.117851ms","start":"2026-07-22T22:09:05.533311Z","end":"2026-07-22T22:09:05.851429Z","steps":["trace[1531343026] 'process raft request' (duration: 317.439934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:05.851535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.39945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:09:05.851578Z","caller":"traceutil/trace.go:171","msg":"trace[2036588015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"287.491711ms","start":"2026-07-22T22:09:05.564076Z","end":"2026-07-22T22:09:05.851568Z","steps":["trace[2036588015] 'agreement among raft nodes before linearized reading' (duration: 287.347608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:05.851687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:05.533288Z","time spent":"318.302955ms","remote":"127.0.0.1:45832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:09:06.824038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.314286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:06.824116Z","caller":"traceutil/trace.go:171","msg":"trace[610825404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"687.428239ms","start":"2026-07-22T22:09:06.13667Z","end":"2026-07-22T22:09:06.824098Z","steps":["trace[610825404] 'range keys from in-memory index tree' (duration: 687.246954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:06.824157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:06.136656Z","time spent":"687.493331ms","remote":"127.0.0.1:45738","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-22T22:09:06.825012Z","caller":"traceutil/trace.go:171","msg":"trace[1468613965] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"205.48547ms","start":"2026-07-22T22:09:06.619276Z","end":"2026-07-22T22:09:06.824761Z","steps":["trace[1468613965] 'read index received' (duration: 202.150887ms)","trace[1468613965] 'applied index is now lower than readState.Index' (duration: 3.333483ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:06.825034Z","caller":"traceutil/trace.go:171","msg":"trace[1376517205] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"437.350685ms","start":"2026-07-22T22:09:06.387647Z","end":"2026-07-22T22:09:06.824998Z","steps":["trace[1376517205] 'process raft request' (duration: 434.010532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:06.826567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:06.387623Z","time spent":"438.882102ms","remote":"127.0.0.1:45832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:09:06.826331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.047029ms","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-22T22:09:06.827411Z","caller":"traceutil/trace.go:171","msg":"trace[951354337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"208.146776ms","start":"2026-07-22T22:09:06.619215Z","end":"2026-07-22T22:09:06.827362Z","steps":["trace[951354337] 'agreement among raft nodes before linearized reading' (duration: 205.855219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:06.828262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.163107ms","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-22T22:09:06.828342Z","caller":"traceutil/trace.go:171","msg":"trace[31933717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"187.244129ms","start":"2026-07-22T22:09:06.641065Z","end":"2026-07-22T22:09:06.828309Z","steps":["trace[31933717] 'agreement among raft nodes before linearized reading' (duration: 186.826759ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:30.712842Z","caller":"traceutil/trace.go:171","msg":"trace[253795999] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"309.071442ms","start":"2026-07-22T22:09:30.403733Z","end":"2026-07-22T22:09:30.712805Z","steps":["trace[253795999] 'process raft request' (duration: 308.889098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:30.713295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:30.40371Z","time spent":"309.212915ms","remote":"127.0.0.1:46042","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-22T22:09:31.169258Z","caller":"traceutil/trace.go:171","msg":"trace[458313320] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"233.058181ms","start":"2026-07-22T22:09:30.936177Z","end":"2026-07-22T22:09:31.169235Z","steps":["trace[458313320] 'process raft request' (duration: 232.943868ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:31.173738Z","caller":"traceutil/trace.go:171","msg":"trace[673010110] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"236.583326ms","start":"2026-07-22T22:09:30.937126Z","end":"2026-07-22T22:09:31.17371Z","steps":["trace[673010110] 'process raft request' (duration: 236.093344ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:32.174302Z","caller":"traceutil/trace.go:171","msg":"trace[93039760] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"181.486712ms","start":"2026-07-22T22:09:31.992786Z","end":"2026-07-22T22:09:32.174272Z","steps":["trace[93039760] 'read index received' (duration: 181.335628ms)","trace[93039760] 'applied index is now lower than readState.Index' (duration: 149.624µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:32.174421Z","caller":"traceutil/trace.go:171","msg":"trace[1793083923] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"203.63438ms","start":"2026-07-22T22:09:31.970725Z","end":"2026-07-22T22:09:32.174359Z","steps":["trace[1793083923] 'process raft request' (duration: 203.355913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:32.174585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.77805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:09:32.174929Z","caller":"traceutil/trace.go:171","msg":"trace[336548544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"182.16753ms","start":"2026-07-22T22:09:31.99274Z","end":"2026-07-22T22:09:32.174908Z","steps":["trace[336548544] 'agreement among raft nodes before linearized reading' (duration: 181.696017ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:21.655909Z","caller":"traceutil/trace.go:171","msg":"trace[1572352509] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"207.230135ms","start":"2026-07-22T22:10:21.448657Z","end":"2026-07-22T22:10:21.655887Z","steps":["trace[1572352509] 'read index received' (duration: 207.04145ms)","trace[1572352509] 'applied index is now lower than readState.Index' (duration: 187.705µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:21.656027Z","caller":"traceutil/trace.go:171","msg":"trace[1229022446] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"210.840106ms","start":"2026-07-22T22:10:21.445155Z","end":"2026-07-22T22:10:21.655995Z","steps":["trace[1229022446] 'process raft request' (duration: 210.58332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:21.656254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.575284ms","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-22T22:10:21.656457Z","caller":"traceutil/trace.go:171","msg":"trace[2047666370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1049; }","duration":"207.813469ms","start":"2026-07-22T22:10:21.448625Z","end":"2026-07-22T22:10:21.656438Z","steps":["trace[2047666370] 'agreement among raft nodes before linearized reading' (duration: 207.476251ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:22.01136Z","caller":"traceutil/trace.go:171","msg":"trace[494817968] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"351.005701ms","start":"2026-07-22T22:10:21.660317Z","end":"2026-07-22T22:10:22.011323Z","steps":["trace[494817968] 'process raft request' (duration: 330.354969ms)","trace[494817968] 'compare' (duration: 20.476266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:22.011532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:21.660289Z","time spent":"351.167395ms","remote":"127.0.0.1:46042","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-22T22:10:42.803553Z","caller":"traceutil/trace.go:171","msg":"trace[1500881163] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"286.456873ms","start":"2026-07-22T22:10:42.517074Z","end":"2026-07-22T22:10:42.803531Z","steps":["trace[1500881163] 'read index received' (duration: 286.34229ms)","trace[1500881163] 'applied index is now lower than readState.Index' (duration: 113.553µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:42.803595Z","caller":"traceutil/trace.go:171","msg":"trace[1967685709] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"287.804827ms","start":"2026-07-22T22:10:42.515763Z","end":"2026-07-22T22:10:42.803568Z","steps":["trace[1967685709] 'process raft request' (duration: 287.633062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:42.803766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.667609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-22T22:10:42.803771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.246847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-22T22:10:42.803795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.214513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:10:42.803834Z","caller":"traceutil/trace.go:171","msg":"trace[286658330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"286.332619ms","start":"2026-07-22T22:10:42.517485Z","end":"2026-07-22T22:10:42.803817Z","steps":["trace[286658330] 'agreement among raft nodes before linearized reading' (duration: 286.181585ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:42.803793Z","caller":"traceutil/trace.go:171","msg":"trace[1192689753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1117; }","duration":"286.74425ms","start":"2026-07-22T22:10:42.517042Z","end":"2026-07-22T22:10:42.803786Z","steps":["trace[1192689753] 'agreement among raft nodes before linearized reading' (duration: 286.609207ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:42.803889Z","caller":"traceutil/trace.go:171","msg":"trace[1197757424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"174.363106ms","start":"2026-07-22T22:10:42.629504Z","end":"2026-07-22T22:10:42.803867Z","steps":["trace[1197757424] 'agreement among raft nodes before linearized reading' (duration: 174.209412ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:48.417999Z","caller":"traceutil/trace.go:171","msg":"trace[1617854855] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"233.181399ms","start":"2026-07-22T22:10:48.184793Z","end":"2026-07-22T22:10:48.417975Z","steps":["trace[1617854855] 'process raft request' (duration: 228.43539ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:48.436918Z","caller":"traceutil/trace.go:171","msg":"trace[1954170591] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"249.794719ms","start":"2026-07-22T22:10:48.187093Z","end":"2026-07-22T22:10:48.436888Z","steps":["trace[1954170591] 'process raft request' (duration: 249.592163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:49.75859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.42185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:10:49.758783Z","caller":"traceutil/trace.go:171","msg":"trace[1788897023] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1190; }","duration":"621.331775ms","start":"2026-07-22T22:10:49.137431Z","end":"2026-07-22T22:10:49.758763Z","steps":["trace[1788897023] 'read index received' (duration: 81.635728ms)","trace[1788897023] 'applied index is now lower than readState.Index' (duration: 539.695007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:49.758871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.441777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:10:49.758903Z","caller":"traceutil/trace.go:171","msg":"trace[82801692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"621.503349ms","start":"2026-07-22T22:10:49.137391Z","end":"2026-07-22T22:10:49.758894Z","steps":["trace[82801692] 'agreement among raft nodes before linearized reading' (duration: 621.421137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:49.758932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:49.137371Z","time spent":"621.555041ms","remote":"127.0.0.1:45738","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-22T22:10:49.759049Z","caller":"traceutil/trace.go:171","msg":"trace[1302810954] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"777.70424ms","start":"2026-07-22T22:10:48.981327Z","end":"2026-07-22T22:10:49.759031Z","steps":["trace[1302810954] 'process raft request' (duration: 777.36112ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:49.758992Z","caller":"traceutil/trace.go:171","msg":"trace[867116976] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"786.989823ms","start":"2026-07-22T22:10:48.971968Z","end":"2026-07-22T22:10:49.758958Z","steps":["trace[867116976] 'process raft request' (duration: 247.11527ms)","trace[867116976] 'compare' (duration: 539.063002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:49.759189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:48.981309Z","time spent":"777.794881ms","remote":"127.0.0.1:46042","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-22T22:10:49.759194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:48.971946Z","time spent":"787.194708ms","remote":"127.0.0.1:46042","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-22T22:10:50.953176Z","caller":"traceutil/trace.go:171","msg":"trace[1548499838] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"107.86776ms","start":"2026-07-22T22:10:50.845211Z","end":"2026-07-22T22:10:50.953079Z","steps":["trace[1548499838] 'process raft request' (duration: 107.580793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.890758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227694841894610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:11:04.987178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.184508095s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:11:04.989233Z","caller":"traceutil/trace.go:171","msg":"trace[95613337] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"1.146366513s","start":"2026-07-22T22:11:03.842844Z","end":"2026-07-22T22:11:04.989211Z","steps":["trace[95613337] 'read index received' (duration: 1.146066796s)","trace[95613337] 'applied index is now lower than readState.Index' (duration: 298.747µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:04.989515Z","caller":"traceutil/trace.go:171","msg":"trace[1298133776] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"1.186980168s","start":"2026-07-22T22:11:03.802522Z","end":"2026-07-22T22:11:04.989502Z","steps":["trace[1298133776] 'process raft request' (duration: 1.186447064s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.989618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:03.8025Z","time spent":"1.187052629s","remote":"127.0.0.1:46042","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-22T22:11:04.989958Z","caller":"traceutil/trace.go:171","msg":"trace[1300017782] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"1.129716084s","start":"2026-07-22T22:11:03.860228Z","end":"2026-07-22T22:11:04.989944Z","steps":["trace[1300017782] 'process raft request' (duration: 1.128936244s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.990023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:03.860204Z","time spent":"1.129788626s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:04.990173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147340359s","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-22T22:11:04.9902Z","caller":"traceutil/trace.go:171","msg":"trace[397558857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"1.147402801s","start":"2026-07-22T22:11:03.842789Z","end":"2026-07-22T22:11:04.990192Z","steps":["trace[397558857] 'agreement among raft nodes before linearized reading' (duration: 1.147299888s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:04.990227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:03.842768Z","time spent":"1.147453471s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T22:11:19.329166Z","caller":"traceutil/trace.go:171","msg":"trace[979498801] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"146.051942ms","start":"2026-07-22T22:11:19.183094Z","end":"2026-07-22T22:11:19.329146Z","steps":["trace[979498801] 'process raft request' (duration: 144.987156ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:23.246817Z","caller":"traceutil/trace.go:171","msg":"trace[843532603] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"123.971379ms","start":"2026-07-22T22:11:23.122825Z","end":"2026-07-22T22:11:23.246797Z","steps":["trace[843532603] 'process raft request' (duration: 123.410144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:23.859439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227694841894791,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:11:24.118278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.849405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:24.11841Z","caller":"traceutil/trace.go:171","msg":"trace[1893224948] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"759.546463ms","start":"2026-07-22T22:11:23.35884Z","end":"2026-07-22T22:11:24.118386Z","steps":["trace[1893224948] 'read index received' (duration: 505.371141ms)","trace[1893224948] 'applied index is now lower than readState.Index' (duration: 254.173902ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:24.118432Z","caller":"traceutil/trace.go:171","msg":"trace[389729525] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"762.094217ms","start":"2026-07-22T22:11:23.356307Z","end":"2026-07-22T22:11:24.118401Z","steps":["trace[389729525] 'process raft request' (duration: 508.030367ms)","trace[389729525] 'compare' (duration: 253.705561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:24.118573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.911063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T22:11:24.118585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:23.356298Z","time spent":"762.201669ms","remote":"127.0.0.1:45918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:24.118614Z","caller":"traceutil/trace.go:171","msg":"trace[758242559] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1317; }","duration":"624.000685ms","start":"2026-07-22T22:11:23.494604Z","end":"2026-07-22T22:11:24.118604Z","steps":["trace[758242559] 'agreement among raft nodes before linearized reading' (duration: 623.924083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.11865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:23.49457Z","time spent":"624.070627ms","remote":"127.0.0.1:45918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:24.118634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.876431ms","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-22T22:11:24.118709Z","caller":"traceutil/trace.go:171","msg":"trace[1797182165] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1317; }","duration":"327.995543ms","start":"2026-07-22T22:11:23.790696Z","end":"2026-07-22T22:11:24.118691Z","steps":["trace[1797182165] 'agreement among raft nodes before linearized reading' (duration: 327.85971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.118719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.847341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5jhhn\" ","response":"range_response_count:1 size:11061"} {"level":"warn","ts":"2026-07-22T22:11:24.118759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:23.790682Z","time spent":"328.067255ms","remote":"127.0.0.1:46270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:11:24.118813Z","caller":"traceutil/trace.go:171","msg":"trace[211033645] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5jhhn; range_end:; response_count:1; response_revision:1317; }","duration":"759.973983ms","start":"2026-07-22T22:11:23.358817Z","end":"2026-07-22T22:11:24.118791Z","steps":["trace[211033645] 'agreement among raft nodes before linearized reading' (duration: 759.667536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.118874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:23.358804Z","time spent":"760.064626ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11083,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-5jhhn\" "} {"level":"warn","ts":"2026-07-22T22:11:24.485181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.132577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:24.485292Z","caller":"traceutil/trace.go:171","msg":"trace[1309973446] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"357.392061ms","start":"2026-07-22T22:11:24.127881Z","end":"2026-07-22T22:11:24.485273Z","steps":["trace[1309973446] 'process raft request' (duration: 108.08637ms)","trace[1309973446] 'compare' (duration: 248.819838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:24.485388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:24.127869Z","time spent":"357.460723ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:24.756904Z","caller":"traceutil/trace.go:171","msg":"trace[1179877301] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1378; }","duration":"620.60824ms","start":"2026-07-22T22:11:24.136264Z","end":"2026-07-22T22:11:24.756872Z","steps":["trace[1179877301] 'read index received' (duration: 99.716471ms)","trace[1179877301] 'applied index is now lower than readState.Index' (duration: 520.890829ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:24.75703Z","caller":"traceutil/trace.go:171","msg":"trace[489518209] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"622.380705ms","start":"2026-07-22T22:11:24.134614Z","end":"2026-07-22T22:11:24.756995Z","steps":["trace[489518209] 'process raft request' (duration: 622.043426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.75707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.770764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:24.757203Z","caller":"traceutil/trace.go:171","msg":"trace[892197076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"620.974769ms","start":"2026-07-22T22:11:24.136212Z","end":"2026-07-22T22:11:24.757187Z","steps":["trace[892197076] 'agreement among raft nodes before linearized reading' (duration: 620.780904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:24.757216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:24.13459Z","time spent":"622.523208ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:24.757242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:24.136203Z","time spent":"621.0297ms","remote":"127.0.0.1:45738","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-22T22:11:24.914612Z","caller":"traceutil/trace.go:171","msg":"trace[336053326] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"150.945215ms","start":"2026-07-22T22:11:24.763621Z","end":"2026-07-22T22:11:24.914567Z","steps":["trace[336053326] 'process raft request' (duration: 123.974868ms)","trace[336053326] 'compare' (duration: 26.718071ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:24.989623Z","caller":"traceutil/trace.go:171","msg":"trace[816643044] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"225.525315ms","start":"2026-07-22T22:11:24.76406Z","end":"2026-07-22T22:11:24.989586Z","steps":["trace[816643044] 'process raft request' (duration: 225.264048ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:24.989691Z","caller":"traceutil/trace.go:171","msg":"trace[454079695] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"220.636362ms","start":"2026-07-22T22:11:24.769022Z","end":"2026-07-22T22:11:24.989658Z","steps":["trace[454079695] 'process raft request' (duration: 220.462997ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.50677Z","caller":"traceutil/trace.go:171","msg":"trace[703242505] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"193.608354ms","start":"2026-07-22T22:11:25.313118Z","end":"2026-07-22T22:11:25.506726Z","steps":["trace[703242505] 'process raft request' (duration: 193.42621ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.538455Z","caller":"traceutil/trace.go:171","msg":"trace[992444875] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"224.710445ms","start":"2026-07-22T22:11:25.313729Z","end":"2026-07-22T22:11:25.53844Z","steps":["trace[992444875] 'process raft request' (duration: 224.5215ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:25.538389Z","caller":"traceutil/trace.go:171","msg":"trace[303150221] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1384; }","duration":"218.281503ms","start":"2026-07-22T22:11:25.320086Z","end":"2026-07-22T22:11:25.538368Z","steps":["trace[303150221] 'read index received' (duration: 186.572847ms)","trace[303150221] 'applied index is now lower than readState.Index' (duration: 31.707726ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:25.538792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.676273ms","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-22T22:11:25.538871Z","caller":"traceutil/trace.go:171","msg":"trace[442452392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1325; }","duration":"218.807046ms","start":"2026-07-22T22:11:25.320018Z","end":"2026-07-22T22:11:25.538826Z","steps":["trace[442452392] 'agreement among raft nodes before linearized reading' (duration: 218.654363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:26.039682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227694841894811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:11:26.606929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.982323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:26.607088Z","caller":"traceutil/trace.go:171","msg":"trace[921330741] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1386; }","duration":"1.068430777s","start":"2026-07-22T22:11:25.538644Z","end":"2026-07-22T22:11:26.607074Z","steps":["trace[921330741] 'read index received' (duration: 545.257ms)","trace[921330741] 'applied index is now lower than readState.Index' (duration: 523.173027ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:26.60716Z","caller":"traceutil/trace.go:171","msg":"trace[1263774375] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.093556587s","start":"2026-07-22T22:11:25.513596Z","end":"2026-07-22T22:11:26.607153Z","steps":["trace[1263774375] 'process raft request' (duration: 570.289497ms)","trace[1263774375] 'compare' (duration: 522.87936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:26.6072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:25.513573Z","time spent":"1.093608629s","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:11:26.607473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287264623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-22T22:11:26.607444Z","caller":"traceutil/trace.go:171","msg":"trace[1821602259] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.064282584s","start":"2026-07-22T22:11:25.543105Z","end":"2026-07-22T22:11:26.607388Z","steps":["trace[1821602259] 'process raft request' (duration: 1.063917375s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:26.607504Z","caller":"traceutil/trace.go:171","msg":"trace[848919408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.287338415s","start":"2026-07-22T22:11:25.320156Z","end":"2026-07-22T22:11:26.607494Z","steps":["trace[848919408] 'agreement among raft nodes before linearized reading' (duration: 1.287188112s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:26.607527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:25.320134Z","time spent":"1.287388757s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:26.607538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286275439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-22T22:11:26.607607Z","caller":"traceutil/trace.go:171","msg":"trace[1428052515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.286375482s","start":"2026-07-22T22:11:25.321217Z","end":"2026-07-22T22:11:26.607592Z","steps":["trace[1428052515] 'agreement among raft nodes before linearized reading' (duration: 1.286108695s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:26.607604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:25.543085Z","time spent":"1.064433228s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:26.607599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249418895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-22T22:11:26.607646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:25.321189Z","time spent":"1.286449094s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T22:11:26.607663Z","caller":"traceutil/trace.go:171","msg":"trace[2008491743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.249528228s","start":"2026-07-22T22:11:25.358118Z","end":"2026-07-22T22:11:26.607646Z","steps":["trace[2008491743] 'agreement among raft nodes before linearized reading' (duration: 1.249337133s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:26.607705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:25.358108Z","time spent":"1.249589299s","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:26.607754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.602205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130421"} {"level":"warn","ts":"2026-07-22T22:11:26.607597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.69048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:26.607785Z","caller":"traceutil/trace.go:171","msg":"trace[680641854] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1327; }","duration":"205.631175ms","start":"2026-07-22T22:11:26.402146Z","end":"2026-07-22T22:11:26.607778Z","steps":["trace[680641854] 'agreement among raft nodes before linearized reading' (duration: 205.482832ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:26.607831Z","caller":"traceutil/trace.go:171","msg":"trace[902460222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"468.939967ms","start":"2026-07-22T22:11:26.138862Z","end":"2026-07-22T22:11:26.607802Z","steps":["trace[902460222] 'agreement among raft nodes before linearized reading' (duration: 468.693571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:26.607766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.324898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-22T22:11:26.607951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.138844Z","time spent":"469.0724ms","remote":"127.0.0.1:45738","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-22T22:11:26.607975Z","caller":"traceutil/trace.go:171","msg":"trace[2013531449] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1327; }","duration":"207.567294ms","start":"2026-07-22T22:11:26.400395Z","end":"2026-07-22T22:11:26.607963Z","steps":["trace[2013531449] 'agreement among raft nodes before linearized reading' (duration: 207.339498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.219308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.637661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:27.219386Z","caller":"traceutil/trace.go:171","msg":"trace[753703238] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"606.758603ms","start":"2026-07-22T22:11:26.612615Z","end":"2026-07-22T22:11:27.219373Z","steps":["trace[753703238] 'process raft request' (duration: 268.00736ms)","trace[753703238] 'compare' (duration: 338.513797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:27.219419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.612598Z","time spent":"606.806845ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:27.885984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.500557ms","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-22T22:11:27.886083Z","caller":"traceutil/trace.go:171","msg":"trace[1186763258] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1328; }","duration":"129.61435ms","start":"2026-07-22T22:11:27.756454Z","end":"2026-07-22T22:11:27.886068Z","steps":["trace[1186763258] 'range keys from in-memory index tree' (duration: 129.480917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.934005Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.05341117s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:11:27.934565Z","caller":"traceutil/trace.go:171","msg":"trace[1183934745] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"1.321911803s","start":"2026-07-22T22:11:26.612631Z","end":"2026-07-22T22:11:27.934543Z","steps":["trace[1183934745] 'process raft request' (duration: 1.321642756s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.934759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.612611Z","time spent":"1.322047756s","remote":"127.0.0.1:46042","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-22T22:11:27.93476Z","caller":"traceutil/trace.go:171","msg":"trace[579898163] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"1.321101233s","start":"2026-07-22T22:11:26.613634Z","end":"2026-07-22T22:11:27.934736Z","steps":["trace[579898163] 'process raft request' (duration: 1.320835396s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.934857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.613625Z","time spent":"1.321187545s","remote":"127.0.0.1:46042","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-22T22:11:27.93486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321742548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130421"} {"level":"info","ts":"2026-07-22T22:11:27.934914Z","caller":"traceutil/trace.go:171","msg":"trace[84743506] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1330; }","duration":"1.321818651s","start":"2026-07-22T22:11:26.613085Z","end":"2026-07-22T22:11:27.934903Z","steps":["trace[84743506] 'agreement among raft nodes before linearized reading' (duration: 1.321518173s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:27.934547Z","caller":"traceutil/trace.go:171","msg":"trace[232717481] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1388; }","duration":"1.32141099s","start":"2026-07-22T22:11:26.613107Z","end":"2026-07-22T22:11:27.934518Z","steps":["trace[232717481] 'read index received' (duration: 267.458736ms)","trace[232717481] 'applied index is now lower than readState.Index' (duration: 1.053951004s)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:27.934947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:26.613076Z","time spent":"1.321863492s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130443,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-22T22:11:27.959032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.123314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:11:27.959061Z","caller":"traceutil/trace.go:171","msg":"trace[1592448759] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"644.018646ms","start":"2026-07-22T22:11:27.315019Z","end":"2026-07-22T22:11:27.959037Z","steps":["trace[1592448759] 'process raft request' (duration: 643.787671ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:27.959091Z","caller":"traceutil/trace.go:171","msg":"trace[882745452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"416.217025ms","start":"2026-07-22T22:11:27.542861Z","end":"2026-07-22T22:11:27.959078Z","steps":["trace[882745452] 'agreement among raft nodes before linearized reading' (duration: 416.060351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:27.959118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.542845Z","time spent":"416.269437ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:11:27.95917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:27.314982Z","time spent":"644.131229ms","remote":"127.0.0.1:46042","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-22T22:11:28.810529Z","caller":"traceutil/trace.go:171","msg":"trace[271902256] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"324.614329ms","start":"2026-07-22T22:11:28.485881Z","end":"2026-07-22T22:11:28.810495Z","steps":["trace[271902256] 'process raft request' (duration: 276.997295ms)","trace[271902256] 'compare' (duration: 47.204923ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:28.810578Z","caller":"traceutil/trace.go:171","msg":"trace[705822110] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"198.501917ms","start":"2026-07-22T22:11:28.612057Z","end":"2026-07-22T22:11:28.810559Z","steps":["trace[705822110] 'read index received' (duration: 150.842102ms)","trace[705822110] 'applied index is now lower than readState.Index' (duration: 47.659015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:28.810666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.485859Z","time spent":"324.733171ms","remote":"127.0.0.1:45774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:28.810894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.803595ms","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-22T22:11:28.810997Z","caller":"traceutil/trace.go:171","msg":"trace[68844664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"198.956039ms","start":"2026-07-22T22:11:28.612014Z","end":"2026-07-22T22:11:28.81097Z","steps":["trace[68844664] 'agreement among raft nodes before linearized reading' (duration: 198.639271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:29.284011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.30921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:29.284603Z","caller":"traceutil/trace.go:171","msg":"trace[1184954894] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"390.266235ms","start":"2026-07-22T22:11:28.894267Z","end":"2026-07-22T22:11:29.284533Z","steps":["trace[1184954894] 'process raft request' (duration: 16.346549ms)","trace[1184954894] 'compare' (duration: 373.063604ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:29.284622Z","caller":"traceutil/trace.go:171","msg":"trace[64310123] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"384.09973ms","start":"2026-07-22T22:11:28.900489Z","end":"2026-07-22T22:11:29.284589Z","steps":["trace[64310123] 'process raft request' (duration: 383.928796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:29.284712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.894238Z","time spent":"390.433298ms","remote":"127.0.0.1:46042","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-22T22:11:29.28478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:28.900467Z","time spent":"384.227323ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:11:29.284759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.266387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:29.284896Z","caller":"traceutil/trace.go:171","msg":"trace[1522841740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"148.439292ms","start":"2026-07-22T22:11:29.136432Z","end":"2026-07-22T22:11:29.284872Z","steps":["trace[1522841740] 'agreement among raft nodes before linearized reading' (duration: 148.219127ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:29.285518Z","caller":"traceutil/trace.go:171","msg":"trace[101041105] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1397; }","duration":"148.060683ms","start":"2026-07-22T22:11:29.13647Z","end":"2026-07-22T22:11:29.284531Z","steps":["trace[101041105] 'read index received' (duration: 38.461µs)","trace[101041105] 'applied index is now lower than readState.Index' (duration: 148.020821ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:29.861476Z","caller":"traceutil/trace.go:171","msg":"trace[1522455353] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"568.736799ms","start":"2026-07-22T22:11:29.292703Z","end":"2026-07-22T22:11:29.86144Z","steps":["trace[1522455353] 'process raft request' (duration: 568.669487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:29.861711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:29.292666Z","time spent":"568.934434ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:11:29.861895Z","caller":"traceutil/trace.go:171","msg":"trace[488702145] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"570.76007ms","start":"2026-07-22T22:11:29.291099Z","end":"2026-07-22T22:11:29.861859Z","steps":["trace[488702145] 'process raft request' (duration: 569.943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:29.862629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:29.291084Z","time spent":"570.875973ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:30.120067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.823603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:30.120459Z","caller":"traceutil/trace.go:171","msg":"trace[893065716] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"367.000811ms","start":"2026-07-22T22:11:29.753438Z","end":"2026-07-22T22:11:30.120439Z","steps":["trace[893065716] 'read index received' (duration: 108.129951ms)","trace[893065716] 'applied index is now lower than readState.Index' (duration: 258.86948ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:30.120581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.139465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:30.120612Z","caller":"traceutil/trace.go:171","msg":"trace[1047466575] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1341; }","duration":"367.211576ms","start":"2026-07-22T22:11:29.75339Z","end":"2026-07-22T22:11:30.120602Z","steps":["trace[1047466575] 'agreement among raft nodes before linearized reading' (duration: 367.122614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.120649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:29.753369Z","time spent":"367.270138ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:11:30.120857Z","caller":"traceutil/trace.go:171","msg":"trace[1692828877] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"782.020967ms","start":"2026-07-22T22:11:29.338771Z","end":"2026-07-22T22:11:30.120792Z","steps":["trace[1692828877] 'process raft request' (duration: 543.369843ms)","trace[1692828877] 'compare' (duration: 237.67205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:30.121286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:29.338749Z","time spent":"782.252052ms","remote":"127.0.0.1:46042","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-22T22:11:30.193677Z","caller":"traceutil/trace.go:171","msg":"trace[964437477] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"229.564495ms","start":"2026-07-22T22:11:29.964078Z","end":"2026-07-22T22:11:30.193643Z","steps":["trace[964437477] 'process raft request' (duration: 229.3391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.19376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.231323ms","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-22T22:11:30.19384Z","caller":"traceutil/trace.go:171","msg":"trace[1349039782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"254.403628ms","start":"2026-07-22T22:11:29.939419Z","end":"2026-07-22T22:11:30.193822Z","steps":["trace[1349039782] 'agreement among raft nodes before linearized reading' (duration: 254.156502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.193875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.140561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-22T22:11:30.193889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.666447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:11:30.193934Z","caller":"traceutil/trace.go:171","msg":"trace[1533038794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1342; }","duration":"254.236393ms","start":"2026-07-22T22:11:29.939681Z","end":"2026-07-22T22:11:30.193918Z","steps":["trace[1533038794] 'agreement among raft nodes before linearized reading' (duration: 254.038088ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:30.193962Z","caller":"traceutil/trace.go:171","msg":"trace[1026968168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"217.77996ms","start":"2026-07-22T22:11:29.976162Z","end":"2026-07-22T22:11:30.193942Z","steps":["trace[1026968168] 'agreement among raft nodes before linearized reading' (duration: 217.533944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.376803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.261934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:30.376971Z","caller":"traceutil/trace.go:171","msg":"trace[122385767] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"179.935041ms","start":"2026-07-22T22:11:30.196986Z","end":"2026-07-22T22:11:30.376921Z","steps":["trace[122385767] 'process raft request' (duration: 73.482652ms)","trace[122385767] 'compare' (duration: 106.109621ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:30.73558Z","caller":"traceutil/trace.go:171","msg":"trace[559717382] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"536.893731ms","start":"2026-07-22T22:11:30.198649Z","end":"2026-07-22T22:11:30.735542Z","steps":["trace[559717382] 'process raft request' (duration: 536.151003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.735742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.19864Z","time spent":"537.026254ms","remote":"127.0.0.1:46042","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-22T22:11:30.736223Z","caller":"traceutil/trace.go:171","msg":"trace[84531561] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"349.8011ms","start":"2026-07-22T22:11:30.386398Z","end":"2026-07-22T22:11:30.736199Z","steps":["trace[84531561] 'read index received' (duration: 348.422536ms)","trace[84531561] 'applied index is now lower than readState.Index' (duration: 1.377664ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:30.736357Z","caller":"traceutil/trace.go:171","msg":"trace[58867549] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"537.085366ms","start":"2026-07-22T22:11:30.199259Z","end":"2026-07-22T22:11:30.736345Z","steps":["trace[58867549] 'process raft request' (duration: 536.207624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.736431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.193681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:11:30.736449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.199253Z","time spent":"537.128367ms","remote":"127.0.0.1:46042","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-22T22:11:30.736421Z","caller":"traceutil/trace.go:171","msg":"trace[10866789] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"104.10833ms","start":"2026-07-22T22:11:30.632277Z","end":"2026-07-22T22:11:30.736385Z","steps":["trace[10866789] 'process raft request' (duration: 103.877714ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:30.736479Z","caller":"traceutil/trace.go:171","msg":"trace[610661326] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1346; }","duration":"224.296463ms","start":"2026-07-22T22:11:30.512166Z","end":"2026-07-22T22:11:30.736463Z","steps":["trace[610661326] 'agreement among raft nodes before linearized reading' (duration: 224.206031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.736686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.290992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:30.736719Z","caller":"traceutil/trace.go:171","msg":"trace[754463788] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1346; }","duration":"350.350314ms","start":"2026-07-22T22:11:30.38636Z","end":"2026-07-22T22:11:30.73671Z","steps":["trace[754463788] 'agreement among raft nodes before linearized reading' (duration: 350.301443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:30.736746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:30.386346Z","time spent":"350.393325ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:36.62217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.424255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-wjlxh\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-07-22T22:11:36.622359Z","caller":"traceutil/trace.go:171","msg":"trace[2011625095] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-wjlxh; range_end:; response_count:1; response_revision:1392; }","duration":"202.65082ms","start":"2026-07-22T22:11:36.419687Z","end":"2026-07-22T22:11:36.622338Z","steps":["trace[2011625095] 'range keys from in-memory index tree' (duration: 202.279241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:36.622854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.25094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:11:36.622948Z","caller":"traceutil/trace.go:171","msg":"trace[110475309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1392; }","duration":"200.388893ms","start":"2026-07-22T22:11:36.422535Z","end":"2026-07-22T22:11:36.622924Z","steps":["trace[110475309] 'range keys from in-memory index tree' (duration: 199.812739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.28987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227694841894944,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:37.563984Z","caller":"traceutil/trace.go:171","msg":"trace[1427626759] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1456; }","duration":"775.147044ms","start":"2026-07-22T22:11:36.788815Z","end":"2026-07-22T22:11:37.563962Z","steps":["trace[1427626759] 'read index received' (duration: 774.923308ms)","trace[1427626759] 'applied index is now lower than readState.Index' (duration: 222.706µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:37.564607Z","caller":"traceutil/trace.go:171","msg":"trace[1826636820] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"931.337821ms","start":"2026-07-22T22:11:36.633258Z","end":"2026-07-22T22:11:37.564596Z","steps":["trace[1826636820] 'process raft request' (duration: 930.577161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.564861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.968985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:37.564926Z","caller":"traceutil/trace.go:171","msg":"trace[932293849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"427.070308ms","start":"2026-07-22T22:11:37.137839Z","end":"2026-07-22T22:11:37.564909Z","steps":["trace[932293849] 'agreement among raft nodes before linearized reading' (duration: 426.894204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.56496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:37.137828Z","time spent":"427.125559ms","remote":"127.0.0.1:45738","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-22T22:11:37.564976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.449834ms","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:507"} {"level":"warn","ts":"2026-07-22T22:11:37.564894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.068227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-22T22:11:37.565052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.205104ms","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-22T22:11:37.565001Z","caller":"traceutil/trace.go:171","msg":"trace[1083688548] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1394; }","duration":"460.507976ms","start":"2026-07-22T22:11:37.104485Z","end":"2026-07-22T22:11:37.564993Z","steps":["trace[1083688548] 'agreement among raft nodes before linearized reading' (duration: 460.401953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.565134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.820592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:37.565224Z","caller":"traceutil/trace.go:171","msg":"trace[1022333412] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1394; }","duration":"306.940866ms","start":"2026-07-22T22:11:37.258273Z","end":"2026-07-22T22:11:37.565213Z","steps":["trace[1022333412] 'agreement among raft nodes before linearized reading' (duration: 306.840543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.565256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:37.258235Z","time spent":"307.014027ms","remote":"127.0.0.1:50192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:37.565201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:37.104474Z","time spent":"460.66357ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-22T22:11:37.565049Z","caller":"traceutil/trace.go:171","msg":"trace[367096246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1394; }","duration":"776.259002ms","start":"2026-07-22T22:11:36.788779Z","end":"2026-07-22T22:11:37.565038Z","steps":["trace[367096246] 'agreement among raft nodes before linearized reading' (duration: 775.988625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.565501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.788753Z","time spent":"776.739393ms","remote":"127.0.0.1:46042","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-22T22:11:37.565714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.704043ms","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-22T22:11:37.565822Z","caller":"traceutil/trace.go:171","msg":"trace[2118087466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1394; }","duration":"776.864147ms","start":"2026-07-22T22:11:36.788933Z","end":"2026-07-22T22:11:37.565797Z","steps":["trace[2118087466] 'agreement among raft nodes before linearized reading' (duration: 775.78372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.565884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.788834Z","time spent":"777.036121ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:37.56658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.633239Z","time spent":"931.445823ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:37.566349Z","caller":"traceutil/trace.go:171","msg":"trace[1045928596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1394; }","duration":"541.536587ms","start":"2026-07-22T22:11:37.024797Z","end":"2026-07-22T22:11:37.566334Z","steps":["trace[1045928596] 'agreement among raft nodes before linearized reading' (duration: 540.219375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:37.567028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:37.024752Z","time spent":"542.111231ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T22:15:46.209196Z","caller":"traceutil/trace.go:171","msg":"trace[9674982] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"110.50014ms","start":"2026-07-22T22:15:46.098643Z","end":"2026-07-22T22:15:46.209143Z","steps":["trace[9674982] 'process raft request' (duration: 90.175497ms)","trace[9674982] 'compare' (duration: 20.138698ms)"],"step_count":2}