{"level":"warn","ts":"2026-05-08T02:55:22.671244Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T02:55:22.671332Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T02:55:22.671401Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T02:55:22.671414Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-08T02:55:22.671429Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T02:55:22.672094Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-05-08T02:55:22.67217Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T02:55:23.014166Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"341.762016ms"} {"level":"info","ts":"2026-05-08T02:55:23.546609Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-05-08T02:55:23.546792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T02:55:23.546868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-05-08T02:55:23.546915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T02:55:23.546943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-05-08T02:55:23.547036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-05-08T02:55:23.597602Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T02:55:23.679121Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T02:55:23.812556Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T02:55:23.817255Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T02:55:23.817578Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T02:55:23.817826Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T02:55:23.817984Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T02:55:23.818001Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T02:55:23.820556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-05-08T02:55:23.820743Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-08T02:55:23.823432Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T02:55:23.823535Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-08T02:55:23.82359Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-08T02:55:23.823929Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T02:55:23.823994Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T02:55:24.283507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T02:55:24.283569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T02:55:24.283624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-05-08T02:55:24.283651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-05-08T02:55:24.283666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-08T02:55:24.283687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-05-08T02:55:24.283705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-08T02:55:24.285006Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T02:55:24.285008Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:55:24.285175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:55:24.2852Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:55:24.285342Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T02:55:24.285373Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T02:55:24.286551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:55:24.286641Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:55:24.286667Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:55:24.286887Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T02:55:24.29148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"warn","ts":"2026-05-08T02:55:29.385352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114199180984697,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:55:29.420192Z","caller":"traceutil/trace.go:171","msg":"trace[411329071] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"554.457922ms","start":"2026-05-08T02:55:28.865684Z","end":"2026-05-08T02:55:29.420142Z","steps":["trace[411329071] 'process raft request' (duration: 554.307889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.420849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:28.865657Z","time spent":"554.783181ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:55:29.819677Z","caller":"traceutil/trace.go:171","msg":"trace[1830726794] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"751.010425ms","start":"2026-05-08T02:55:29.068612Z","end":"2026-05-08T02:55:29.819623Z","steps":["trace[1830726794] 'process raft request' (duration: 750.877632ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:55:29.819743Z","caller":"traceutil/trace.go:171","msg":"trace[1375624295] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:283; }","duration":"935.306234ms","start":"2026-05-08T02:55:28.884411Z","end":"2026-05-08T02:55:29.819717Z","steps":["trace[1375624295] 'read index received' (duration: 535.527654ms)","trace[1375624295] 'applied index is now lower than readState.Index' (duration: 399.77767ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:55:29.819748Z","caller":"traceutil/trace.go:171","msg":"trace[567529181] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"535.644617ms","start":"2026-05-08T02:55:29.284062Z","end":"2026-05-08T02:55:29.819706Z","steps":["trace[567529181] 'process raft request' (duration: 535.466203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.819875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.068579Z","time spent":"751.20991ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:55:29.81999Z","caller":"traceutil/trace.go:171","msg":"trace[1985572211] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"535.727029ms","start":"2026-05-08T02:55:29.284249Z","end":"2026-05-08T02:55:29.819976Z","steps":["trace[1985572211] 'process raft request' (duration: 535.32038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.819997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.284034Z","time spent":"535.820021ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:55:29.820087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.284175Z","time spent":"535.858343ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:55:29.820224Z","caller":"traceutil/trace.go:171","msg":"trace[662530465] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"532.642038ms","start":"2026-05-08T02:55:29.287569Z","end":"2026-05-08T02:55:29.820211Z","steps":["trace[662530465] 'process raft request' (duration: 532.118886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.820268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.287555Z","time spent":"532.694479ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:55:29.820264Z","caller":"traceutil/trace.go:171","msg":"trace[829413495] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"533.883396ms","start":"2026-05-08T02:55:29.286357Z","end":"2026-05-08T02:55:29.82024Z","steps":["trace[829413495] 'process raft request' (duration: 533.298993ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:55:29.820293Z","caller":"traceutil/trace.go:171","msg":"trace[696704464] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"534.364328ms","start":"2026-05-08T02:55:29.285865Z","end":"2026-05-08T02:55:29.82023Z","steps":["trace[696704464] 'process raft request' (duration: 533.733534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.820372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.286343Z","time spent":"533.982308ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:55:29.82037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.65735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:55:29.820488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.28584Z","time spent":"534.526831ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:55:29.82055Z","caller":"traceutil/trace.go:171","msg":"trace[1405177700] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:284; }","duration":"849.889696ms","start":"2026-05-08T02:55:28.970639Z","end":"2026-05-08T02:55:29.820529Z","steps":["trace[1405177700] 'agreement among raft nodes before linearized reading' (duration: 849.62906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.820602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.182944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:55:29.820603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.072524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:55:29.820614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:28.970619Z","time spent":"849.982398ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-08T02:55:29.820645Z","caller":"traceutil/trace.go:171","msg":"trace[1518084597] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:284; }","duration":"936.276156ms","start":"2026-05-08T02:55:28.884355Z","end":"2026-05-08T02:55:29.820631Z","steps":["trace[1518084597] 'agreement among raft nodes before linearized reading' (duration: 936.213095ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:55:29.820663Z","caller":"traceutil/trace.go:171","msg":"trace[599954896] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:284; }","duration":"396.127785ms","start":"2026-05-08T02:55:29.424512Z","end":"2026-05-08T02:55:29.820639Z","steps":["trace[599954896] 'agreement among raft nodes before linearized reading' (duration: 396.043483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.82069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:29.424492Z","time spent":"396.191356ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-05-08T02:55:29.820688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:28.884326Z","time spent":"936.354068ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-05-08T02:55:29.820792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.880106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-08T02:55:29.820822Z","caller":"traceutil/trace.go:171","msg":"trace[696731713] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:284; }","duration":"849.945046ms","start":"2026-05-08T02:55:28.970867Z","end":"2026-05-08T02:55:29.820812Z","steps":["trace[696731713] 'agreement among raft nodes before linearized reading' (duration: 849.881905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.820849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:28.970847Z","time spent":"849.996008ms","remote":"127.0.0.1:53936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-08T02:55:29.820894Z","caller":"traceutil/trace.go:171","msg":"trace[1085266224] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"850.278915ms","start":"2026-05-08T02:55:28.970594Z","end":"2026-05-08T02:55:29.820873Z","steps":["trace[1085266224] 'process raft request' (duration: 848.795921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:55:29.821038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:55:28.970576Z","time spent":"850.429399ms","remote":"127.0.0.1:53612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:55:33.537861Z","caller":"traceutil/trace.go:171","msg":"trace[648159679] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"115.857623ms","start":"2026-05-08T02:55:33.421878Z","end":"2026-05-08T02:55:33.537735Z","steps":["trace[648159679] 'process raft request' (duration: 115.628878ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.212861Z","caller":"traceutil/trace.go:171","msg":"trace[1099074297] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"173.068688ms","start":"2026-05-08T02:56:01.03976Z","end":"2026-05-08T02:56:01.212829Z","steps":["trace[1099074297] 'process raft request' (duration: 172.915324ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.249384Z","caller":"traceutil/trace.go:171","msg":"trace[502726156] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"196.352248ms","start":"2026-05-08T02:56:01.053004Z","end":"2026-05-08T02:56:01.249356Z","steps":["trace[502726156] 'process raft request' (duration: 196.033201ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.249442Z","caller":"traceutil/trace.go:171","msg":"trace[307151230] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"196.312997ms","start":"2026-05-08T02:56:01.053086Z","end":"2026-05-08T02:56:01.249398Z","steps":["trace[307151230] 'process raft request' (duration: 196.070271ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.249512Z","caller":"traceutil/trace.go:171","msg":"trace[294261807] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"187.976024ms","start":"2026-05-08T02:56:01.061504Z","end":"2026-05-08T02:56:01.24948Z","steps":["trace[294261807] 'process raft request' (duration: 187.81676ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.24968Z","caller":"traceutil/trace.go:171","msg":"trace[1784202903] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"184.896493ms","start":"2026-05-08T02:56:01.064724Z","end":"2026-05-08T02:56:01.249621Z","steps":["trace[1784202903] 'process raft request' (duration: 184.648196ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.250224Z","caller":"traceutil/trace.go:171","msg":"trace[270897308] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"182.029507ms","start":"2026-05-08T02:56:01.068178Z","end":"2026-05-08T02:56:01.250208Z","steps":["trace[270897308] 'process raft request' (duration: 181.270028ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.250309Z","caller":"traceutil/trace.go:171","msg":"trace[342315500] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"170.023968ms","start":"2026-05-08T02:56:01.080268Z","end":"2026-05-08T02:56:01.250292Z","steps":["trace[342315500] 'process raft request' (duration: 169.728662ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.250901Z","caller":"traceutil/trace.go:171","msg":"trace[1180984901] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"127.215767ms","start":"2026-05-08T02:56:01.123672Z","end":"2026-05-08T02:56:01.250888Z","steps":["trace[1180984901] 'process raft request' (duration: 127.173426ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.251058Z","caller":"traceutil/trace.go:171","msg":"trace[270437872] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"159.627628ms","start":"2026-05-08T02:56:01.091421Z","end":"2026-05-08T02:56:01.251049Z","steps":["trace[270437872] 'process raft request' (duration: 159.107636ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.251205Z","caller":"traceutil/trace.go:171","msg":"trace[929423473] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"150.362333ms","start":"2026-05-08T02:56:01.100835Z","end":"2026-05-08T02:56:01.251197Z","steps":["trace[929423473] 'process raft request' (duration: 149.884742ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:01.251313Z","caller":"traceutil/trace.go:171","msg":"trace[1654350976] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"150.410115ms","start":"2026-05-08T02:56:01.100894Z","end":"2026-05-08T02:56:01.251304Z","steps":["trace[1654350976] 'process raft request' (duration: 149.877512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:04.80712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114199180985411,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:56:04.879362Z","caller":"traceutil/trace.go:171","msg":"trace[776773912] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"572.803717ms","start":"2026-05-08T02:56:04.306528Z","end":"2026-05-08T02:56:04.879332Z","steps":["trace[776773912] 'read index received' (duration: 570.511354ms)","trace[776773912] 'applied index is now lower than readState.Index' (duration: 2.291533ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:56:04.879427Z","caller":"traceutil/trace.go:171","msg":"trace[631017762] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"573.124745ms","start":"2026-05-08T02:56:04.30628Z","end":"2026-05-08T02:56:04.879404Z","steps":["trace[631017762] 'process raft request' (duration: 572.210564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:04.879615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:04.30625Z","time spent":"573.229268ms","remote":"127.0.0.1:53642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:56:04.879736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.11675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T02:56:04.879791Z","caller":"traceutil/trace.go:171","msg":"trace[1683377274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"464.222672ms","start":"2026-05-08T02:56:04.415553Z","end":"2026-05-08T02:56:04.879776Z","steps":["trace[1683377274] 'agreement among raft nodes before linearized reading' (duration: 463.961406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:04.879808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.967446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T02:56:04.879833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:04.415522Z","time spent":"464.299444ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:56:04.87989Z","caller":"traceutil/trace.go:171","msg":"trace[1994278152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"353.105789ms","start":"2026-05-08T02:56:04.526761Z","end":"2026-05-08T02:56:04.879867Z","steps":["trace[1994278152] 'agreement among raft nodes before linearized reading' (duration: 352.788912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:04.879967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:04.526729Z","time spent":"353.230832ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:56:04.880103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.582226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:56:04.880134Z","caller":"traceutil/trace.go:171","msg":"trace[79339038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"573.613676ms","start":"2026-05-08T02:56:04.306512Z","end":"2026-05-08T02:56:04.880126Z","steps":["trace[79339038] 'agreement among raft nodes before linearized reading' (duration: 572.994102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:04.880152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:04.306493Z","time spent":"573.655287ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:56:05.62127Z","caller":"traceutil/trace.go:171","msg":"trace[1055265728] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"128.529527ms","start":"2026-05-08T02:56:05.492713Z","end":"2026-05-08T02:56:05.621243Z","steps":["trace[1055265728] 'process raft request' (duration: 118.836183ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:08.182916Z","caller":"traceutil/trace.go:171","msg":"trace[266850542] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"131.888045ms","start":"2026-05-08T02:56:08.050992Z","end":"2026-05-08T02:56:08.18288Z","steps":["trace[266850542] 'read index received' (duration: 130.467202ms)","trace[266850542] 'applied index is now lower than readState.Index' (duration: 1.420223ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:56:08.183003Z","caller":"traceutil/trace.go:171","msg":"trace[706269009] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"234.373758ms","start":"2026-05-08T02:56:07.948582Z","end":"2026-05-08T02:56:08.182956Z","steps":["trace[706269009] 'process raft request' (duration: 233.437077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:08.183341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.314706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T02:56:08.183524Z","caller":"traceutil/trace.go:171","msg":"trace[92275918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"132.455579ms","start":"2026-05-08T02:56:08.05096Z","end":"2026-05-08T02:56:08.183416Z","steps":["trace[92275918] 'agreement among raft nodes before linearized reading' (duration: 132.040329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:08.495624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.58297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:56:08.496124Z","caller":"traceutil/trace.go:171","msg":"trace[1847364676] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"307.443621ms","start":"2026-05-08T02:56:08.188658Z","end":"2026-05-08T02:56:08.496101Z","steps":["trace[1847364676] 'process raft request' (duration: 110.96613ms)","trace[1847364676] 'compare' (duration: 195.014357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:56:08.496277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:08.188622Z","time spent":"307.631015ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:56:08.50253Z","caller":"traceutil/trace.go:171","msg":"trace[910382853] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"304.023793ms","start":"2026-05-08T02:56:08.198382Z","end":"2026-05-08T02:56:08.502406Z","steps":["trace[910382853] 'process raft request' (duration: 303.743405ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:08.50263Z","caller":"traceutil/trace.go:171","msg":"trace[906380236] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"259.858739ms","start":"2026-05-08T02:56:08.242733Z","end":"2026-05-08T02:56:08.502592Z","steps":["trace[906380236] 'process raft request' (duration: 259.560731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:08.502849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:08.198355Z","time spent":"304.381971ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:56:09.569655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.0232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T02:56:09.569782Z","caller":"traceutil/trace.go:171","msg":"trace[1707487561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"670.225036ms","start":"2026-05-08T02:56:08.899528Z","end":"2026-05-08T02:56:09.569753Z","steps":["trace[1707487561] 'range keys from in-memory index tree' (duration: 669.739293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:09.569833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:08.899492Z","time spent":"670.328937ms","remote":"127.0.0.1:53820","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-05-08T02:56:09.569799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.093823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T02:56:09.569914Z","caller":"traceutil/trace.go:171","msg":"trace[2112202792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"667.262967ms","start":"2026-05-08T02:56:08.902624Z","end":"2026-05-08T02:56:09.569887Z","steps":["trace[2112202792] 'range keys from in-memory index tree' (duration: 666.880347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:09.570018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:08.902598Z","time spent":"667.371909ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:56:09.570086Z","caller":"traceutil/trace.go:171","msg":"trace[1219399201] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"262.890269ms","start":"2026-05-08T02:56:09.307152Z","end":"2026-05-08T02:56:09.570042Z","steps":["trace[1219399201] 'read index received' (duration: 259.137672ms)","trace[1219399201] 'applied index is now lower than readState.Index' (duration: 3.750907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:56:09.570205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.052423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:56:09.570177Z","caller":"traceutil/trace.go:171","msg":"trace[633281915] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"634.666652ms","start":"2026-05-08T02:56:08.935435Z","end":"2026-05-08T02:56:09.570102Z","steps":["trace[633281915] 'process raft request' (duration: 631.104868ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:09.570249Z","caller":"traceutil/trace.go:171","msg":"trace[161782416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"263.150776ms","start":"2026-05-08T02:56:09.307078Z","end":"2026-05-08T02:56:09.570229Z","steps":["trace[161782416] 'agreement among raft nodes before linearized reading' (duration: 263.091324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:09.570366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:08.935392Z","time spent":"634.866336ms","remote":"127.0.0.1:53642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:56:09.5704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.633522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:56:09.570457Z","caller":"traceutil/trace.go:171","msg":"trace[1943260509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"641.778476ms","start":"2026-05-08T02:56:08.928662Z","end":"2026-05-08T02:56:09.57044Z","steps":["trace[1943260509] 'range keys from in-memory index tree' (duration: 640.27604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:09.570523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:56:08.92863Z","time spent":"641.880988ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:56:11.408838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.504584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:56:11.408935Z","caller":"traceutil/trace.go:171","msg":"trace[526642125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"102.666188ms","start":"2026-05-08T02:56:11.306247Z","end":"2026-05-08T02:56:11.408913Z","steps":["trace[526642125] 'range keys from in-memory index tree' (duration: 102.352171ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:56:25.493242Z","caller":"traceutil/trace.go:171","msg":"trace[623041626] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"239.754671ms","start":"2026-05-08T02:56:25.253465Z","end":"2026-05-08T02:56:25.49322Z","steps":["trace[623041626] 'read index received' (duration: 239.536456ms)","trace[623041626] 'applied index is now lower than readState.Index' (duration: 217.195µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:56:25.493288Z","caller":"traceutil/trace.go:171","msg":"trace[1255778843] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"270.947899ms","start":"2026-05-08T02:56:25.222305Z","end":"2026-05-08T02:56:25.493253Z","steps":["trace[1255778843] 'process raft request' (duration: 270.768365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:25.493414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.907204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:56:25.493459Z","caller":"traceutil/trace.go:171","msg":"trace[990496927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"186.992866ms","start":"2026-05-08T02:56:25.306452Z","end":"2026-05-08T02:56:25.493445Z","steps":["trace[990496927] 'agreement among raft nodes before linearized reading' (duration: 186.913004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:56:25.493435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.946996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T02:56:25.493527Z","caller":"traceutil/trace.go:171","msg":"trace[88034029] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:801; }","duration":"240.095899ms","start":"2026-05-08T02:56:25.253409Z","end":"2026-05-08T02:56:25.493505Z","steps":["trace[88034029] 'agreement among raft nodes before linearized reading' (duration: 239.936096ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:57:00.647014Z","caller":"traceutil/trace.go:171","msg":"trace[2083646314] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"140.367587ms","start":"2026-05-08T02:57:00.50661Z","end":"2026-05-08T02:57:00.646978Z","steps":["trace[2083646314] 'process raft request' (duration: 140.180153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:00.934446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.979168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T02:57:00.934528Z","caller":"traceutil/trace.go:171","msg":"trace[363627945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"125.114331ms","start":"2026-05-08T02:57:00.809397Z","end":"2026-05-08T02:57:00.934511Z","steps":["trace[363627945] 'range keys from in-memory index tree' (duration: 124.805374ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:57:01.517507Z","caller":"traceutil/trace.go:171","msg":"trace[1963254196] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"210.265184ms","start":"2026-05-08T02:57:01.307218Z","end":"2026-05-08T02:57:01.517483Z","steps":["trace[1963254196] 'read index received' (duration: 210.204192ms)","trace[1963254196] 'applied index is now lower than readState.Index' (duration: 60.062µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:57:01.51762Z","caller":"traceutil/trace.go:171","msg":"trace[1452972462] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"579.594672ms","start":"2026-05-08T02:57:00.937992Z","end":"2026-05-08T02:57:01.517587Z","steps":["trace[1452972462] 'process raft request' (duration: 579.346336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:01.517668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.427947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:57:01.517702Z","caller":"traceutil/trace.go:171","msg":"trace[165583101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"210.53027ms","start":"2026-05-08T02:57:01.307162Z","end":"2026-05-08T02:57:01.517693Z","steps":["trace[165583101] 'agreement among raft nodes before linearized reading' (duration: 210.442918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:01.517807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:00.937947Z","time spent":"579.748987ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:57:01.517834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.114889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T02:57:01.51792Z","caller":"traceutil/trace.go:171","msg":"trace[326772683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"127.266843ms","start":"2026-05-08T02:57:01.390633Z","end":"2026-05-08T02:57:01.5179Z","steps":["trace[326772683] 'agreement among raft nodes before linearized reading' (duration: 127.011407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:27.848897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.827383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:57:27.84904Z","caller":"traceutil/trace.go:171","msg":"trace[2068343248] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"193.035021ms","start":"2026-05-08T02:57:27.655973Z","end":"2026-05-08T02:57:27.849008Z","steps":["trace[2068343248] 'read index received' (duration: 60.252µs)","trace[2068343248] 'applied index is now lower than readState.Index' (duration: 192.972469ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:57:27.849325Z","caller":"traceutil/trace.go:171","msg":"trace[189753886] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"523.370364ms","start":"2026-05-08T02:57:27.3258Z","end":"2026-05-08T02:57:27.849171Z","steps":["trace[189753886] 'process raft request' (duration: 273.148391ms)","trace[189753886] 'compare' (duration: 249.556506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:57:27.849505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.193042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T02:57:27.849619Z","caller":"traceutil/trace.go:171","msg":"trace[1855001809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"185.389007ms","start":"2026-05-08T02:57:27.66421Z","end":"2026-05-08T02:57:27.849599Z","steps":["trace[1855001809] 'agreement among raft nodes before linearized reading' (duration: 185.006928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:27.849738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:27.32578Z","time spent":"523.6008ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:57:27.849882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.601504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T02:57:27.850067Z","caller":"traceutil/trace.go:171","msg":"trace[31556874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"194.141197ms","start":"2026-05-08T02:57:27.655889Z","end":"2026-05-08T02:57:27.850031Z","steps":["trace[31556874] 'agreement among raft nodes before linearized reading' (duration: 193.310847ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:57:28.050894Z","caller":"traceutil/trace.go:171","msg":"trace[737758909] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"196.538244ms","start":"2026-05-08T02:57:27.854306Z","end":"2026-05-08T02:57:28.050844Z","steps":["trace[737758909] 'process raft request' (duration: 143.710267ms)","trace[737758909] 'compare' (duration: 52.50948ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:57:28.055275Z","caller":"traceutil/trace.go:171","msg":"trace[408802430] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1140; }","duration":"199.260119ms","start":"2026-05-08T02:57:27.85599Z","end":"2026-05-08T02:57:28.05525Z","steps":["trace[408802430] 'read index received' (duration: 142.15495ms)","trace[408802430] 'applied index is now lower than readState.Index' (duration: 57.103929ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:57:28.055325Z","caller":"traceutil/trace.go:171","msg":"trace[1113391280] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"200.827907ms","start":"2026-05-08T02:57:27.854448Z","end":"2026-05-08T02:57:28.055276Z","steps":["trace[1113391280] 'process raft request' (duration: 200.691764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:28.05552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.535086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-08T02:57:28.055614Z","caller":"traceutil/trace.go:171","msg":"trace[49660751] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1103; }","duration":"199.739922ms","start":"2026-05-08T02:57:27.855853Z","end":"2026-05-08T02:57:28.055593Z","steps":["trace[49660751] 'agreement among raft nodes before linearized reading' (duration: 199.532137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:30.112451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.204404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:57:30.112546Z","caller":"traceutil/trace.go:171","msg":"trace[1650653630] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1108; }","duration":"326.395819ms","start":"2026-05-08T02:57:29.786131Z","end":"2026-05-08T02:57:30.112527Z","steps":["trace[1650653630] 'count revisions from in-memory index tree' (duration: 326.115593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:30.112601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:29.786076Z","time spent":"326.507991ms","remote":"127.0.0.1:38240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:57:30.112613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.345065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T02:57:30.112733Z","caller":"traceutil/trace.go:171","msg":"trace[1446609285] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1108; }","duration":"528.531449ms","start":"2026-05-08T02:57:29.584176Z","end":"2026-05-08T02:57:30.112708Z","steps":["trace[1446609285] 'count revisions from in-memory index tree' (duration: 528.230412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:30.112744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.448256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:57:30.112791Z","caller":"traceutil/trace.go:171","msg":"trace[1572310855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"805.499567ms","start":"2026-05-08T02:57:29.307279Z","end":"2026-05-08T02:57:30.112778Z","steps":["trace[1572310855] 'range keys from in-memory index tree' (duration: 805.365415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:30.112786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:29.584146Z","time spent":"528.627682ms","remote":"127.0.0.1:53888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:57:30.112821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:29.307188Z","time spent":"805.624931ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:57:30.982059Z","caller":"traceutil/trace.go:171","msg":"trace[2066111538] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"162.079427ms","start":"2026-05-08T02:57:30.819953Z","end":"2026-05-08T02:57:30.982033Z","steps":["trace[2066111538] 'process raft request' (duration: 161.896302ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:57:43.43466Z","caller":"traceutil/trace.go:171","msg":"trace[467987826] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"413.168845ms","start":"2026-05-08T02:57:43.02147Z","end":"2026-05-08T02:57:43.434639Z","steps":["trace[467987826] 'process raft request' (duration: 413.020512ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:57:43.434752Z","caller":"traceutil/trace.go:171","msg":"trace[204441218] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1248; }","duration":"239.20585ms","start":"2026-05-08T02:57:43.195519Z","end":"2026-05-08T02:57:43.434725Z","steps":["trace[204441218] 'read index received' (duration: 239.19483ms)","trace[204441218] 'applied index is now lower than readState.Index' (duration: 9.37µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:57:43.43481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:43.021443Z","time spent":"413.293909ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:57:43.434951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.400195ms","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-05-08T02:57:43.434984Z","caller":"traceutil/trace.go:171","msg":"trace[747435478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1204; }","duration":"239.513257ms","start":"2026-05-08T02:57:43.19546Z","end":"2026-05-08T02:57:43.434973Z","steps":["trace[747435478] 'agreement among raft nodes before linearized reading' (duration: 239.383334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:43.935798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114199180986706,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:57:44.229523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.241958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:57:44.229619Z","caller":"traceutil/trace.go:171","msg":"trace[163843659] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"794.784807ms","start":"2026-05-08T02:57:43.434818Z","end":"2026-05-08T02:57:44.229603Z","steps":["trace[163843659] 'read index received' (duration: 677.310163ms)","trace[163843659] 'applied index is now lower than readState.Index' (duration: 117.473184ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:57:44.229713Z","caller":"traceutil/trace.go:171","msg":"trace[831704004] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"834.709207ms","start":"2026-05-08T02:57:43.394993Z","end":"2026-05-08T02:57:44.229702Z","steps":["trace[831704004] 'process raft request' (duration: 717.142831ms)","trace[831704004] 'compare' (duration: 117.036383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:57:44.229767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:43.394973Z","time spent":"834.763918ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:57:44.230049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.822752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:57:44.230127Z","caller":"traceutil/trace.go:171","msg":"trace[564320899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1205; }","duration":"923.925264ms","start":"2026-05-08T02:57:43.306182Z","end":"2026-05-08T02:57:44.230107Z","steps":["trace[564320899] 'agreement among raft nodes before linearized reading' (duration: 923.7623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:57:44.230168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:57:43.306174Z","time spent":"923.987045ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:58:01.724317Z","caller":"traceutil/trace.go:171","msg":"trace[2023619319] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"123.07873ms","start":"2026-05-08T02:58:01.601203Z","end":"2026-05-08T02:58:01.724282Z","steps":["trace[2023619319] 'process raft request' (duration: 122.896626ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:01.84501Z","caller":"traceutil/trace.go:171","msg":"trace[1163847761] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"113.728352ms","start":"2026-05-08T02:58:01.731261Z","end":"2026-05-08T02:58:01.84499Z","steps":["trace[1163847761] 'process raft request' (duration: 113.581248ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:01.845183Z","caller":"traceutil/trace.go:171","msg":"trace[647842114] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"108.29669ms","start":"2026-05-08T02:58:01.736876Z","end":"2026-05-08T02:58:01.845173Z","steps":["trace[647842114] 'process raft request' (duration: 108.065195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:02.915893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114199180986887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:58:03.028369Z","caller":"traceutil/trace.go:171","msg":"trace[842004139] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"702.850043ms","start":"2026-05-08T02:58:02.325489Z","end":"2026-05-08T02:58:03.028339Z","steps":["trace[842004139] 'process raft request' (duration: 702.704309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:03.02861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:02.325415Z","time spent":"703.035417ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:58:03.11657Z","caller":"traceutil/trace.go:171","msg":"trace[594438477] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"700.970287ms","start":"2026-05-08T02:58:02.415569Z","end":"2026-05-08T02:58:03.11654Z","steps":["trace[594438477] 'read index received' (duration: 613.268907ms)","trace[594438477] 'applied index is now lower than readState.Index' (duration: 87.69982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:03.116895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.290745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T02:58:03.117005Z","caller":"traceutil/trace.go:171","msg":"trace[1748021645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"701.441678ms","start":"2026-05-08T02:58:02.415536Z","end":"2026-05-08T02:58:03.116978Z","steps":["trace[1748021645] 'agreement among raft nodes before linearized reading' (duration: 701.180882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:03.11702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.828649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T02:58:03.117067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:02.41552Z","time spent":"701.533611ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:58:03.117095Z","caller":"traceutil/trace.go:171","msg":"trace[1280128155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"664.931202ms","start":"2026-05-08T02:58:02.452123Z","end":"2026-05-08T02:58:03.117055Z","steps":["trace[1280128155] 'agreement among raft nodes before linearized reading' (duration: 664.638495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:03.117169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:02.452116Z","time spent":"665.040994ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:58:03.117402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.640951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T02:58:03.117435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.895197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:58:03.117532Z","caller":"traceutil/trace.go:171","msg":"trace[1202642754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"657.01293ms","start":"2026-05-08T02:58:02.460497Z","end":"2026-05-08T02:58:03.11751Z","steps":["trace[1202642754] 'agreement among raft nodes before linearized reading' (duration: 656.836035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:03.117621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:02.460478Z","time spent":"657.128512ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T02:58:03.117464Z","caller":"traceutil/trace.go:171","msg":"trace[1596185106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"656.722642ms","start":"2026-05-08T02:58:02.460724Z","end":"2026-05-08T02:58:03.117447Z","steps":["trace[1596185106] 'agreement among raft nodes before linearized reading' (duration: 656.578279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:03.117686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:02.460713Z","time spent":"656.957588ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:58:04.260668Z","caller":"traceutil/trace.go:171","msg":"trace[620104582] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"322.641577ms","start":"2026-05-08T02:58:03.938001Z","end":"2026-05-08T02:58:04.260642Z","steps":["trace[620104582] 'read index received' (duration: 322.467753ms)","trace[620104582] 'applied index is now lower than readState.Index' (duration: 172.914µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:04.260832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.801251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:58:04.260889Z","caller":"traceutil/trace.go:171","msg":"trace[441999807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1291; }","duration":"322.919715ms","start":"2026-05-08T02:58:03.937958Z","end":"2026-05-08T02:58:04.260878Z","steps":["trace[441999807] 'agreement among raft nodes before linearized reading' (duration: 322.779611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:04.26093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:03.937936Z","time spent":"322.981227ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:58:04.261254Z","caller":"traceutil/trace.go:171","msg":"trace[225553844] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"689.802737ms","start":"2026-05-08T02:58:03.571414Z","end":"2026-05-08T02:58:04.261216Z","steps":["trace[225553844] 'process raft request' (duration: 689.103379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:04.261428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:03.57139Z","time spent":"689.941231ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:05.591367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283951459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:58:05.59149Z","caller":"traceutil/trace.go:171","msg":"trace[1812708974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"1.284104353s","start":"2026-05-08T02:58:04.307353Z","end":"2026-05-08T02:58:05.591457Z","steps":["trace[1812708974] 'range keys from in-memory index tree' (duration: 1.283865037s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:05.59158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:04.307335Z","time spent":"1.284233916s","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:58:05.591597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.435839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:58:05.59169Z","caller":"traceutil/trace.go:171","msg":"trace[777754276] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"558.677972ms","start":"2026-05-08T02:58:05.032999Z","end":"2026-05-08T02:58:05.591677Z","steps":["trace[777754276] 'read index received' (duration: 35.121µs)","trace[777754276] 'applied index is now lower than readState.Index' (duration: 558.641801ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:58:05.591758Z","caller":"traceutil/trace.go:171","msg":"trace[584010696] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"1.149180796s","start":"2026-05-08T02:58:04.442569Z","end":"2026-05-08T02:58:05.59175Z","steps":["trace[584010696] 'process raft request' (duration: 461.48772ms)","trace[584010696] 'compare' (duration: 686.930596ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:05.591801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:04.442537Z","time spent":"1.149242547s","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:05.592078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.553991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T02:58:05.592165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.163903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-08T02:58:05.592158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.180405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T02:58:05.592192Z","caller":"traceutil/trace.go:171","msg":"trace[725239070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"559.213285ms","start":"2026-05-08T02:58:05.03297Z","end":"2026-05-08T02:58:05.592183Z","steps":["trace[725239070] 'agreement among raft nodes before linearized reading' (duration: 559.109693ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:05.592206Z","caller":"traceutil/trace.go:171","msg":"trace[1084614320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"415.262537ms","start":"2026-05-08T02:58:05.176929Z","end":"2026-05-08T02:58:05.592192Z","steps":["trace[1084614320] 'agreement among raft nodes before linearized reading' (duration: 415.079073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:05.592221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.032951Z","time spent":"559.265596ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T02:58:05.592239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.17691Z","time spent":"415.321819ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:58:05.592178Z","caller":"traceutil/trace.go:171","msg":"trace[1998536461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"449.701264ms","start":"2026-05-08T02:58:05.142452Z","end":"2026-05-08T02:58:05.592153Z","steps":["trace[1998536461] 'agreement among raft nodes before linearized reading' (duration: 449.497699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:05.5924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.202181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T02:58:05.592422Z","caller":"traceutil/trace.go:171","msg":"trace[1462943111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"416.234022ms","start":"2026-05-08T02:58:05.176182Z","end":"2026-05-08T02:58:05.592416Z","steps":["trace[1462943111] 'agreement among raft nodes before linearized reading' (duration: 416.17381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:05.592445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.176175Z","time spent":"416.266562ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:58:05.592456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.142431Z","time spent":"449.963251ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:58:05.592765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.57816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:58:05.592901Z","caller":"traceutil/trace.go:171","msg":"trace[1670712954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"416.690513ms","start":"2026-05-08T02:58:05.17615Z","end":"2026-05-08T02:58:05.59284Z","steps":["trace[1670712954] 'agreement among raft nodes before linearized reading' (duration: 416.276083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:05.593089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.176101Z","time spent":"416.969359ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:58:06.085571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.917093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:58:06.085681Z","caller":"traceutil/trace.go:171","msg":"trace[1054876182] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"488.466555ms","start":"2026-05-08T02:58:05.597194Z","end":"2026-05-08T02:58:06.085661Z","steps":["trace[1054876182] 'process raft request' (duration: 354.348017ms)","trace[1054876182] 'compare' (duration: 133.689928ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:06.08574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.597171Z","time spent":"488.541687ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:58:06.513403Z","caller":"traceutil/trace.go:171","msg":"trace[1203900077] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1340; }","duration":"914.274159ms","start":"2026-05-08T02:58:05.599107Z","end":"2026-05-08T02:58:06.513381Z","steps":["trace[1203900077] 'read index received' (duration: 352.451241ms)","trace[1203900077] 'applied index is now lower than readState.Index' (duration: 561.822038ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:58:06.513441Z","caller":"traceutil/trace.go:171","msg":"trace[750037873] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"916.187526ms","start":"2026-05-08T02:58:05.597225Z","end":"2026-05-08T02:58:06.513412Z","steps":["trace[750037873] 'process raft request' (duration: 875.707182ms)","trace[750037873] 'compare' (duration: 39.991161ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:58:06.5135Z","caller":"traceutil/trace.go:171","msg":"trace[192043761] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"916.062553ms","start":"2026-05-08T02:58:05.597428Z","end":"2026-05-08T02:58:06.51349Z","steps":["trace[192043761] 'process raft request' (duration: 915.613291ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:06.513511Z","caller":"traceutil/trace.go:171","msg":"trace[1327714814] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"915.97847ms","start":"2026-05-08T02:58:05.597521Z","end":"2026-05-08T02:58:06.5135Z","steps":["trace[1327714814] 'process raft request' (duration: 915.817956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:06.513578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.597205Z","time spent":"916.301308ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:06.513608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.597408Z","time spent":"916.127794ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:06.513608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.59748Z","time spent":"916.055392ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:58:06.513453Z","caller":"traceutil/trace.go:171","msg":"trace[1639116350] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"915.95416ms","start":"2026-05-08T02:58:05.597477Z","end":"2026-05-08T02:58:06.513431Z","steps":["trace[1639116350] 'process raft request' (duration: 915.607061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:06.513693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.581077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:58:06.51375Z","caller":"traceutil/trace.go:171","msg":"trace[926741309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"914.659899ms","start":"2026-05-08T02:58:05.599077Z","end":"2026-05-08T02:58:06.513737Z","steps":["trace[926741309] 'agreement among raft nodes before linearized reading' (duration: 914.529176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:06.513758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.597462Z","time spent":"916.269058ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:06.513792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.599067Z","time spent":"914.71742ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:58:06.842203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.76536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:58:06.842305Z","caller":"traceutil/trace.go:171","msg":"trace[2078712924] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"328.820007ms","start":"2026-05-08T02:58:06.51347Z","end":"2026-05-08T02:58:06.84229Z","steps":["trace[2078712924] 'read index received' (duration: 224.946404ms)","trace[2078712924] 'applied index is now lower than readState.Index' (duration: 103.872623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:06.842373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23831009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T02:58:06.842377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.933292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:58:06.842404Z","caller":"traceutil/trace.go:171","msg":"trace[1372956970] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1298; }","duration":"1.238356891s","start":"2026-05-08T02:58:05.604038Z","end":"2026-05-08T02:58:06.842395Z","steps":["trace[1372956970] 'agreement among raft nodes before linearized reading' (duration: 1.238295009s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:06.842389Z","caller":"traceutil/trace.go:171","msg":"trace[1722729077] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"574.251989ms","start":"2026-05-08T02:58:06.268077Z","end":"2026-05-08T02:58:06.842329Z","steps":["trace[1722729077] 'process raft request' (duration: 470.277704ms)","trace[1722729077] 'compare' (duration: 103.650768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:06.842456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:05.60403Z","time spent":"1.238413883s","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:58:06.842437Z","caller":"traceutil/trace.go:171","msg":"trace[819266716] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1298; }","duration":"376.042635ms","start":"2026-05-08T02:58:06.466379Z","end":"2026-05-08T02:58:06.842421Z","steps":["trace[819266716] 'agreement among raft nodes before linearized reading' (duration: 375.940272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:06.842619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:06.268051Z","time spent":"574.442223ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:06.842736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:06.466357Z","time spent":"376.251369ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:58:07.383677Z","caller":"traceutil/trace.go:171","msg":"trace[588119074] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"213.893286ms","start":"2026-05-08T02:58:07.16976Z","end":"2026-05-08T02:58:07.383654Z","steps":["trace[588119074] 'process raft request' (duration: 213.590059ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:07.667271Z","caller":"traceutil/trace.go:171","msg":"trace[1112776719] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"282.930372ms","start":"2026-05-08T02:58:07.384307Z","end":"2026-05-08T02:58:07.667237Z","steps":["trace[1112776719] 'process raft request' (duration: 186.630233ms)","trace[1112776719] 'compare' (duration: 96.073624ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:58:07.667304Z","caller":"traceutil/trace.go:171","msg":"trace[959807924] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"277.699885ms","start":"2026-05-08T02:58:07.389587Z","end":"2026-05-08T02:58:07.667287Z","steps":["trace[959807924] 'process raft request' (duration: 277.671375ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:07.667344Z","caller":"traceutil/trace.go:171","msg":"trace[1393666973] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"282.212676ms","start":"2026-05-08T02:58:07.385126Z","end":"2026-05-08T02:58:07.667338Z","steps":["trace[1393666973] 'read index received' (duration: 185.795423ms)","trace[1393666973] 'applied index is now lower than readState.Index' (duration: 96.416372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:07.667397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.257676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:58:07.668232Z","caller":"traceutil/trace.go:171","msg":"trace[575056664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"283.107327ms","start":"2026-05-08T02:58:07.385097Z","end":"2026-05-08T02:58:07.668205Z","steps":["trace[575056664] 'agreement among raft nodes before linearized reading' (duration: 282.256967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:07.668298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.578455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-08T02:58:07.668349Z","caller":"traceutil/trace.go:171","msg":"trace[1714045922] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1302; }","duration":"280.637417ms","start":"2026-05-08T02:58:07.387687Z","end":"2026-05-08T02:58:07.668325Z","steps":["trace[1714045922] 'agreement among raft nodes before linearized reading' (duration: 280.520794ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:12.646668Z","caller":"traceutil/trace.go:171","msg":"trace[759099034] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"102.627833ms","start":"2026-05-08T02:58:12.54399Z","end":"2026-05-08T02:58:12.646618Z","steps":["trace[759099034] 'process raft request' (duration: 101.528927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:17.658257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.185869ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:58:17.658432Z","caller":"traceutil/trace.go:171","msg":"trace[832529246] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1375; }","duration":"630.386853ms","start":"2026-05-08T02:58:17.028017Z","end":"2026-05-08T02:58:17.658404Z","steps":["trace[832529246] 'range keys from in-memory index tree' (duration: 630.159078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:17.658653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.373151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:58:17.65877Z","caller":"traceutil/trace.go:171","msg":"trace[940448366] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"352.036962ms","start":"2026-05-08T02:58:17.306711Z","end":"2026-05-08T02:58:17.658748Z","steps":["trace[940448366] 'read index received' (duration: 50.071µs)","trace[940448366] 'applied index is now lower than readState.Index' (duration: 351.985131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:17.659412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.795837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T02:58:17.659488Z","caller":"traceutil/trace.go:171","msg":"trace[1159702686] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1376; }","duration":"349.90325ms","start":"2026-05-08T02:58:17.309567Z","end":"2026-05-08T02:58:17.65947Z","steps":["trace[1159702686] 'agreement among raft nodes before linearized reading' (duration: 349.698785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:17.659499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.78205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:58:17.65953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:17.309558Z","time spent":"349.963691ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"info","ts":"2026-05-08T02:58:17.659567Z","caller":"traceutil/trace.go:171","msg":"trace[377805291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"352.879932ms","start":"2026-05-08T02:58:17.30667Z","end":"2026-05-08T02:58:17.65955Z","steps":["trace[377805291] 'agreement among raft nodes before linearized reading' (duration: 352.770029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:17.659626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:17.306653Z","time spent":"352.960354ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:58:17.658883Z","caller":"traceutil/trace.go:171","msg":"trace[1922335282] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"787.635832ms","start":"2026-05-08T02:58:16.87123Z","end":"2026-05-08T02:58:17.658866Z","steps":["trace[1922335282] 'process raft request' (duration: 33.909803ms)","trace[1922335282] 'compare' (duration: 753.156756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:17.660289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:16.871202Z","time spent":"788.641588ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:58:18.015441Z","caller":"traceutil/trace.go:171","msg":"trace[1074588857] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"354.123352ms","start":"2026-05-08T02:58:17.661286Z","end":"2026-05-08T02:58:18.01541Z","steps":["trace[1074588857] 'read index received' (duration: 348.877435ms)","trace[1074588857] 'applied index is now lower than readState.Index' (duration: 5.244137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:58:18.015475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:17.661006Z","time spent":"354.463709ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T02:58:18.015674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.362108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:58:18.015728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.712158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T02:58:18.015748Z","caller":"traceutil/trace.go:171","msg":"trace[1871097204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"354.47099ms","start":"2026-05-08T02:58:17.661249Z","end":"2026-05-08T02:58:18.01572Z","steps":["trace[1871097204] 'agreement among raft nodes before linearized reading' (duration: 354.348057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:18.015801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:17.66104Z","time spent":"354.748727ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:58:18.015794Z","caller":"traceutil/trace.go:171","msg":"trace[476231544] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1376; }","duration":"303.822291ms","start":"2026-05-08T02:58:17.711955Z","end":"2026-05-08T02:58:18.015777Z","steps":["trace[476231544] 'agreement among raft nodes before linearized reading' (duration: 303.719658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:18.01629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:17.711932Z","time spent":"304.298232ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:58:18.016514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.598059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-08T02:58:18.016577Z","caller":"traceutil/trace.go:171","msg":"trace[1927544130] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"248.704151ms","start":"2026-05-08T02:58:17.767858Z","end":"2026-05-08T02:58:18.016562Z","steps":["trace[1927544130] 'agreement among raft nodes before linearized reading' (duration: 248.462756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:18.01678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.789772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T02:58:18.016875Z","caller":"traceutil/trace.go:171","msg":"trace[75999175] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1376; }","duration":"129.974887ms","start":"2026-05-08T02:58:17.886882Z","end":"2026-05-08T02:58:18.016857Z","steps":["trace[75999175] 'agreement among raft nodes before linearized reading' (duration: 129.724901ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:18.123729Z","caller":"traceutil/trace.go:171","msg":"trace[1508550553] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"101.600377ms","start":"2026-05-08T02:58:18.022076Z","end":"2026-05-08T02:58:18.123676Z","steps":["trace[1508550553] 'process raft request' (duration: 98.172454ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:27.052044Z","caller":"traceutil/trace.go:171","msg":"trace[705068526] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"332.327381ms","start":"2026-05-08T02:58:26.719683Z","end":"2026-05-08T02:58:27.052011Z","steps":["trace[705068526] 'read index received' (duration: 332.002443ms)","trace[705068526] 'applied index is now lower than readState.Index' (duration: 322.538µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:58:27.052133Z","caller":"traceutil/trace.go:171","msg":"trace[900916631] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"397.909565ms","start":"2026-05-08T02:58:26.654195Z","end":"2026-05-08T02:58:27.052104Z","steps":["trace[900916631] 'process raft request' (duration: 397.606018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:27.052493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:26.654178Z","time spent":"398.018549ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:58:27.052581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.193396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-08T02:58:27.052593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.035859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T02:58:27.052643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.115957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T02:58:27.052704Z","caller":"traceutil/trace.go:171","msg":"trace[596060975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1417; }","duration":"319.134151ms","start":"2026-05-08T02:58:26.733543Z","end":"2026-05-08T02:58:27.052677Z","steps":["trace[596060975] 'agreement among raft nodes before linearized reading' (duration: 318.708211ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:27.052712Z","caller":"traceutil/trace.go:171","msg":"trace[140211903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1417; }","duration":"318.187699ms","start":"2026-05-08T02:58:26.734508Z","end":"2026-05-08T02:58:27.052695Z","steps":["trace[140211903] 'agreement among raft nodes before linearized reading' (duration: 318.005594ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:58:27.052639Z","caller":"traceutil/trace.go:171","msg":"trace[738748918] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1417; }","duration":"145.299999ms","start":"2026-05-08T02:58:26.907323Z","end":"2026-05-08T02:58:27.052623Z","steps":["trace[738748918] 'agreement among raft nodes before linearized reading' (duration: 144.965081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:27.052767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:26.734473Z","time spent":"318.280551ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T02:58:27.052818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:26.733503Z","time spent":"319.261744ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:58:27.052705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.947987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T02:58:27.053021Z","caller":"traceutil/trace.go:171","msg":"trace[1935680641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1417; }","duration":"333.311806ms","start":"2026-05-08T02:58:26.719624Z","end":"2026-05-08T02:58:27.052935Z","steps":["trace[1935680641] 'agreement among raft nodes before linearized reading' (duration: 332.589138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:58:27.053068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:58:26.7196Z","time spent":"333.454959ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T02:59:57.763693Z","caller":"traceutil/trace.go:171","msg":"trace[261953419] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"163.762331ms","start":"2026-05-08T02:59:57.5999Z","end":"2026-05-08T02:59:57.763662Z","steps":["trace[261953419] 'process raft request' (duration: 163.499875ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:59:57.763687Z","caller":"traceutil/trace.go:171","msg":"trace[139854073] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"162.176612ms","start":"2026-05-08T02:59:57.601489Z","end":"2026-05-08T02:59:57.763665Z","steps":["trace[139854073] 'process raft request' (duration: 162.09463ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:00:02.545037Z","caller":"traceutil/trace.go:171","msg":"trace[1675824955] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"112.191992ms","start":"2026-05-08T03:00:02.432822Z","end":"2026-05-08T03:00:02.545014Z","steps":["trace[1675824955] 'process raft request' (duration: 111.688729ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:00:22.416402Z","caller":"traceutil/trace.go:171","msg":"trace[1006766145] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"143.36126ms","start":"2026-05-08T03:00:22.27302Z","end":"2026-05-08T03:00:22.416381Z","steps":["trace[1006766145] 'process raft request' (duration: 143.268666ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:00:22.416709Z","caller":"traceutil/trace.go:171","msg":"trace[1640556222] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"143.834681ms","start":"2026-05-08T03:00:22.272852Z","end":"2026-05-08T03:00:22.416687Z","steps":["trace[1640556222] 'process raft request' (duration: 131.418665ms)","trace[1640556222] 'compare' (duration: 11.920443ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T03:01:28.622395Z","caller":"traceutil/trace.go:171","msg":"trace[1895508708] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"168.804956ms","start":"2026-05-08T03:01:28.453564Z","end":"2026-05-08T03:01:28.622369Z","steps":["trace[1895508708] 'process raft request' (duration: 168.654973ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:02:04.925466Z","caller":"traceutil/trace.go:171","msg":"trace[2057217946] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2546; }","duration":"126.383917ms","start":"2026-05-08T03:02:04.799058Z","end":"2026-05-08T03:02:04.925442Z","steps":["trace[2057217946] 'read index received' (duration: 126.231833ms)","trace[2057217946] 'applied index is now lower than readState.Index' (duration: 151.144µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T03:02:04.925588Z","caller":"traceutil/trace.go:171","msg":"trace[2079065407] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"140.911234ms","start":"2026-05-08T03:02:04.784645Z","end":"2026-05-08T03:02:04.925557Z","steps":["trace[2079065407] 'process raft request' (duration: 140.650607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:04.925766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.449436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-05-08T03:02:04.925846Z","caller":"traceutil/trace.go:171","msg":"trace[1386086427] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:2449; }","duration":"108.560649ms","start":"2026-05-08T03:02:04.817271Z","end":"2026-05-08T03:02:04.925832Z","steps":["trace[1386086427] 'agreement among raft nodes before linearized reading' (duration: 108.423226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:04.926321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.199187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:131 size:100880"} {"level":"info","ts":"2026-05-08T03:02:04.926406Z","caller":"traceutil/trace.go:171","msg":"trace[1086179913] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:131; response_revision:2449; }","duration":"127.352491ms","start":"2026-05-08T03:02:04.799033Z","end":"2026-05-08T03:02:04.926386Z","steps":["trace[1086179913] 'agreement among raft nodes before linearized reading' (duration: 126.540941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.509896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.779643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T03:02:05.509982Z","caller":"traceutil/trace.go:171","msg":"trace[1259903449] linearizableReadLoop","detail":"{readStateIndex:2548; appliedIndex:2547; }","duration":"578.269997ms","start":"2026-05-08T03:02:04.931699Z","end":"2026-05-08T03:02:05.509969Z","steps":["trace[1259903449] 'read index received' (duration: 323.36418ms)","trace[1259903449] 'applied index is now lower than readState.Index' (duration: 254.904747ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T03:02:05.510075Z","caller":"traceutil/trace.go:171","msg":"trace[632746597] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"579.828095ms","start":"2026-05-08T03:02:04.930214Z","end":"2026-05-08T03:02:05.510043Z","steps":["trace[632746597] 'process raft request' (duration: 324.832266ms)","trace[632746597] 'compare' (duration: 254.688711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T03:02:05.510319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.614796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-05-08T03:02:05.510347Z","caller":"traceutil/trace.go:171","msg":"trace[2012998013] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2450; }","duration":"578.665626ms","start":"2026-05-08T03:02:04.931675Z","end":"2026-05-08T03:02:05.510341Z","steps":["trace[2012998013] 'agreement among raft nodes before linearized reading' (duration: 578.40411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.510367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:04.931664Z","time spent":"578.697617ms","remote":"127.0.0.1:53642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":6,"response size":363705,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-05-08T03:02:05.510408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:04.9302Z","time spent":"580.112482ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:05.824267Z","caller":"traceutil/trace.go:171","msg":"trace[657403494] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2548; }","duration":"314.240926ms","start":"2026-05-08T03:02:05.510007Z","end":"2026-05-08T03:02:05.824248Z","steps":["trace[657403494] 'read index received' (duration: 237.423226ms)","trace[657403494] 'applied index is now lower than readState.Index' (duration: 76.8166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T03:02:05.824379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.992414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T03:02:05.82443Z","caller":"traceutil/trace.go:171","msg":"trace[385259722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2451; }","duration":"520.092757ms","start":"2026-05-08T03:02:05.304324Z","end":"2026-05-08T03:02:05.824417Z","steps":["trace[385259722] 'agreement among raft nodes before linearized reading' (duration: 519.974904ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:02:05.82444Z","caller":"traceutil/trace.go:171","msg":"trace[1740436842] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"876.448567ms","start":"2026-05-08T03:02:04.947948Z","end":"2026-05-08T03:02:05.824396Z","steps":["trace[1740436842] 'process raft request' (duration: 799.537636ms)","trace[1740436842] 'compare' (duration: 76.635665ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T03:02:05.824486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.3043Z","time spent":"520.165939ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T03:02:05.824549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.622377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-08T03:02:05.824537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.930527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T03:02:05.824583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:04.947896Z","time spent":"876.615991ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:05.824592Z","caller":"traceutil/trace.go:171","msg":"trace[1753107279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2451; }","duration":"707.71976ms","start":"2026-05-08T03:02:05.116861Z","end":"2026-05-08T03:02:05.824581Z","steps":["trace[1753107279] 'agreement among raft nodes before linearized reading' (duration: 707.503264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.824647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.256218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-08T03:02:05.824675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.11684Z","time spent":"707.823772ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T03:02:05.824709Z","caller":"traceutil/trace.go:171","msg":"trace[1420353743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2451; }","duration":"862.33736ms","start":"2026-05-08T03:02:04.962354Z","end":"2026-05-08T03:02:05.824691Z","steps":["trace[1420353743] 'agreement among raft nodes before linearized reading' (duration: 862.123715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.824748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:04.962344Z","time spent":"862.396532ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T03:02:05.824611Z","caller":"traceutil/trace.go:171","msg":"trace[1080070354] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2451; }","duration":"414.048339ms","start":"2026-05-08T03:02:05.410546Z","end":"2026-05-08T03:02:05.824594Z","steps":["trace[1080070354] 'agreement among raft nodes before linearized reading' (duration: 413.889225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.824872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.919348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T03:02:05.824878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.410533Z","time spent":"414.303716ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-08T03:02:05.82492Z","caller":"traceutil/trace.go:171","msg":"trace[1662033655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2451; }","duration":"783.00099ms","start":"2026-05-08T03:02:05.041909Z","end":"2026-05-08T03:02:05.82491Z","steps":["trace[1662033655] 'agreement among raft nodes before linearized reading' (duration: 782.892797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.824921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.281714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-08T03:02:05.824954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.041881Z","time spent":"783.066422ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T03:02:05.824962Z","caller":"traceutil/trace.go:171","msg":"trace[594399348] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2451; }","duration":"826.342016ms","start":"2026-05-08T03:02:04.998609Z","end":"2026-05-08T03:02:05.824951Z","steps":["trace[594399348] 'agreement among raft nodes before linearized reading' (duration: 826.253563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.824994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:04.998597Z","time spent":"826.389086ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-08T03:02:05.825074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.899394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T03:02:05.82511Z","caller":"traceutil/trace.go:171","msg":"trace[1176225388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2451; }","duration":"873.945465ms","start":"2026-05-08T03:02:04.951153Z","end":"2026-05-08T03:02:05.825098Z","steps":["trace[1176225388] 'agreement among raft nodes before linearized reading' (duration: 873.861643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:05.825136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:04.951143Z","time spent":"873.987926ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T03:02:05.825225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.412712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:131 size:100880"} {"level":"info","ts":"2026-05-08T03:02:05.825268Z","caller":"traceutil/trace.go:171","msg":"trace[468933155] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:131; response_revision:2451; }","duration":"284.474963ms","start":"2026-05-08T03:02:05.540781Z","end":"2026-05-08T03:02:05.825256Z","steps":["trace[468933155] 'agreement among raft nodes before linearized reading' (duration: 283.881469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.318776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.039527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T03:02:06.318875Z","caller":"traceutil/trace.go:171","msg":"trace[1972222541] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"487.638749ms","start":"2026-05-08T03:02:05.831219Z","end":"2026-05-08T03:02:06.318858Z","steps":["trace[1972222541] 'process raft request' (duration: 259.445998ms)","trace[1972222541] 'compare' (duration: 227.937264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T03:02:06.318931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.831195Z","time spent":"487.709811ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:06.350128Z","caller":"traceutil/trace.go:171","msg":"trace[950710140] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"518.788005ms","start":"2026-05-08T03:02:05.831274Z","end":"2026-05-08T03:02:06.350062Z","steps":["trace[950710140] 'process raft request' (duration: 518.61335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.350329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.831237Z","time spent":"518.969899ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:06.350349Z","caller":"traceutil/trace.go:171","msg":"trace[1765046083] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"518.944239ms","start":"2026-05-08T03:02:05.831389Z","end":"2026-05-08T03:02:06.350333Z","steps":["trace[1765046083] 'process raft request' (duration: 518.632861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.350431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.831378Z","time spent":"519.00991ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:06.350693Z","caller":"traceutil/trace.go:171","msg":"trace[606687528] linearizableReadLoop","detail":"{readStateIndex:2555; appliedIndex:2549; }","duration":"451.188032ms","start":"2026-05-08T03:02:05.899487Z","end":"2026-05-08T03:02:06.350675Z","steps":["trace[606687528] 'read index received' (duration: 191.123547ms)","trace[606687528] 'applied index is now lower than readState.Index' (duration: 260.063315ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T03:02:06.350679Z","caller":"traceutil/trace.go:171","msg":"trace[2025854751] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"517.502213ms","start":"2026-05-08T03:02:05.833128Z","end":"2026-05-08T03:02:06.35063Z","steps":["trace[2025854751] 'process raft request' (duration: 517.36286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.350914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.044673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-08T03:02:06.350961Z","caller":"traceutil/trace.go:171","msg":"trace[2095213607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:2457; }","duration":"443.133635ms","start":"2026-05-08T03:02:05.907817Z","end":"2026-05-08T03:02:06.35095Z","steps":["trace[2095213607] 'agreement among raft nodes before linearized reading' (duration: 443.031353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.350969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.833104Z","time spent":"517.724499ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:06.35104Z","caller":"traceutil/trace.go:171","msg":"trace[1013927551] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"517.813221ms","start":"2026-05-08T03:02:05.83321Z","end":"2026-05-08T03:02:06.351023Z","steps":["trace[1013927551] 'process raft request' (duration: 517.36755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.350993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.907795Z","time spent":"443.190636ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-08T03:02:06.351142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.833203Z","time spent":"517.889303ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T03:02:06.350962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.461399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-08T03:02:06.351196Z","caller":"traceutil/trace.go:171","msg":"trace[319981724] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2457; }","duration":"451.733006ms","start":"2026-05-08T03:02:05.89945Z","end":"2026-05-08T03:02:06.351183Z","steps":["trace[319981724] 'agreement among raft nodes before linearized reading' (duration: 451.436299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.351241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.899433Z","time spent":"451.795278ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-05-08T03:02:06.351355Z","caller":"traceutil/trace.go:171","msg":"trace[597983360] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"519.913672ms","start":"2026-05-08T03:02:05.831425Z","end":"2026-05-08T03:02:06.351339Z","steps":["trace[597983360] 'process raft request' (duration: 518.844816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.351441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:05.831416Z","time spent":"519.983745ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T03:02:06.735158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.498988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T03:02:06.735275Z","caller":"traceutil/trace.go:171","msg":"trace[405881302] linearizableReadLoop","detail":"{readStateIndex:2556; appliedIndex:2555; }","duration":"377.383637ms","start":"2026-05-08T03:02:06.357873Z","end":"2026-05-08T03:02:06.735256Z","steps":["trace[405881302] 'read index received' (duration: 126.692534ms)","trace[405881302] 'applied index is now lower than readState.Index' (duration: 250.689593ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T03:02:06.735373Z","caller":"traceutil/trace.go:171","msg":"trace[842416207] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"379.267233ms","start":"2026-05-08T03:02:06.356081Z","end":"2026-05-08T03:02:06.735348Z","steps":["trace[842416207] 'process raft request' (duration: 128.488638ms)","trace[842416207] 'compare' (duration: 250.377675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T03:02:06.735485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:06.356063Z","time spent":"379.366305ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T03:02:06.735854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.973322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-05-08T03:02:06.735959Z","caller":"traceutil/trace.go:171","msg":"trace[1176946286] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2458; }","duration":"378.102524ms","start":"2026-05-08T03:02:06.357836Z","end":"2026-05-08T03:02:06.735939Z","steps":["trace[1176946286] 'agreement among raft nodes before linearized reading' (duration: 377.51759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.736013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:06.357823Z","time spent":"378.172287ms","remote":"127.0.0.1:53642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":6,"response size":363705,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-05-08T03:02:06.735984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.549897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-08T03:02:06.736276Z","caller":"traceutil/trace.go:171","msg":"trace[2169749] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:2459; }","duration":"304.884734ms","start":"2026-05-08T03:02:06.431374Z","end":"2026-05-08T03:02:06.736258Z","steps":["trace[2169749] 'agreement among raft nodes before linearized reading' (duration: 304.492515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:06.736323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:06.431357Z","time spent":"304.953186ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-05-08T03:02:06.736346Z","caller":"traceutil/trace.go:171","msg":"trace[784583538] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"178.693193ms","start":"2026-05-08T03:02:06.557623Z","end":"2026-05-08T03:02:06.736316Z","steps":["trace[784583538] 'process raft request' (duration: 178.113179ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:02:12.208153Z","caller":"traceutil/trace.go:171","msg":"trace[1929672892] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"465.150127ms","start":"2026-05-08T03:02:11.742984Z","end":"2026-05-08T03:02:12.208134Z","steps":["trace[1929672892] 'process raft request' (duration: 465.045254ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T03:02:12.208184Z","caller":"traceutil/trace.go:171","msg":"trace[1002077317] linearizableReadLoop","detail":"{readStateIndex:2578; appliedIndex:2578; }","duration":"431.487469ms","start":"2026-05-08T03:02:11.776661Z","end":"2026-05-08T03:02:12.208149Z","steps":["trace[1002077317] 'read index received' (duration: 431.475589ms)","trace[1002077317] 'applied index is now lower than readState.Index' (duration: 9.71µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T03:02:12.20828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:11.742972Z","time spent":"465.242899ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T03:02:12.208431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.738495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T03:02:12.208554Z","caller":"traceutil/trace.go:171","msg":"trace[444280797] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:2479; }","duration":"431.903358ms","start":"2026-05-08T03:02:11.776632Z","end":"2026-05-08T03:02:12.208535Z","steps":["trace[444280797] 'agreement among raft nodes before linearized reading' (duration: 431.664063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:12.208602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:11.77662Z","time spent":"431.970721ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 "} {"level":"info","ts":"2026-05-08T03:02:12.239103Z","caller":"traceutil/trace.go:171","msg":"trace[79283835] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"398.118209ms","start":"2026-05-08T03:02:11.840959Z","end":"2026-05-08T03:02:12.239077Z","steps":["trace[79283835] 'process raft request' (duration: 397.821261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:12.239119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.320894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8smq6\" ","response":"range_response_count:1 size:5487"} {"level":"warn","ts":"2026-05-08T03:02:12.239203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:11.84094Z","time spent":"398.213001ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T03:02:12.239199Z","caller":"traceutil/trace.go:171","msg":"trace[255152741] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8smq6; range_end:; response_count:1; response_revision:2480; }","duration":"457.459728ms","start":"2026-05-08T03:02:11.781719Z","end":"2026-05-08T03:02:12.239178Z","steps":["trace[255152741] 'agreement among raft nodes before linearized reading' (duration: 457.226962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T03:02:12.239246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T03:02:11.781703Z","time spent":"457.53422ms","remote":"127.0.0.1:53744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5510,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-8smq6\" "}