{"level":"warn","ts":"2026-07-28T05:48:59.59024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T05:48:59.590323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-28T05:48:59.590381Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T05:48:59.590391Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-07-28T05:48:59.590415Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-28T05:48:59.591059Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-07-28T05:48:59.591135Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-28T05:48:59.645652Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.615726ms"} {"level":"info","ts":"2026-07-28T05:48:59.664315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-07-28T05:48:59.664456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T05:48:59.664487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-07-28T05:48:59.664511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T05:48:59.664523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-07-28T05:48:59.664577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-07-28T05:48:59.679303Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T05:48:59.683825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T05:48:59.684972Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-28T05:48:59.687123Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T05:48:59.687353Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T05:48:59.687571Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-28T05:48:59.687653Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-28T05:48:59.687671Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-28T05:48:59.689289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-07-28T05:48:59.689512Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-07-28T05:48:59.694542Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-28T05:48:59.694626Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-07-28T05:48:59.694679Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-07-28T05:48:59.694981Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T05:48:59.695044Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T05:49:00.365265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T05:49:00.365379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T05:49:00.365421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-07-28T05:49:00.365445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-07-28T05:49:00.365456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-07-28T05:49:00.365474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-07-28T05:49:00.365489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-07-28T05:49:00.366533Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T05:49:00.367769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T05:49:00.367989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T05:49:00.368043Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T05:49:00.368226Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T05:49:00.368476Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T05:49:00.368536Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T05:49:00.370944Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T05:49:00.37099Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T05:49:00.371368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-28T05:49:00.375101Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"warn","ts":"2026-07-28T05:49:02.834179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.714183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-28T05:49:02.834322Z","caller":"traceutil/trace.go:171","msg":"trace[1908378165] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:71; }","duration":"106.934536ms","start":"2026-07-28T05:49:02.727352Z","end":"2026-07-28T05:49:02.834286Z","steps":["trace[1908378165] 'range keys from in-memory index tree' (duration: 106.586311ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:02.835082Z","caller":"traceutil/trace.go:171","msg":"trace[1438987578] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"107.324503ms","start":"2026-07-28T05:49:02.727738Z","end":"2026-07-28T05:49:02.835062Z","steps":["trace[1438987578] 'process raft request' (duration: 106.869056ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:05.239327Z","caller":"traceutil/trace.go:171","msg":"trace[1499518399] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"109.321807ms","start":"2026-07-28T05:49:05.129971Z","end":"2026-07-28T05:49:05.239292Z","steps":["trace[1499518399] 'process raft request' (duration: 109.255576ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:05.239325Z","caller":"traceutil/trace.go:171","msg":"trace[2124828040] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"120.50889ms","start":"2026-07-28T05:49:05.11878Z","end":"2026-07-28T05:49:05.239289Z","steps":["trace[2124828040] 'process raft request' (duration: 62.867465ms)","trace[2124828040] 'compare' (duration: 57.387991ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:16.056865Z","caller":"traceutil/trace.go:171","msg":"trace[445357574] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"125.563323ms","start":"2026-07-28T05:49:15.931276Z","end":"2026-07-28T05:49:16.05684Z","steps":["trace[445357574] 'process raft request' (duration: 125.433421ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:16.117151Z","caller":"traceutil/trace.go:171","msg":"trace[1763699396] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"185.79838ms","start":"2026-07-28T05:49:15.931309Z","end":"2026-07-28T05:49:16.117107Z","steps":["trace[1763699396] 'process raft request' (duration: 160.598618ms)","trace[1763699396] 'compare' (duration: 25.01804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:17.266965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741299252499359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T05:49:17.327272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023604851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"info","ts":"2026-07-28T05:49:17.32738Z","caller":"traceutil/trace.go:171","msg":"trace[780824885] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:413; }","duration":"1.023764183s","start":"2026-07-28T05:49:16.303591Z","end":"2026-07-28T05:49:17.327355Z","steps":["trace[780824885] 'range keys from in-memory index tree' (duration: 1.023396938s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:17.327445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:16.303563Z","time spent":"1.023866724s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4818,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-07-28T05:49:17.330994Z","caller":"traceutil/trace.go:171","msg":"trace[1136398076] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"564.204349ms","start":"2026-07-28T05:49:16.766759Z","end":"2026-07-28T05:49:17.330963Z","steps":["trace[1136398076] 'read index received' (duration: 563.914715ms)","trace[1136398076] 'applied index is now lower than readState.Index' (duration: 288.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:17.331013Z","caller":"traceutil/trace.go:171","msg":"trace[2087436049] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"986.77035ms","start":"2026-07-28T05:49:16.344224Z","end":"2026-07-28T05:49:17.330995Z","steps":["trace[2087436049] 'process raft request' (duration: 986.531836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:17.331107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.339931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T05:49:17.331138Z","caller":"traceutil/trace.go:171","msg":"trace[1091418984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"564.414182ms","start":"2026-07-28T05:49:16.766714Z","end":"2026-07-28T05:49:17.331129Z","steps":["trace[1091418984] 'agreement among raft nodes before linearized reading' (duration: 564.343012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:17.331161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:16.76669Z","time spent":"564.465093ms","remote":"127.0.0.1:54462","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-07-28T05:49:17.331748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:16.344197Z","time spent":"986.884212ms","remote":"127.0.0.1:54462","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-07-28T05:49:18.383955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.235231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:49:18.384144Z","caller":"traceutil/trace.go:171","msg":"trace[1201365380] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"269.425327ms","start":"2026-07-28T05:49:18.114689Z","end":"2026-07-28T05:49:18.384114Z","steps":["trace[1201365380] 'process raft request' (duration: 152.738819ms)","trace[1201365380] 'compare' (duration: 116.075629ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:18.384142Z","caller":"traceutil/trace.go:171","msg":"trace[1860583422] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"263.840268ms","start":"2026-07-28T05:49:18.120234Z","end":"2026-07-28T05:49:18.384074Z","steps":["trace[1860583422] 'read index received' (duration: 147.136621ms)","trace[1860583422] 'applied index is now lower than readState.Index' (duration: 116.701967ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:18.384392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.027642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:49:18.384481Z","caller":"traceutil/trace.go:171","msg":"trace[1947508172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"264.246854ms","start":"2026-07-28T05:49:18.120218Z","end":"2026-07-28T05:49:18.384465Z","steps":["trace[1947508172] 'agreement among raft nodes before linearized reading' (duration: 263.996081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:18.385944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.188498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-28T05:49:18.386007Z","caller":"traceutil/trace.go:171","msg":"trace[1653208687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"239.28463ms","start":"2026-07-28T05:49:18.146705Z","end":"2026-07-28T05:49:18.38599Z","steps":["trace[1653208687] 'agreement among raft nodes before linearized reading' (duration: 239.168988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:22.485387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.859524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T05:49:22.485467Z","caller":"traceutil/trace.go:171","msg":"trace[287612474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"361.985596ms","start":"2026-07-28T05:49:22.123463Z","end":"2026-07-28T05:49:22.485448Z","steps":["trace[287612474] 'range keys from in-memory index tree' (duration: 361.743432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:22.48551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:22.123441Z","time spent":"362.060116ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T05:49:23.099552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741299252499396,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T05:49:23.174792Z","caller":"traceutil/trace.go:171","msg":"trace[1531109331] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"685.646195ms","start":"2026-07-28T05:49:22.489127Z","end":"2026-07-28T05:49:23.174773Z","steps":["trace[1531109331] 'process raft request' (duration: 685.494293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:23.174935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:22.489114Z","time spent":"685.761576ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:23.342998Z","caller":"traceutil/trace.go:171","msg":"trace[220247007] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"853.77973ms","start":"2026-07-28T05:49:22.489193Z","end":"2026-07-28T05:49:23.342973Z","steps":["trace[220247007] 'process raft request' (duration: 853.550417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:23.34318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:22.489168Z","time spent":"853.910371ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:23.343214Z","caller":"traceutil/trace.go:171","msg":"trace[1973105978] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:442; }","duration":"744.084759ms","start":"2026-07-28T05:49:22.599094Z","end":"2026-07-28T05:49:23.343179Z","steps":["trace[1973105978] 'read index received' (duration: 575.547249ms)","trace[1973105978] 'applied index is now lower than readState.Index' (duration: 168.5309ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:23.343301Z","caller":"traceutil/trace.go:171","msg":"trace[1316661232] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"852.648634ms","start":"2026-07-28T05:49:22.490624Z","end":"2026-07-28T05:49:23.343273Z","steps":["trace[1316661232] 'process raft request' (duration: 852.308579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:23.343356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.236941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:49:23.343427Z","caller":"traceutil/trace.go:171","msg":"trace[2046743611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"744.334883ms","start":"2026-07-28T05:49:22.59906Z","end":"2026-07-28T05:49:23.343395Z","steps":["trace[2046743611] 'agreement among raft nodes before linearized reading' (duration: 744.234121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:23.343451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:22.490605Z","time spent":"852.764345ms","remote":"127.0.0.1:54462","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-07-28T05:49:23.343478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:22.599046Z","time spent":"744.420405ms","remote":"127.0.0.1:54190","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-07-28T05:49:25.298521Z","caller":"traceutil/trace.go:171","msg":"trace[673630787] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"115.547073ms","start":"2026-07-28T05:49:25.182944Z","end":"2026-07-28T05:49:25.298491Z","steps":["trace[673630787] 'process raft request' (duration: 115.397101ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:25.838044Z","caller":"traceutil/trace.go:171","msg":"trace[1351035269] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"490.381831ms","start":"2026-07-28T05:49:25.347634Z","end":"2026-07-28T05:49:25.838016Z","steps":["trace[1351035269] 'read index received' (duration: 477.551797ms)","trace[1351035269] 'applied index is now lower than readState.Index' (duration: 12.829004ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:25.838089Z","caller":"traceutil/trace.go:171","msg":"trace[801788524] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"527.500015ms","start":"2026-07-28T05:49:25.310556Z","end":"2026-07-28T05:49:25.838056Z","steps":["trace[801788524] 'process raft request' (duration: 514.712122ms)","trace[801788524] 'compare' (duration: 12.606361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:25.83822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:25.310521Z","time spent":"527.632108ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:49:25.838278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.591603ms","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-07-28T05:49:25.838225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.571533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:49:25.838368Z","caller":"traceutil/trace.go:171","msg":"trace[461094529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"490.762836ms","start":"2026-07-28T05:49:25.347591Z","end":"2026-07-28T05:49:25.838354Z","steps":["trace[461094529] 'agreement among raft nodes before linearized reading' (duration: 490.574824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:25.838396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.703688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T05:49:25.838403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:25.347574Z","time spent":"490.819257ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T05:49:25.838427Z","caller":"traceutil/trace.go:171","msg":"trace[1425999390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"452.7767ms","start":"2026-07-28T05:49:25.385641Z","end":"2026-07-28T05:49:25.838418Z","steps":["trace[1425999390] 'agreement among raft nodes before linearized reading' (duration: 452.718868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:25.838465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:25.385609Z","time spent":"452.847281ms","remote":"127.0.0.1:54462","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-07-28T05:49:25.838472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.538445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:49:25.838332Z","caller":"traceutil/trace.go:171","msg":"trace[491741485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:434; }","duration":"490.624594ms","start":"2026-07-28T05:49:25.34768Z","end":"2026-07-28T05:49:25.838305Z","steps":["trace[491741485] 'agreement among raft nodes before linearized reading' (duration: 490.575243ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:25.838573Z","caller":"traceutil/trace.go:171","msg":"trace[1049525393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"238.690118ms","start":"2026-07-28T05:49:25.599859Z","end":"2026-07-28T05:49:25.838549Z","steps":["trace[1049525393] 'agreement among raft nodes before linearized reading' (duration: 238.501894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:25.838721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:25.347596Z","time spent":"490.97374ms","remote":"127.0.0.1:54462","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-07-28T05:49:28.513407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.614731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:49:28.513691Z","caller":"traceutil/trace.go:171","msg":"trace[1306300551] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"1.005447997s","start":"2026-07-28T05:49:27.508216Z","end":"2026-07-28T05:49:28.513664Z","steps":["trace[1306300551] 'process raft request' (duration: 1.005295655s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:28.513792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.508204Z","time spent":"1.005541688s","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:28.513829Z","caller":"traceutil/trace.go:171","msg":"trace[725038771] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"1.006282197s","start":"2026-07-28T05:49:27.507518Z","end":"2026-07-28T05:49:28.5138Z","steps":["trace[725038771] 'process raft request' (duration: 339.20177ms)","trace[725038771] 'compare' (duration: 666.438169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:28.513976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.507497Z","time spent":"1.006410109s","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T05:49:28.515228Z","caller":"traceutil/trace.go:171","msg":"trace[2003759862] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"1.006355158s","start":"2026-07-28T05:49:27.508849Z","end":"2026-07-28T05:49:28.515204Z","steps":["trace[2003759862] 'process raft request' (duration: 1.006210986s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:28.515392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.508836Z","time spent":"1.00646991s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:28.5154Z","caller":"traceutil/trace.go:171","msg":"trace[2145232754] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:461; }","duration":"915.571278ms","start":"2026-07-28T05:49:27.599795Z","end":"2026-07-28T05:49:28.515366Z","steps":["trace[2145232754] 'read index received' (duration: 246.93927ms)","trace[2145232754] 'applied index is now lower than readState.Index' (duration: 668.630198ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:28.515455Z","caller":"traceutil/trace.go:171","msg":"trace[493303078] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"1.006239827s","start":"2026-07-28T05:49:27.509197Z","end":"2026-07-28T05:49:28.515437Z","steps":["trace[493303078] 'process raft request' (duration: 1.005965534s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:28.515569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.744751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T05:49:28.515577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.509184Z","time spent":"1.006317189s","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:28.515612Z","caller":"traceutil/trace.go:171","msg":"trace[1097492420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"915.853613ms","start":"2026-07-28T05:49:27.599747Z","end":"2026-07-28T05:49:28.515601Z","steps":["trace[1097492420] 'agreement among raft nodes before linearized reading' (duration: 915.754822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:28.515644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.599728Z","time spent":"915.908354ms","remote":"127.0.0.1:54178","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-07-28T05:49:28.515685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.50073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:49:28.515743Z","caller":"traceutil/trace.go:171","msg":"trace[258665513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"666.595951ms","start":"2026-07-28T05:49:27.849133Z","end":"2026-07-28T05:49:28.515729Z","steps":["trace[258665513] 'agreement among raft nodes before linearized reading' (duration: 666.49911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:28.515782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.849115Z","time spent":"666.659723ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T05:49:28.515815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.897855ms","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-07-28T05:49:28.515836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.540763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T05:49:28.515868Z","caller":"traceutil/trace.go:171","msg":"trace[139669395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"654.964196ms","start":"2026-07-28T05:49:27.860891Z","end":"2026-07-28T05:49:28.515855Z","steps":["trace[139669395] 'agreement among raft nodes before linearized reading' (duration: 654.877624ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:28.515879Z","caller":"traceutil/trace.go:171","msg":"trace[1933089095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"655.611784ms","start":"2026-07-28T05:49:27.860254Z","end":"2026-07-28T05:49:28.515866Z","steps":["trace[1933089095] 'agreement among raft nodes before linearized reading' (duration: 655.539283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:28.515905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.860883Z","time spent":"655.014886ms","remote":"127.0.0.1:54462","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-07-28T05:49:28.515912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:27.860237Z","time spent":"655.668394ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T05:49:31.635547Z","caller":"traceutil/trace.go:171","msg":"trace[734988633] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"173.911024ms","start":"2026-07-28T05:49:31.461612Z","end":"2026-07-28T05:49:31.635523Z","steps":["trace[734988633] 'process raft request' (duration: 173.468839ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:38.707914Z","caller":"traceutil/trace.go:171","msg":"trace[584307729] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"121.862601ms","start":"2026-07-28T05:49:38.586022Z","end":"2026-07-28T05:49:38.707885Z","steps":["trace[584307729] 'process raft request' (duration: 121.760899ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:42.043055Z","caller":"traceutil/trace.go:171","msg":"trace[1541284713] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"202.373397ms","start":"2026-07-28T05:49:41.840635Z","end":"2026-07-28T05:49:42.043008Z","steps":["trace[1541284713] 'process raft request' (duration: 201.79745ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:42.043216Z","caller":"traceutil/trace.go:171","msg":"trace[121531171] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"208.213089ms","start":"2026-07-28T05:49:41.834976Z","end":"2026-07-28T05:49:42.043189Z","steps":["trace[121531171] 'process raft request' (duration: 158.373002ms)","trace[121531171] 'compare' (duration: 48.811935ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:42.241709Z","caller":"traceutil/trace.go:171","msg":"trace[1057539103] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"144.159826ms","start":"2026-07-28T05:49:42.097518Z","end":"2026-07-28T05:49:42.241678Z","steps":["trace[1057539103] 'process raft request' (duration: 120.782976ms)","trace[1057539103] 'compare' (duration: 23.100107ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:42.241805Z","caller":"traceutil/trace.go:171","msg":"trace[809902796] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"124.304361ms","start":"2026-07-28T05:49:42.117479Z","end":"2026-07-28T05:49:42.241784Z","steps":["trace[809902796] 'read index received' (duration: 100.794779ms)","trace[809902796] 'applied index is now lower than readState.Index' (duration: 23.508482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:42.241989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.495483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-28T05:49:42.242022Z","caller":"traceutil/trace.go:171","msg":"trace[978236743] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:619; }","duration":"124.573803ms","start":"2026-07-28T05:49:42.117439Z","end":"2026-07-28T05:49:42.242013Z","steps":["trace[978236743] 'agreement among raft nodes before linearized reading' (duration: 124.428212ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:42.437141Z","caller":"traceutil/trace.go:171","msg":"trace[88450260] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"199.461361ms","start":"2026-07-28T05:49:42.237657Z","end":"2026-07-28T05:49:42.437119Z","steps":["trace[88450260] 'process raft request' (duration: 126.577268ms)","trace[88450260] 'compare' (duration: 72.028102ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:42.437309Z","caller":"traceutil/trace.go:171","msg":"trace[1793817660] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"189.275455ms","start":"2026-07-28T05:49:42.248016Z","end":"2026-07-28T05:49:42.437291Z","steps":["trace[1793817660] 'process raft request' (duration: 189.060833ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:42.437325Z","caller":"traceutil/trace.go:171","msg":"trace[853341484] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"173.057354ms","start":"2026-07-28T05:49:42.264235Z","end":"2026-07-28T05:49:42.437292Z","steps":["trace[853341484] 'read index received' (duration: 99.904598ms)","trace[853341484] 'applied index is now lower than readState.Index' (duration: 73.150976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:42.43759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.352069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-07-28T05:49:42.437621Z","caller":"traceutil/trace.go:171","msg":"trace[706647025] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:621; }","duration":"173.427869ms","start":"2026-07-28T05:49:42.264184Z","end":"2026-07-28T05:49:42.437612Z","steps":["trace[706647025] 'agreement among raft nodes before linearized reading' (duration: 173.244147ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:42.741903Z","caller":"traceutil/trace.go:171","msg":"trace[233636461] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"105.952572ms","start":"2026-07-28T05:49:42.635928Z","end":"2026-07-28T05:49:42.74188Z","steps":["trace[233636461] 'read index received' (duration: 27.068035ms)","trace[233636461] 'applied index is now lower than readState.Index' (duration: 78.883757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:42.742092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.141134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-07-28T05:49:42.74213Z","caller":"traceutil/trace.go:171","msg":"trace[457210005] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:633; }","duration":"106.220846ms","start":"2026-07-28T05:49:42.635898Z","end":"2026-07-28T05:49:42.742118Z","steps":["trace[457210005] 'agreement among raft nodes before linearized reading' (duration: 106.113194ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:42.742081Z","caller":"traceutil/trace.go:171","msg":"trace[1005650296] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"113.259292ms","start":"2026-07-28T05:49:42.628754Z","end":"2026-07-28T05:49:42.742014Z","steps":["trace[1005650296] 'process raft request' (duration: 34.393245ms)","trace[1005650296] 'compare' (duration: 78.586734ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:43.27832Z","caller":"traceutil/trace.go:171","msg":"trace[636877229] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"433.094948ms","start":"2026-07-28T05:49:42.845207Z","end":"2026-07-28T05:49:43.278302Z","steps":["trace[636877229] 'read index received' (duration: 432.928707ms)","trace[636877229] 'applied index is now lower than readState.Index' (duration: 165.471µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:43.278409Z","caller":"traceutil/trace.go:171","msg":"trace[1081778214] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"446.455165ms","start":"2026-07-28T05:49:42.831938Z","end":"2026-07-28T05:49:43.278394Z","steps":["trace[1081778214] 'process raft request' (duration: 446.238221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:43.278486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.254581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-07-28T05:49:43.278519Z","caller":"traceutil/trace.go:171","msg":"trace[1489382314] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:640; }","duration":"433.304802ms","start":"2026-07-28T05:49:42.845203Z","end":"2026-07-28T05:49:43.278507Z","steps":["trace[1489382314] 'agreement among raft nodes before linearized reading' (duration: 433.17095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:43.278541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:42.831915Z","time spent":"446.553485ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:49:43.278572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:42.845172Z","time spent":"433.382333ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":20889,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-28T05:49:43.301736Z","caller":"traceutil/trace.go:171","msg":"trace[739962470] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"124.862879ms","start":"2026-07-28T05:49:43.176855Z","end":"2026-07-28T05:49:43.301718Z","steps":["trace[739962470] 'process raft request' (duration: 124.834639ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:43.301783Z","caller":"traceutil/trace.go:171","msg":"trace[980920628] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"323.306117ms","start":"2026-07-28T05:49:42.97846Z","end":"2026-07-28T05:49:43.301767Z","steps":["trace[980920628] 'process raft request' (duration: 323.126775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:43.301884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:42.978434Z","time spent":"323.406309ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T05:49:43.923019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741299252499762,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T05:49:44.317629Z","caller":"traceutil/trace.go:171","msg":"trace[360960436] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"960.147217ms","start":"2026-07-28T05:49:43.357458Z","end":"2026-07-28T05:49:44.317605Z","steps":["trace[360960436] 'process raft request' (duration: 959.991585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.317768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.357434Z","time spent":"960.262249ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:44.349782Z","caller":"traceutil/trace.go:171","msg":"trace[675835684] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"927.314891ms","start":"2026-07-28T05:49:43.422448Z","end":"2026-07-28T05:49:44.349763Z","steps":["trace[675835684] 'read index received' (duration: 895.076036ms)","trace[675835684] 'applied index is now lower than readState.Index' (duration: 32.237565ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:44.349899Z","caller":"traceutil/trace.go:171","msg":"trace[2033290252] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"973.764563ms","start":"2026-07-28T05:49:43.376125Z","end":"2026-07-28T05:49:44.34989Z","steps":["trace[2033290252] 'process raft request' (duration: 973.281276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.349983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.376106Z","time spent":"973.817544ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T05:49:44.350261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.783987ms","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-07-28T05:49:44.350324Z","caller":"traceutil/trace.go:171","msg":"trace[1991973100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"687.891499ms","start":"2026-07-28T05:49:43.662417Z","end":"2026-07-28T05:49:44.350309Z","steps":["trace[1991973100] 'agreement among raft nodes before linearized reading' (duration: 687.683466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.350366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.662392Z","time spent":"687.963699ms","remote":"127.0.0.1:54462","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-07-28T05:49:44.350353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.837067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-07-28T05:49:44.350528Z","caller":"traceutil/trace.go:171","msg":"trace[2047950316] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:649; }","duration":"928.09532ms","start":"2026-07-28T05:49:43.422409Z","end":"2026-07-28T05:49:44.350504Z","steps":["trace[2047950316] 'agreement among raft nodes before linearized reading' (duration: 927.679095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.350558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.195505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-07-28T05:49:44.350585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.422393Z","time spent":"928.179111ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15711,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-07-28T05:49:44.350616Z","caller":"traceutil/trace.go:171","msg":"trace[669923377] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:649; }","duration":"376.300287ms","start":"2026-07-28T05:49:43.9743Z","end":"2026-07-28T05:49:44.3506Z","steps":["trace[669923377] 'agreement among raft nodes before linearized reading' (duration: 376.107785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.350633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.69775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-28T05:49:44.350652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.974274Z","time spent":"376.369508ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-07-28T05:49:44.350626Z","caller":"traceutil/trace.go:171","msg":"trace[228874945] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"772.915837ms","start":"2026-07-28T05:49:43.577675Z","end":"2026-07-28T05:49:44.350591Z","steps":["trace[228874945] 'process raft request' (duration: 771.844475ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:44.350675Z","caller":"traceutil/trace.go:171","msg":"trace[1490408627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"444.767821ms","start":"2026-07-28T05:49:43.905896Z","end":"2026-07-28T05:49:44.350664Z","steps":["trace[1490408627] 'agreement among raft nodes before linearized reading' (duration: 444.636909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.350705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.905882Z","time spent":"444.816101ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T05:49:44.350728Z","caller":"traceutil/trace.go:171","msg":"trace[1385752818] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"145.118139ms","start":"2026-07-28T05:49:44.205597Z","end":"2026-07-28T05:49:44.350715Z","steps":["trace[1385752818] 'process raft request' (duration: 144.080617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.35076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.524115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-28T05:49:44.350769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.577641Z","time spent":"773.055778ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T05:49:44.350787Z","caller":"traceutil/trace.go:171","msg":"trace[2032265620] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:649; }","duration":"154.580275ms","start":"2026-07-28T05:49:44.196197Z","end":"2026-07-28T05:49:44.350777Z","steps":["trace[2032265620] 'agreement among raft nodes before linearized reading' (duration: 154.530955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.350922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.628848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-28T05:49:44.350954Z","caller":"traceutil/trace.go:171","msg":"trace[1199795303] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:649; }","duration":"576.700248ms","start":"2026-07-28T05:49:43.774243Z","end":"2026-07-28T05:49:44.350944Z","steps":["trace[1199795303] 'agreement among raft nodes before linearized reading' (duration: 576.609367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:44.35098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:43.774221Z","time spent":"576.753428ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-07-28T05:49:44.643036Z","caller":"traceutil/trace.go:171","msg":"trace[1810530148] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"154.88698ms","start":"2026-07-28T05:49:44.488124Z","end":"2026-07-28T05:49:44.643011Z","steps":["trace[1810530148] 'process raft request' (duration: 154.536236ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:47.261446Z","caller":"traceutil/trace.go:171","msg":"trace[1680768528] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"279.723513ms","start":"2026-07-28T05:49:46.981701Z","end":"2026-07-28T05:49:47.261425Z","steps":["trace[1680768528] 'process raft request' (duration: 278.657551ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:51.664372Z","caller":"traceutil/trace.go:171","msg":"trace[514211685] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"240.99847ms","start":"2026-07-28T05:49:51.423322Z","end":"2026-07-28T05:49:51.664321Z","steps":["trace[514211685] 'process raft request' (duration: 240.873698ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:51.664416Z","caller":"traceutil/trace.go:171","msg":"trace[2093152316] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:733; }","duration":"230.802359ms","start":"2026-07-28T05:49:51.433564Z","end":"2026-07-28T05:49:51.664366Z","steps":["trace[2093152316] 'read index received' (duration: 230.789999ms)","trace[2093152316] 'applied index is now lower than readState.Index' (duration: 9.81µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:51.665889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.313047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mqg79\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-28T05:49:51.665959Z","caller":"traceutil/trace.go:171","msg":"trace[734485247] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mqg79; range_end:; response_count:1; response_revision:707; }","duration":"232.426568ms","start":"2026-07-28T05:49:51.433512Z","end":"2026-07-28T05:49:51.665938Z","steps":["trace[734485247] 'agreement among raft nodes before linearized reading' (duration: 230.978491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:52.902661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741299252499915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T05:49:53.263714Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.583388568s","expected-duration":"1s"} {"level":"info","ts":"2026-07-28T05:49:53.264029Z","caller":"traceutil/trace.go:171","msg":"trace[432429853] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"1.583836273s","start":"2026-07-28T05:49:51.680176Z","end":"2026-07-28T05:49:53.264012Z","steps":["trace[432429853] 'process raft request' (duration: 1.583736142s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.264132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:51.680157Z","time spent":"1.583922985s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:53.326026Z","caller":"traceutil/trace.go:171","msg":"trace[839039274] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:733; }","duration":"924.374433ms","start":"2026-07-28T05:49:52.401615Z","end":"2026-07-28T05:49:53.325989Z","steps":["trace[839039274] 'read index received' (duration: 862.316172ms)","trace[839039274] 'applied index is now lower than readState.Index' (duration: 62.057121ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:53.326145Z","caller":"traceutil/trace.go:171","msg":"trace[1775466065] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"532.002928ms","start":"2026-07-28T05:49:52.794091Z","end":"2026-07-28T05:49:53.326094Z","steps":["trace[1775466065] 'process raft request' (duration: 481.538924ms)","trace[1775466065] 'compare' (duration: 50.222141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:53.326299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:52.794059Z","time spent":"532.148889ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:49:53.32631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.936928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T05:49:53.326308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.668686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-28T05:49:53.326353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.652284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:49:53.326364Z","caller":"traceutil/trace.go:171","msg":"trace[1943973679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"852.993468ms","start":"2026-07-28T05:49:52.473354Z","end":"2026-07-28T05:49:53.326348Z","steps":["trace[1943973679] 'agreement among raft nodes before linearized reading' (duration: 852.813537ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:53.326387Z","caller":"traceutil/trace.go:171","msg":"trace[1637518302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"443.720275ms","start":"2026-07-28T05:49:52.882657Z","end":"2026-07-28T05:49:53.326377Z","steps":["trace[1637518302] 'agreement among raft nodes before linearized reading' (duration: 443.605954ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:53.32638Z","caller":"traceutil/trace.go:171","msg":"trace[956190685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"924.786397ms","start":"2026-07-28T05:49:52.401574Z","end":"2026-07-28T05:49:53.326361Z","steps":["trace[956190685] 'agreement among raft nodes before linearized reading' (duration: 924.553854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.32641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:52.882639Z","time spent":"443.766615ms","remote":"127.0.0.1:54462","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-07-28T05:49:53.326407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:52.473292Z","time spent":"853.10397ms","remote":"127.0.0.1:54462","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-07-28T05:49:53.326426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:52.401547Z","time spent":"924.870428ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T05:49:53.326593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.13437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:49:53.326621Z","caller":"traceutil/trace.go:171","msg":"trace[420703252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"472.18544ms","start":"2026-07-28T05:49:52.854426Z","end":"2026-07-28T05:49:53.326612Z","steps":["trace[420703252] 'agreement among raft nodes before linearized reading' (duration: 472.10517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.326642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.241424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T05:49:53.326649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:52.854413Z","time spent":"472.230551ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T05:49:53.326673Z","caller":"traceutil/trace.go:171","msg":"trace[1169081444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"567.321624ms","start":"2026-07-28T05:49:52.759344Z","end":"2026-07-28T05:49:53.326666Z","steps":["trace[1169081444] 'agreement among raft nodes before linearized reading' (duration: 567.243803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.326692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:52.759309Z","time spent":"567.379145ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T05:49:53.66616Z","caller":"traceutil/trace.go:171","msg":"trace[409522442] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"335.274064ms","start":"2026-07-28T05:49:53.330864Z","end":"2026-07-28T05:49:53.666138Z","steps":["trace[409522442] 'process raft request' (duration: 335.113192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.666345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:53.330813Z","time spent":"335.444515ms","remote":"127.0.0.1:54462","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-07-28T05:49:53.66643Z","caller":"traceutil/trace.go:171","msg":"trace[67894091] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"335.063881ms","start":"2026-07-28T05:49:53.331344Z","end":"2026-07-28T05:49:53.666407Z","steps":["trace[67894091] 'process raft request' (duration: 334.756267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.666563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:53.331317Z","time spent":"335.176052ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:53.666809Z","caller":"traceutil/trace.go:171","msg":"trace[1343192563] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"335.213042ms","start":"2026-07-28T05:49:53.331582Z","end":"2026-07-28T05:49:53.666795Z","steps":["trace[1343192563] 'process raft request' (duration: 334.750487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.666919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:53.331557Z","time spent":"335.317083ms","remote":"127.0.0.1:54462","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-07-28T05:49:53.667124Z","caller":"traceutil/trace.go:171","msg":"trace[1032105894] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"334.951309ms","start":"2026-07-28T05:49:53.332141Z","end":"2026-07-28T05:49:53.667093Z","steps":["trace[1032105894] 'process raft request' (duration: 334.610326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:53.667308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:53.332105Z","time spent":"335.0972ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:55.770862Z","caller":"traceutil/trace.go:171","msg":"trace[455080822] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"171.022314ms","start":"2026-07-28T05:49:55.5998Z","end":"2026-07-28T05:49:55.770823Z","steps":["trace[455080822] 'read index received' (duration: 170.848142ms)","trace[455080822] 'applied index is now lower than readState.Index' (duration: 173.372µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:49:55.770911Z","caller":"traceutil/trace.go:171","msg":"trace[864097604] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"365.084797ms","start":"2026-07-28T05:49:55.405806Z","end":"2026-07-28T05:49:55.770891Z","steps":["trace[864097604] 'process raft request' (duration: 364.845924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:55.770996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.191276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:49:55.771028Z","caller":"traceutil/trace.go:171","msg":"trace[2028497641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"171.280357ms","start":"2026-07-28T05:49:55.599739Z","end":"2026-07-28T05:49:55.771019Z","steps":["trace[2028497641] 'agreement among raft nodes before linearized reading' (duration: 171.217416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:55.771035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:55.405779Z","time spent":"365.190548ms","remote":"127.0.0.1:54462","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-07-28T05:49:55.771132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.189997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:49:55.771191Z","caller":"traceutil/trace.go:171","msg":"trace[247953808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:716; }","duration":"100.298499ms","start":"2026-07-28T05:49:55.670877Z","end":"2026-07-28T05:49:55.771175Z","steps":["trace[247953808] 'agreement among raft nodes before linearized reading' (duration: 100.119577ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:56.358507Z","caller":"traceutil/trace.go:171","msg":"trace[1718512720] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"583.476145ms","start":"2026-07-28T05:49:55.775006Z","end":"2026-07-28T05:49:56.358482Z","steps":["trace[1718512720] 'process raft request' (duration: 561.065815ms)","trace[1718512720] 'compare' (duration: 22.313688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:49:56.358643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:55.774985Z","time spent":"583.590306ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:49:56.525409Z","caller":"traceutil/trace.go:171","msg":"trace[623821688] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"750.313311ms","start":"2026-07-28T05:49:55.77507Z","end":"2026-07-28T05:49:56.525383Z","steps":["trace[623821688] 'process raft request' (duration: 750.141889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:56.52558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:55.77505Z","time spent":"750.428082ms","remote":"127.0.0.1:54462","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-07-28T05:49:56.525632Z","caller":"traceutil/trace.go:171","msg":"trace[48099459] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"749.324419ms","start":"2026-07-28T05:49:55.776288Z","end":"2026-07-28T05:49:56.525612Z","steps":["trace[48099459] 'process raft request' (duration: 749.249478ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:49:56.525636Z","caller":"traceutil/trace.go:171","msg":"trace[715350406] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"749.961466ms","start":"2026-07-28T05:49:55.775657Z","end":"2026-07-28T05:49:56.525619Z","steps":["trace[715350406] 'process raft request' (duration: 749.685293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:49:56.525734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:55.77628Z","time spent":"749.40088ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:49:56.525735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:49:55.775641Z","time spent":"750.046028ms","remote":"127.0.0.1:54462","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-07-28T05:50:10.530816Z","caller":"traceutil/trace.go:171","msg":"trace[1125265033] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"106.951925ms","start":"2026-07-28T05:50:10.423841Z","end":"2026-07-28T05:50:10.530793Z","steps":["trace[1125265033] 'process raft request' (duration: 106.762993ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:14.230403Z","caller":"traceutil/trace.go:171","msg":"trace[167248954] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"323.714587ms","start":"2026-07-28T05:50:13.906652Z","end":"2026-07-28T05:50:14.230366Z","steps":["trace[167248954] 'process raft request' (duration: 323.264412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:14.230572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:13.906636Z","time spent":"323.852068ms","remote":"127.0.0.1:54462","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-07-28T05:50:14.898906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.205016ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-28T05:50:14.899027Z","caller":"traceutil/trace.go:171","msg":"trace[27567960] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"354.241484ms","start":"2026-07-28T05:50:14.544759Z","end":"2026-07-28T05:50:14.899001Z","steps":["trace[27567960] 'read index received' (duration: 252.878646ms)","trace[27567960] 'applied index is now lower than readState.Index' (duration: 101.361018ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:14.899116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:14.231754Z","time spent":"667.355523ms","remote":"127.0.0.1:54212","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-28T05:50:14.899438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.670948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:50:14.899492Z","caller":"traceutil/trace.go:171","msg":"trace[48440828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"354.775949ms","start":"2026-07-28T05:50:14.544702Z","end":"2026-07-28T05:50:14.899478Z","steps":["trace[48440828] 'agreement among raft nodes before linearized reading' (duration: 354.503526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:14.899528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:14.54466Z","time spent":"354.8602ms","remote":"127.0.0.1:54462","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-07-28T05:50:14.905702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.614338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:50:14.905774Z","caller":"traceutil/trace.go:171","msg":"trace[1231186471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:837; }","duration":"305.712949ms","start":"2026-07-28T05:50:14.600044Z","end":"2026-07-28T05:50:14.905757Z","steps":["trace[1231186471] 'agreement among raft nodes before linearized reading' (duration: 305.585137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:14.905815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:14.60003Z","time spent":"305.77818ms","remote":"127.0.0.1:54178","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-07-28T05:50:14.90586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.029197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:50:14.905917Z","caller":"traceutil/trace.go:171","msg":"trace[1488886892] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"275.27912ms","start":"2026-07-28T05:50:14.630612Z","end":"2026-07-28T05:50:14.905891Z","steps":["trace[1488886892] 'process raft request' (duration: 274.869295ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:14.905948Z","caller":"traceutil/trace.go:171","msg":"trace[606211773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:837; }","duration":"250.189508ms","start":"2026-07-28T05:50:14.655739Z","end":"2026-07-28T05:50:14.905928Z","steps":["trace[606211773] 'agreement among raft nodes before linearized reading' (duration: 249.956575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:14.905937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.931597ms","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-07-28T05:50:14.906066Z","caller":"traceutil/trace.go:171","msg":"trace[1364256777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:837; }","duration":"251.115769ms","start":"2026-07-28T05:50:14.654919Z","end":"2026-07-28T05:50:14.906035Z","steps":["trace[1364256777] 'agreement among raft nodes before linearized reading' (duration: 250.791855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:14.906322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.906598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T05:50:14.906495Z","caller":"traceutil/trace.go:171","msg":"trace[2024135017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:837; }","duration":"279.269912ms","start":"2026-07-28T05:50:14.627183Z","end":"2026-07-28T05:50:14.906453Z","steps":["trace[2024135017] 'agreement among raft nodes before linearized reading' (duration: 278.626956ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:27.838968Z","caller":"traceutil/trace.go:171","msg":"trace[547738583] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"101.1432ms","start":"2026-07-28T05:50:27.737802Z","end":"2026-07-28T05:50:27.838945Z","steps":["trace[547738583] 'process raft request' (duration: 100.901738ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:29.152614Z","caller":"traceutil/trace.go:171","msg":"trace[1859293439] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"112.490711ms","start":"2026-07-28T05:50:29.040097Z","end":"2026-07-28T05:50:29.152588Z","steps":["trace[1859293439] 'process raft request' (duration: 112.264879ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:29.152941Z","caller":"traceutil/trace.go:171","msg":"trace[46986562] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"107.721492ms","start":"2026-07-28T05:50:29.045194Z","end":"2026-07-28T05:50:29.152915Z","steps":["trace[46986562] 'process raft request' (duration: 107.600241ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:29.153026Z","caller":"traceutil/trace.go:171","msg":"trace[1887572673] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"112.860344ms","start":"2026-07-28T05:50:29.040149Z","end":"2026-07-28T05:50:29.15301Z","steps":["trace[1887572673] 'process raft request' (duration: 112.389429ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:29.586745Z","caller":"traceutil/trace.go:171","msg":"trace[109489033] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"427.757605ms","start":"2026-07-28T05:50:29.158962Z","end":"2026-07-28T05:50:29.586719Z","steps":["trace[109489033] 'process raft request' (duration: 427.556463ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:29.586903Z","caller":"traceutil/trace.go:171","msg":"trace[801569097] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"424.852474ms","start":"2026-07-28T05:50:29.16202Z","end":"2026-07-28T05:50:29.586872Z","steps":["trace[801569097] 'process raft request' (duration: 424.643212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:29.586985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.158935Z","time spent":"427.889066ms","remote":"127.0.0.1:54340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:50:29.587067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.162001Z","time spent":"424.991366ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:50:30.452306Z","caller":"traceutil/trace.go:171","msg":"trace[840986034] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.152407802s","start":"2026-07-28T05:50:29.299858Z","end":"2026-07-28T05:50:30.452266Z","steps":["trace[840986034] 'process raft request' (duration: 1.152030868s)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:30.452287Z","caller":"traceutil/trace.go:171","msg":"trace[1629038542] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1042; }","duration":"1.069721728s","start":"2026-07-28T05:50:29.382529Z","end":"2026-07-28T05:50:30.452251Z","steps":["trace[1629038542] 'read index received' (duration: 204.100417ms)","trace[1629038542] 'applied index is now lower than readState.Index' (duration: 865.619871ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:50:30.452464Z","caller":"traceutil/trace.go:171","msg":"trace[40168238] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.096883598s","start":"2026-07-28T05:50:29.355457Z","end":"2026-07-28T05:50:30.452341Z","steps":["trace[40168238] 'process raft request' (duration: 1.096702267s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.45249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.299828Z","time spent":"1.152571903s","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T05:50:30.452942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070388596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"warn","ts":"2026-07-28T05:50:30.452948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.355422Z","time spent":"1.097153671s","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:50:30.453357Z","caller":"traceutil/trace.go:171","msg":"trace[1629014351] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:1011; }","duration":"1.070511036s","start":"2026-07-28T05:50:29.382475Z","end":"2026-07-28T05:50:30.452986Z","steps":["trace[1629014351] 'agreement among raft nodes before linearized reading' (duration: 1.0699692s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.453649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.382453Z","time spent":"1.071180183s","remote":"127.0.0.1:54288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1487,"request content":"key:\"/registry/configmaps/local-path-storage/local-path-config\" "} {"level":"info","ts":"2026-07-28T05:50:30.798682Z","caller":"traceutil/trace.go:171","msg":"trace[1358516565] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.202443358s","start":"2026-07-28T05:50:29.596207Z","end":"2026-07-28T05:50:30.798651Z","steps":["trace[1358516565] 'process raft request' (duration: 1.201405907s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.798912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.596188Z","time spent":"1.202570679s","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:50:30.995424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.948847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T05:50:30.995861Z","caller":"traceutil/trace.go:171","msg":"trace[681327761] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"539.742424ms","start":"2026-07-28T05:50:30.4561Z","end":"2026-07-28T05:50:30.995842Z","steps":["trace[681327761] 'process raft request' (duration: 539.699624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.995957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:30.456077Z","time spent":"539.827235ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T05:50:30.996318Z","caller":"traceutil/trace.go:171","msg":"trace[278285912] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.150854938s","start":"2026-07-28T05:50:29.845415Z","end":"2026-07-28T05:50:30.99627Z","steps":["trace[278285912] 'process raft request' (duration: 1.150209341s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.996487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.397151511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T05:50:30.996489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.155893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T05:50:30.996537Z","caller":"traceutil/trace.go:171","msg":"trace[666617870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"1.397206952s","start":"2026-07-28T05:50:29.599318Z","end":"2026-07-28T05:50:30.996525Z","steps":["trace[666617870] 'agreement among raft nodes before linearized reading' (duration: 1.397115841s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.996528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.845386Z","time spent":"1.151003641s","remote":"127.0.0.1:54462","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-07-28T05:50:30.996556Z","caller":"traceutil/trace.go:171","msg":"trace[889154432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"692.265014ms","start":"2026-07-28T05:50:30.304275Z","end":"2026-07-28T05:50:30.99654Z","steps":["trace[889154432] 'agreement among raft nodes before linearized reading' (duration: 692.030522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.996575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:29.599291Z","time spent":"1.397277232s","remote":"127.0.0.1:54178","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-07-28T05:50:30.996596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:30.304261Z","time spent":"692.328216ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T05:50:30.99673Z","caller":"traceutil/trace.go:171","msg":"trace[1937822527] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1046; }","duration":"543.878978ms","start":"2026-07-28T05:50:30.452405Z","end":"2026-07-28T05:50:30.996284Z","steps":["trace[1937822527] 'read index received' (duration: 345.171573ms)","trace[1937822527] 'applied index is now lower than readState.Index' (duration: 198.705045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:30.99687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.718852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:50:30.997474Z","caller":"traceutil/trace.go:171","msg":"trace[2065658331] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1014; }","duration":"397.35452ms","start":"2026-07-28T05:50:30.600101Z","end":"2026-07-28T05:50:30.997455Z","steps":["trace[2065658331] 'agreement among raft nodes before linearized reading' (duration: 396.728943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:30.997603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:30.600083Z","time spent":"397.468971ms","remote":"127.0.0.1:54434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-28T05:50:31.504933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741299252500574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T05:50:31.672218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.197662ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:50:31.672366Z","caller":"traceutil/trace.go:171","msg":"trace[392938210] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1014; }","duration":"572.356634ms","start":"2026-07-28T05:50:31.099989Z","end":"2026-07-28T05:50:31.672345Z","steps":["trace[392938210] 'range keys from in-memory index tree' (duration: 572.180781ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:31.732895Z","caller":"traceutil/trace.go:171","msg":"trace[2114184170] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"728.503386ms","start":"2026-07-28T05:50:31.004372Z","end":"2026-07-28T05:50:31.732876Z","steps":["trace[2114184170] 'read index received' (duration: 728.285964ms)","trace[2114184170] 'applied index is now lower than readState.Index' (duration: 216.172µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:31.733037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.651967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:50:31.733058Z","caller":"traceutil/trace.go:171","msg":"trace[1813504609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"728.705928ms","start":"2026-07-28T05:50:31.004345Z","end":"2026-07-28T05:50:31.733051Z","steps":["trace[1813504609] 'agreement among raft nodes before linearized reading' (duration: 728.654377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:31.733078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.004336Z","time spent":"728.738158ms","remote":"127.0.0.1:54178","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-07-28T05:50:31.73324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.5013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-bg5cb\" ","response":"range_response_count:1 size:3611"} {"level":"warn","ts":"2026-07-28T05:50:31.733256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.675268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-28T05:50:31.733293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.808423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T05:50:31.733336Z","caller":"traceutil/trace.go:171","msg":"trace[863619927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"576.799389ms","start":"2026-07-28T05:50:31.156519Z","end":"2026-07-28T05:50:31.733319Z","steps":["trace[863619927] 'agreement among raft nodes before linearized reading' (duration: 576.582047ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:31.733332Z","caller":"traceutil/trace.go:171","msg":"trace[35066602] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-bg5cb; range_end:; response_count:1; response_revision:1015; }","duration":"707.632002ms","start":"2026-07-28T05:50:31.025677Z","end":"2026-07-28T05:50:31.733309Z","steps":["trace[35066602] 'agreement among raft nodes before linearized reading' (duration: 707.39263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:31.733368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.797179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:50:31.733362Z","caller":"traceutil/trace.go:171","msg":"trace[843482979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"665.903803ms","start":"2026-07-28T05:50:31.06744Z","end":"2026-07-28T05:50:31.733344Z","steps":["trace[843482979] 'agreement among raft nodes before linearized reading' (duration: 665.724962ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:31.733387Z","caller":"traceutil/trace.go:171","msg":"trace[2130935473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"694.83767ms","start":"2026-07-28T05:50:31.038544Z","end":"2026-07-28T05:50:31.733382Z","steps":["trace[2130935473] 'agreement among raft nodes before linearized reading' (duration: 694.758279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:31.73338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.156502Z","time spent":"576.868841ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T05:50:31.733402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.038534Z","time spent":"694.865011ms","remote":"127.0.0.1:54462","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-07-28T05:50:31.733406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.067428Z","time spent":"665.968124ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T05:50:31.73341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.025658Z","time spent":"707.738553ms","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3634,"request content":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-bg5cb\" "} {"level":"warn","ts":"2026-07-28T05:50:31.733429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.337764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-28T05:50:31.733689Z","caller":"traceutil/trace.go:171","msg":"trace[909948816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"576.479186ms","start":"2026-07-28T05:50:31.157023Z","end":"2026-07-28T05:50:31.733502Z","steps":["trace[909948816] 'agreement among raft nodes before linearized reading' (duration: 576.230483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:31.733832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.157002Z","time spent":"576.81611ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-28T05:50:31.733581Z","caller":"traceutil/trace.go:171","msg":"trace[844405417] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"731.993002ms","start":"2026-07-28T05:50:31.001009Z","end":"2026-07-28T05:50:31.733002Z","steps":["trace[844405417] 'process raft request' (duration: 731.73559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:31.734676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.00099Z","time spent":"733.604668ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:50:31.854113Z","caller":"traceutil/trace.go:171","msg":"trace[1306465562] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"116.386864ms","start":"2026-07-28T05:50:31.737695Z","end":"2026-07-28T05:50:31.854082Z","steps":["trace[1306465562] 'process raft request' (duration: 52.56608ms)","trace[1306465562] 'compare' (duration: 63.613472ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:50:31.877488Z","caller":"traceutil/trace.go:171","msg":"trace[815438801] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"138.640972ms","start":"2026-07-28T05:50:31.738824Z","end":"2026-07-28T05:50:31.877465Z","steps":["trace[815438801] 'process raft request' (duration: 138.500021ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:31.877537Z","caller":"traceutil/trace.go:171","msg":"trace[329324035] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"136.240548ms","start":"2026-07-28T05:50:31.741268Z","end":"2026-07-28T05:50:31.877509Z","steps":["trace[329324035] 'process raft request' (duration: 136.095216ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:31.877483Z","caller":"traceutil/trace.go:171","msg":"trace[2059848871] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"138.802774ms","start":"2026-07-28T05:50:31.738651Z","end":"2026-07-28T05:50:31.877454Z","steps":["trace[2059848871] 'process raft request' (duration: 138.527031ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:31.877673Z","caller":"traceutil/trace.go:171","msg":"trace[39174082] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"134.605681ms","start":"2026-07-28T05:50:31.743054Z","end":"2026-07-28T05:50:31.877659Z","steps":["trace[39174082] 'process raft request' (duration: 134.349009ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:32.230027Z","caller":"traceutil/trace.go:171","msg":"trace[1899965118] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"181.369426ms","start":"2026-07-28T05:50:32.048612Z","end":"2026-07-28T05:50:32.229981Z","steps":["trace[1899965118] 'read index received' (duration: 130.988381ms)","trace[1899965118] 'applied index is now lower than readState.Index' (duration: 50.379605ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:50:32.230056Z","caller":"traceutil/trace.go:171","msg":"trace[170773867] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"345.848112ms","start":"2026-07-28T05:50:31.884163Z","end":"2026-07-28T05:50:32.230011Z","steps":["trace[170773867] 'process raft request' (duration: 295.507737ms)","trace[170773867] 'compare' (duration: 50.129213ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:32.230166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.540779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:50:32.230236Z","caller":"traceutil/trace.go:171","msg":"trace[212855584] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1021; }","duration":"181.654139ms","start":"2026-07-28T05:50:32.048567Z","end":"2026-07-28T05:50:32.230221Z","steps":["trace[212855584] 'agreement among raft nodes before linearized reading' (duration: 181.530088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:32.230348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.373245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T05:50:32.230382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:31.88414Z","time spent":"346.091615ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:50:32.230396Z","caller":"traceutil/trace.go:171","msg":"trace[1395887104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"176.457526ms","start":"2026-07-28T05:50:32.053928Z","end":"2026-07-28T05:50:32.230385Z","steps":["trace[1395887104] 'agreement among raft nodes before linearized reading' (duration: 176.360445ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:32.380962Z","caller":"traceutil/trace.go:171","msg":"trace[920479049] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"143.811072ms","start":"2026-07-28T05:50:32.237096Z","end":"2026-07-28T05:50:32.380907Z","steps":["trace[920479049] 'process raft request' (duration: 136.086482ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:46.098296Z","caller":"traceutil/trace.go:171","msg":"trace[351512697] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"121.547711ms","start":"2026-07-28T05:50:45.976711Z","end":"2026-07-28T05:50:46.098259Z","steps":["trace[351512697] 'process raft request' (duration: 53.312327ms)","trace[351512697] 'compare' (duration: 68.090973ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:50:46.101726Z","caller":"traceutil/trace.go:171","msg":"trace[1235496757] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"124.468649ms","start":"2026-07-28T05:50:45.977219Z","end":"2026-07-28T05:50:46.101687Z","steps":["trace[1235496757] 'process raft request' (duration: 124.274557ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:46.101767Z","caller":"traceutil/trace.go:171","msg":"trace[407379491] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"124.190136ms","start":"2026-07-28T05:50:45.977547Z","end":"2026-07-28T05:50:46.101737Z","steps":["trace[407379491] 'process raft request' (duration: 124.058735ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:48.26088Z","caller":"traceutil/trace.go:171","msg":"trace[1860966800] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"157.774562ms","start":"2026-07-28T05:50:48.103079Z","end":"2026-07-28T05:50:48.260854Z","steps":["trace[1860966800] 'read index received' (duration: 103.391537ms)","trace[1860966800] 'applied index is now lower than readState.Index' (duration: 54.381515ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:50:48.261229Z","caller":"traceutil/trace.go:171","msg":"trace[819697816] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"194.313411ms","start":"2026-07-28T05:50:48.06688Z","end":"2026-07-28T05:50:48.261193Z","steps":["trace[819697816] 'process raft request' (duration: 139.666533ms)","trace[819697816] 'compare' (duration: 54.183553ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:48.261255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.166445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:50:48.261317Z","caller":"traceutil/trace.go:171","msg":"trace[1094108066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1075; }","duration":"158.240556ms","start":"2026-07-28T05:50:48.103061Z","end":"2026-07-28T05:50:48.261302Z","steps":["trace[1094108066] 'agreement among raft nodes before linearized reading' (duration: 158.033364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:48.261479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.67747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T05:50:48.261471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.859432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:50:48.261529Z","caller":"traceutil/trace.go:171","msg":"trace[170698972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1075; }","duration":"155.762981ms","start":"2026-07-28T05:50:48.105751Z","end":"2026-07-28T05:50:48.261514Z","steps":["trace[170698972] 'agreement among raft nodes before linearized reading' (duration: 155.596589ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:48.26156Z","caller":"traceutil/trace.go:171","msg":"trace[1054818333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1075; }","duration":"155.984803ms","start":"2026-07-28T05:50:48.105548Z","end":"2026-07-28T05:50:48.261533Z","steps":["trace[1054818333] 'agreement among raft nodes before linearized reading' (duration: 155.744191ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:48.683603Z","caller":"traceutil/trace.go:171","msg":"trace[2072244165] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"417.957119ms","start":"2026-07-28T05:50:48.265621Z","end":"2026-07-28T05:50:48.683578Z","steps":["trace[2072244165] 'process raft request' (duration: 338.405648ms)","trace[2072244165] 'compare' (duration: 79.315759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:48.683743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:48.265593Z","time spent":"418.079161ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:50:48.683837Z","caller":"traceutil/trace.go:171","msg":"trace[1180321718] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"418.060721ms","start":"2026-07-28T05:50:48.26575Z","end":"2026-07-28T05:50:48.683811Z","steps":["trace[1180321718] 'process raft request' (duration: 417.751148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:48.683972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:48.265722Z","time spent":"418.173822ms","remote":"127.0.0.1:54462","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-07-28T05:50:48.683835Z","caller":"traceutil/trace.go:171","msg":"trace[1034841660] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"417.310683ms","start":"2026-07-28T05:50:48.266503Z","end":"2026-07-28T05:50:48.683814Z","steps":["trace[1034841660] 'process raft request' (duration: 417.237183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:48.684106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:48.266397Z","time spent":"417.686538ms","remote":"127.0.0.1:54462","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-07-28T05:50:48.69539Z","caller":"traceutil/trace.go:171","msg":"trace[1998861905] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1079; }","duration":"382.155708ms","start":"2026-07-28T05:50:48.313212Z","end":"2026-07-28T05:50:48.695367Z","steps":["trace[1998861905] 'process raft request' (duration: 382.003846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:48.695518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:48.313192Z","time spent":"382.237428ms","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-07-28T05:50:51.031005Z","caller":"traceutil/trace.go:171","msg":"trace[865911687] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"144.877577ms","start":"2026-07-28T05:50:50.886104Z","end":"2026-07-28T05:50:51.030982Z","steps":["trace[865911687] 'process raft request' (duration: 144.821707ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:51.031128Z","caller":"traceutil/trace.go:171","msg":"trace[872100126] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"337.829226ms","start":"2026-07-28T05:50:50.693275Z","end":"2026-07-28T05:50:51.031104Z","steps":["trace[872100126] 'process raft request' (duration: 337.532023ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:51.031176Z","caller":"traceutil/trace.go:171","msg":"trace[1685188787] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"337.936517ms","start":"2026-07-28T05:50:50.693206Z","end":"2026-07-28T05:50:51.031142Z","steps":["trace[1685188787] 'process raft request' (duration: 330.301752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:50:51.031273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:50.693247Z","time spent":"337.958517ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:50:51.031417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:50:50.693188Z","time spent":"338.096939ms","remote":"127.0.0.1:54462","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-07-28T05:50:53.307426Z","caller":"traceutil/trace.go:171","msg":"trace[28040476] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"106.893619ms","start":"2026-07-28T05:50:53.200509Z","end":"2026-07-28T05:50:53.307402Z","steps":["trace[28040476] 'process raft request' (duration: 106.803619ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:50:53.307701Z","caller":"traceutil/trace.go:171","msg":"trace[1002787670] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"262.865266ms","start":"2026-07-28T05:50:53.044768Z","end":"2026-07-28T05:50:53.307633Z","steps":["trace[1002787670] 'process raft request' (duration: 170.145985ms)","trace[1002787670] 'compare' (duration: 92.047875ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:50:57.576173Z","caller":"traceutil/trace.go:171","msg":"trace[1062119859] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"224.184833ms","start":"2026-07-28T05:50:57.351957Z","end":"2026-07-28T05:50:57.576142Z","steps":["trace[1062119859] 'process raft request' (duration: 196.327784ms)","trace[1062119859] 'compare' (duration: 27.714397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:50:59.653296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.487011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T05:50:59.793527Z","caller":"traceutil/trace.go:171","msg":"trace[1839262914] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"136.954915ms","start":"2026-07-28T05:50:59.656548Z","end":"2026-07-28T05:50:59.793503Z","steps":["trace[1839262914] 'process raft request' (duration: 136.767004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:00.144294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.06437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T05:51:00.144403Z","caller":"traceutil/trace.go:171","msg":"trace[64252724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"124.203961ms","start":"2026-07-28T05:51:00.020182Z","end":"2026-07-28T05:51:00.144386Z","steps":["trace[64252724] 'range keys from in-memory index tree' (duration: 123.92264ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:01.341905Z","caller":"traceutil/trace.go:171","msg":"trace[650343557] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"151.661091ms","start":"2026-07-28T05:51:01.190222Z","end":"2026-07-28T05:51:01.341883Z","steps":["trace[650343557] 'process raft request' (duration: 78.012197ms)","trace[650343557] 'compare' (duration: 73.550063ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:01.622083Z","caller":"traceutil/trace.go:171","msg":"trace[1468733512] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"262.051858ms","start":"2026-07-28T05:51:01.36Z","end":"2026-07-28T05:51:01.622052Z","steps":["trace[1468733512] 'process raft request' (duration: 261.757056ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:01.903938Z","caller":"traceutil/trace.go:171","msg":"trace[1276548610] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"102.36067ms","start":"2026-07-28T05:51:01.801547Z","end":"2026-07-28T05:51:01.903908Z","steps":["trace[1276548610] 'process raft request' (duration: 102.234949ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:02.321035Z","caller":"traceutil/trace.go:171","msg":"trace[1486379322] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"112.579736ms","start":"2026-07-28T05:51:02.208427Z","end":"2026-07-28T05:51:02.321007Z","steps":["trace[1486379322] 'process raft request' (duration: 112.414585ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:08.519582Z","caller":"traceutil/trace.go:171","msg":"trace[2057841239] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"134.812484ms","start":"2026-07-28T05:51:08.384744Z","end":"2026-07-28T05:51:08.519556Z","steps":["trace[2057841239] 'process raft request' (duration: 134.451731ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:09.004596Z","caller":"traceutil/trace.go:171","msg":"trace[687970129] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"404.210421ms","start":"2026-07-28T05:51:08.600362Z","end":"2026-07-28T05:51:09.004573Z","steps":["trace[687970129] 'read index received' (duration: 403.995249ms)","trace[687970129] 'applied index is now lower than readState.Index' (duration: 214.142µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:09.004632Z","caller":"traceutil/trace.go:171","msg":"trace[388872971] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"481.674684ms","start":"2026-07-28T05:51:08.522931Z","end":"2026-07-28T05:51:09.004605Z","steps":["trace[388872971] 'process raft request' (duration: 481.510903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:09.004767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:08.522909Z","time spent":"481.783805ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:51:09.004812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.417294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:51:09.005107Z","caller":"traceutil/trace.go:171","msg":"trace[2081235921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"404.752487ms","start":"2026-07-28T05:51:08.600327Z","end":"2026-07-28T05:51:09.00508Z","steps":["trace[2081235921] 'agreement among raft nodes before linearized reading' (duration: 404.347723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:09.005186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:08.600313Z","time spent":"404.849968ms","remote":"127.0.0.1:54178","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-07-28T05:51:09.279436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.770766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:09.279563Z","caller":"traceutil/trace.go:171","msg":"trace[993081767] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"235.712746ms","start":"2026-07-28T05:51:09.043832Z","end":"2026-07-28T05:51:09.279544Z","steps":["trace[993081767] 'process raft request' (duration: 90.748357ms)","trace[993081767] 'compare' (duration: 144.648915ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:09.481717Z","caller":"traceutil/trace.go:171","msg":"trace[898287723] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"198.388083ms","start":"2026-07-28T05:51:09.283287Z","end":"2026-07-28T05:51:09.481675Z","steps":["trace[898287723] 'process raft request' (duration: 113.142858ms)","trace[898287723] 'compare' (duration: 85.130534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:09.740638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.338268ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T05:51:09.740783Z","caller":"traceutil/trace.go:171","msg":"trace[951339044] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1189; }","duration":"188.784942ms","start":"2026-07-28T05:51:09.551971Z","end":"2026-07-28T05:51:09.740756Z","steps":["trace[951339044] 'read index received' (duration: 35.282883ms)","trace[951339044] 'applied index is now lower than readState.Index' (duration: 153.500369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:09.740895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.897033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:51:09.740934Z","caller":"traceutil/trace.go:171","msg":"trace[305241229] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1145; }","duration":"188.983774ms","start":"2026-07-28T05:51:09.551936Z","end":"2026-07-28T05:51:09.74092Z","steps":["trace[305241229] 'agreement among raft nodes before linearized reading' (duration: 188.884033ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:09.897791Z","caller":"traceutil/trace.go:171","msg":"trace[697574417] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"195.099911ms","start":"2026-07-28T05:51:09.702666Z","end":"2026-07-28T05:51:09.897766Z","steps":["trace[697574417] 'process raft request' (duration: 194.92305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:10.141206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.196023ms","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-07-28T05:51:10.141284Z","caller":"traceutil/trace.go:171","msg":"trace[1465704298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"120.328003ms","start":"2026-07-28T05:51:10.020939Z","end":"2026-07-28T05:51:10.141267Z","steps":["trace[1465704298] 'range keys from in-memory index tree' (duration: 120.003711ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:11.208177Z","caller":"traceutil/trace.go:171","msg":"trace[397026866] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"195.321176ms","start":"2026-07-28T05:51:11.012828Z","end":"2026-07-28T05:51:11.208149Z","steps":["trace[397026866] 'process raft request' (duration: 195.169105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:14.606456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.392109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:14.606657Z","caller":"traceutil/trace.go:171","msg":"trace[607817762] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"344.06708ms","start":"2026-07-28T05:51:14.262562Z","end":"2026-07-28T05:51:14.606629Z","steps":["trace[607817762] 'process raft request' (duration: 116.415299ms)","trace[607817762] 'compare' (duration: 227.254268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:14.60682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:14.26255Z","time spent":"344.187141ms","remote":"127.0.0.1:54462","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-07-28T05:51:15.655616Z","caller":"traceutil/trace.go:171","msg":"trace[502106181] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"100.352868ms","start":"2026-07-28T05:51:15.555228Z","end":"2026-07-28T05:51:15.655581Z","steps":["trace[502106181] 'process raft request' (duration: 100.035354ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:16.785398Z","caller":"traceutil/trace.go:171","msg":"trace[813070331] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"103.95427ms","start":"2026-07-28T05:51:16.681418Z","end":"2026-07-28T05:51:16.785372Z","steps":["trace[813070331] 'process raft request' (duration: 94.24705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:17.764324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.916818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:51:17.764408Z","caller":"traceutil/trace.go:171","msg":"trace[1771652881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"105.042889ms","start":"2026-07-28T05:51:17.659351Z","end":"2026-07-28T05:51:17.764393Z","steps":["trace[1771652881] 'range keys from in-memory index tree' (duration: 104.768576ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:17.881513Z","caller":"traceutil/trace.go:171","msg":"trace[1569274724] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"113.636769ms","start":"2026-07-28T05:51:17.767849Z","end":"2026-07-28T05:51:17.881486Z","steps":["trace[1569274724] 'process raft request' (duration: 113.470508ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:21.721547Z","caller":"traceutil/trace.go:171","msg":"trace[1193563271] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"118.435958ms","start":"2026-07-28T05:51:21.603041Z","end":"2026-07-28T05:51:21.721477Z","steps":["trace[1193563271] 'process raft request' (duration: 106.227225ms)","trace[1193563271] 'compare' (duration: 11.957401ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:26.085627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.228072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:51:26.085768Z","caller":"traceutil/trace.go:171","msg":"trace[882891667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"165.432563ms","start":"2026-07-28T05:51:25.920309Z","end":"2026-07-28T05:51:26.085742Z","steps":["trace[882891667] 'range keys from in-memory index tree' (duration: 164.98921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:27.810871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.904789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:51:27.810971Z","caller":"traceutil/trace.go:171","msg":"trace[1532795180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"210.078691ms","start":"2026-07-28T05:51:27.600871Z","end":"2026-07-28T05:51:27.81095Z","steps":["trace[1532795180] 'range keys from in-memory index tree' (duration: 209.793208ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:27.937953Z","caller":"traceutil/trace.go:171","msg":"trace[2106645705] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"122.99118ms","start":"2026-07-28T05:51:27.814936Z","end":"2026-07-28T05:51:27.937927Z","steps":["trace[2106645705] 'process raft request' (duration: 122.831839ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:28.218145Z","caller":"traceutil/trace.go:171","msg":"trace[1784570743] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"127.848913ms","start":"2026-07-28T05:51:28.090264Z","end":"2026-07-28T05:51:28.218113Z","steps":["trace[1784570743] 'process raft request' (duration: 127.628141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:28.734964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.218384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:28.735089Z","caller":"traceutil/trace.go:171","msg":"trace[1849078829] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"442.025216ms","start":"2026-07-28T05:51:28.293042Z","end":"2026-07-28T05:51:28.735067Z","steps":["trace[1849078829] 'read index received' (duration: 334.470209ms)","trace[1849078829] 'applied index is now lower than readState.Index' (duration: 107.553067ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:28.735213Z","caller":"traceutil/trace.go:171","msg":"trace[526407] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"513.589284ms","start":"2026-07-28T05:51:28.221607Z","end":"2026-07-28T05:51:28.735197Z","steps":["trace[526407] 'process raft request' (duration: 405.996937ms)","trace[526407] 'compare' (duration: 106.929242ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:28.735466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:28.221592Z","time spent":"513.667625ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:51:28.735496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.438321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T05:51:28.735567Z","caller":"traceutil/trace.go:171","msg":"trace[1180495463] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1206; }","duration":"442.549392ms","start":"2026-07-28T05:51:28.293Z","end":"2026-07-28T05:51:28.735549Z","steps":["trace[1180495463] 'agreement among raft nodes before linearized reading' (duration: 442.3735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:28.735618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:28.292987Z","time spent":"442.621263ms","remote":"127.0.0.1:54512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-28T05:51:28.735603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.47622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-28T05:51:28.735906Z","caller":"traceutil/trace.go:171","msg":"trace[163148049] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1206; }","duration":"370.772564ms","start":"2026-07-28T05:51:28.365051Z","end":"2026-07-28T05:51:28.735824Z","steps":["trace[163148049] 'agreement among raft nodes before linearized reading' (duration: 370.336119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:28.736057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:28.36503Z","time spent":"371.013425ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5571,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-28T05:51:29.319625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.422068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:29.319763Z","caller":"traceutil/trace.go:171","msg":"trace[1224093630] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"365.674292ms","start":"2026-07-28T05:51:28.954062Z","end":"2026-07-28T05:51:29.319736Z","steps":["trace[1224093630] 'read index received' (duration: 40.02µs)","trace[1224093630] 'applied index is now lower than readState.Index' (duration: 365.632222ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:29.319854Z","caller":"traceutil/trace.go:171","msg":"trace[2084052350] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"579.804198ms","start":"2026-07-28T05:51:28.740012Z","end":"2026-07-28T05:51:29.319816Z","steps":["trace[2084052350] 'process raft request' (duration: 150.093777ms)","trace[2084052350] 'compare' (duration: 429.294377ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:29.31995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.887904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T05:51:29.31995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:28.739993Z","time spent":"579.912998ms","remote":"127.0.0.1:54462","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-07-28T05:51:29.320006Z","caller":"traceutil/trace.go:171","msg":"trace[728011500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1207; }","duration":"366.164297ms","start":"2026-07-28T05:51:28.953827Z","end":"2026-07-28T05:51:29.319991Z","steps":["trace[728011500] 'agreement among raft nodes before linearized reading' (duration: 365.987626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:29.32004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:28.953806Z","time spent":"366.226637ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T05:51:29.567483Z","caller":"traceutil/trace.go:171","msg":"trace[1584172505] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"182.314681ms","start":"2026-07-28T05:51:29.38514Z","end":"2026-07-28T05:51:29.567455Z","steps":["trace[1584172505] 'read index received' (duration: 108.725905ms)","trace[1584172505] 'applied index is now lower than readState.Index' (duration: 73.587686ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:29.567579Z","caller":"traceutil/trace.go:171","msg":"trace[1533132738] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"242.132249ms","start":"2026-07-28T05:51:29.325408Z","end":"2026-07-28T05:51:29.567541Z","steps":["trace[1533132738] 'process raft request' (duration: 168.532204ms)","trace[1533132738] 'compare' (duration: 73.375913ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:29.56774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.582385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-28T05:51:29.567877Z","caller":"traceutil/trace.go:171","msg":"trace[65536547] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1208; }","duration":"182.758716ms","start":"2026-07-28T05:51:29.385103Z","end":"2026-07-28T05:51:29.567862Z","steps":["trace[65536547] 'agreement among raft nodes before linearized reading' (duration: 182.447463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:29.770045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.370535ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T05:51:29.770153Z","caller":"traceutil/trace.go:171","msg":"trace[706668409] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"202.569006ms","start":"2026-07-28T05:51:29.567566Z","end":"2026-07-28T05:51:29.770135Z","steps":["trace[706668409] 'read index received' (duration: 71.986069ms)","trace[706668409] 'applied index is now lower than readState.Index' (duration: 130.581527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:29.770352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.378085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T05:51:29.770392Z","caller":"traceutil/trace.go:171","msg":"trace[1521859066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"310.516187ms","start":"2026-07-28T05:51:29.459863Z","end":"2026-07-28T05:51:29.77038Z","steps":["trace[1521859066] 'agreement among raft nodes before linearized reading' (duration: 310.367196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:29.770371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.16436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:51:29.770473Z","caller":"traceutil/trace.go:171","msg":"trace[1567209021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"170.313751ms","start":"2026-07-28T05:51:29.60014Z","end":"2026-07-28T05:51:29.770454Z","steps":["trace[1567209021] 'agreement among raft nodes before linearized reading' (duration: 170.16075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:29.77043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:29.459846Z","time spent":"310.575377ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T05:51:30.845719Z","caller":"traceutil/trace.go:171","msg":"trace[595756729] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"100.757512ms","start":"2026-07-28T05:51:30.744937Z","end":"2026-07-28T05:51:30.845695Z","steps":["trace[595756729] 'process raft request' (duration: 100.624471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:34.522487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.807132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:34.522998Z","caller":"traceutil/trace.go:171","msg":"trace[2064260400] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"153.299246ms","start":"2026-07-28T05:51:34.369666Z","end":"2026-07-28T05:51:34.522965Z","steps":["trace[2064260400] 'process raft request' (duration: 152.941533ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:34.523481Z","caller":"traceutil/trace.go:171","msg":"trace[949670903] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"235.311614ms","start":"2026-07-28T05:51:34.287583Z","end":"2026-07-28T05:51:34.522895Z","steps":["trace[949670903] 'process raft request' (duration: 127.007697ms)","trace[949670903] 'compare' (duration: 107.677731ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:35.300389Z","caller":"traceutil/trace.go:171","msg":"trace[250032922] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"102.552833ms","start":"2026-07-28T05:51:35.197812Z","end":"2026-07-28T05:51:35.300365Z","steps":["trace[250032922] 'process raft request' (duration: 102.360761ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:40.773847Z","caller":"traceutil/trace.go:171","msg":"trace[866420956] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"100.203467ms","start":"2026-07-28T05:51:40.673617Z","end":"2026-07-28T05:51:40.773821Z","steps":["trace[866420956] 'read index received' (duration: 99.961064ms)","trace[866420956] 'applied index is now lower than readState.Index' (duration: 240.963µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:40.773961Z","caller":"traceutil/trace.go:171","msg":"trace[346315864] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"234.118729ms","start":"2026-07-28T05:51:40.539819Z","end":"2026-07-28T05:51:40.773938Z","steps":["trace[346315864] 'process raft request' (duration: 233.836616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:40.774147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.513109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-07-28T05:51:40.774216Z","caller":"traceutil/trace.go:171","msg":"trace[1830722782] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1244; }","duration":"100.631411ms","start":"2026-07-28T05:51:40.673566Z","end":"2026-07-28T05:51:40.774197Z","steps":["trace[1830722782] 'agreement among raft nodes before linearized reading' (duration: 100.364409ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:43.376892Z","caller":"traceutil/trace.go:171","msg":"trace[1672936580] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"410.408243ms","start":"2026-07-28T05:51:42.96646Z","end":"2026-07-28T05:51:43.376868Z","steps":["trace[1672936580] 'read index received' (duration: 410.189201ms)","trace[1672936580] 'applied index is now lower than readState.Index' (duration: 218.072µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:43.376952Z","caller":"traceutil/trace.go:171","msg":"trace[184138414] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"597.019409ms","start":"2026-07-28T05:51:42.779911Z","end":"2026-07-28T05:51:43.37693Z","steps":["trace[184138414] 'process raft request' (duration: 596.735556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:43.377089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:42.779884Z","time spent":"597.124759ms","remote":"127.0.0.1:54462","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-07-28T05:51:43.377135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.673635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T05:51:43.377183Z","caller":"traceutil/trace.go:171","msg":"trace[681864547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"410.763796ms","start":"2026-07-28T05:51:42.966402Z","end":"2026-07-28T05:51:43.377165Z","steps":["trace[681864547] 'agreement among raft nodes before linearized reading' (duration: 410.578984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:43.377224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:42.96638Z","time spent":"410.835776ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T05:51:43.923914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.507186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:43.92416Z","caller":"traceutil/trace.go:171","msg":"trace[1618832324] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"407.470355ms","start":"2026-07-28T05:51:43.51667Z","end":"2026-07-28T05:51:43.92414Z","steps":["trace[1618832324] 'read index received' (duration: 285.648946ms)","trace[1618832324] 'applied index is now lower than readState.Index' (duration: 121.819309ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:43.924358Z","caller":"traceutil/trace.go:171","msg":"trace[411277283] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"542.784967ms","start":"2026-07-28T05:51:43.381558Z","end":"2026-07-28T05:51:43.924343Z","steps":["trace[411277283] 'process raft request' (duration: 420.757156ms)","trace[411277283] 'compare' (duration: 121.367105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:43.924478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:43.381534Z","time spent":"542.905218ms","remote":"127.0.0.1:54462","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-07-28T05:51:43.924841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.155131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T05:51:43.924871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.172201ms","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-07-28T05:51:43.924973Z","caller":"traceutil/trace.go:171","msg":"trace[1196611610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"408.310393ms","start":"2026-07-28T05:51:43.516625Z","end":"2026-07-28T05:51:43.924935Z","steps":["trace[1196611610] 'agreement among raft nodes before linearized reading' (duration: 408.069181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:43.925022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:43.516604Z","time spent":"408.408674ms","remote":"127.0.0.1:54462","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-07-28T05:51:43.924963Z","caller":"traceutil/trace.go:171","msg":"trace[1010901138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"225.276572ms","start":"2026-07-28T05:51:43.699638Z","end":"2026-07-28T05:51:43.924914Z","steps":["trace[1010901138] 'agreement among raft nodes before linearized reading' (duration: 225.08479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:43.92589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.975573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-07-28T05:51:43.926008Z","caller":"traceutil/trace.go:171","msg":"trace[722738860] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1252; }","duration":"385.149155ms","start":"2026-07-28T05:51:43.54083Z","end":"2026-07-28T05:51:43.925979Z","steps":["trace[722738860] 'agreement among raft nodes before linearized reading' (duration: 383.792803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:43.926068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:51:43.540807Z","time spent":"385.250395ms","remote":"127.0.0.1:54278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050291,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-28T05:51:44.051694Z","caller":"traceutil/trace.go:171","msg":"trace[927126979] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"103.607274ms","start":"2026-07-28T05:51:43.948061Z","end":"2026-07-28T05:51:44.051668Z","steps":["trace[927126979] 'read index received' (duration: 56.704641ms)","trace[927126979] 'applied index is now lower than readState.Index' (duration: 46.901503ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:44.051784Z","caller":"traceutil/trace.go:171","msg":"trace[798995771] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"121.277534ms","start":"2026-07-28T05:51:43.930477Z","end":"2026-07-28T05:51:44.051755Z","steps":["trace[798995771] 'process raft request' (duration: 100.675618ms)","trace[798995771] 'compare' (duration: 20.402224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:44.05189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.800536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T05:51:44.051916Z","caller":"traceutil/trace.go:171","msg":"trace[1935386022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"103.882206ms","start":"2026-07-28T05:51:43.948025Z","end":"2026-07-28T05:51:44.051907Z","steps":["trace[1935386022] 'agreement among raft nodes before linearized reading' (duration: 103.736415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:44.348796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.902735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:44.348908Z","caller":"traceutil/trace.go:171","msg":"trace[498369389] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"146.491631ms","start":"2026-07-28T05:51:44.202395Z","end":"2026-07-28T05:51:44.348887Z","steps":["trace[498369389] 'read index received' (duration: 31.463434ms)","trace[498369389] 'applied index is now lower than readState.Index' (duration: 115.027177ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:44.348995Z","caller":"traceutil/trace.go:171","msg":"trace[2048061624] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"219.956153ms","start":"2026-07-28T05:51:44.129025Z","end":"2026-07-28T05:51:44.348981Z","steps":["trace[2048061624] 'process raft request' (duration: 104.789045ms)","trace[2048061624] 'compare' (duration: 114.767604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:44.349463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.056515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-28T05:51:44.349543Z","caller":"traceutil/trace.go:171","msg":"trace[144119362] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:1256; }","duration":"147.185656ms","start":"2026-07-28T05:51:44.20234Z","end":"2026-07-28T05:51:44.349526Z","steps":["trace[144119362] 'agreement among raft nodes before linearized reading' (duration: 146.966994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:44.583981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.747684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:51:44.584097Z","caller":"traceutil/trace.go:171","msg":"trace[2081599748] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"156.556621ms","start":"2026-07-28T05:51:44.427509Z","end":"2026-07-28T05:51:44.584066Z","steps":["trace[2081599748] 'process raft request' (duration: 21.650386ms)","trace[2081599748] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/masterleases/199.19.213.162; req_size:118; } (duration: 134.604123ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:44.956034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.138943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T05:51:44.956174Z","caller":"traceutil/trace.go:171","msg":"trace[1670779448] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1257; }","duration":"211.318065ms","start":"2026-07-28T05:51:44.74483Z","end":"2026-07-28T05:51:44.956148Z","steps":["trace[1670779448] 'count revisions from in-memory index tree' (duration: 211.064443ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:45.573207Z","caller":"traceutil/trace.go:171","msg":"trace[476222] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"190.374944ms","start":"2026-07-28T05:51:45.382799Z","end":"2026-07-28T05:51:45.573174Z","steps":["trace[476222] 'process raft request' (duration: 190.204462ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:45.605674Z","caller":"traceutil/trace.go:171","msg":"trace[1704426136] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"203.360452ms","start":"2026-07-28T05:51:45.402285Z","end":"2026-07-28T05:51:45.605645Z","steps":["trace[1704426136] 'process raft request' (duration: 203.19678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:45.843975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.929447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:51:45.844125Z","caller":"traceutil/trace.go:171","msg":"trace[380215359] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1259; }","duration":"143.122009ms","start":"2026-07-28T05:51:45.700981Z","end":"2026-07-28T05:51:45.844103Z","steps":["trace[380215359] 'count revisions from in-memory index tree' (duration: 142.851896ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:46.157101Z","caller":"traceutil/trace.go:171","msg":"trace[943117107] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"142.949366ms","start":"2026-07-28T05:51:46.014128Z","end":"2026-07-28T05:51:46.157078Z","steps":["trace[943117107] 'read index received' (duration: 119.769388ms)","trace[943117107] 'applied index is now lower than readState.Index' (duration: 23.179268ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:46.157131Z","caller":"traceutil/trace.go:171","msg":"trace[1954152903] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"143.678302ms","start":"2026-07-28T05:51:46.013417Z","end":"2026-07-28T05:51:46.157096Z","steps":["trace[1954152903] 'process raft request' (duration: 120.482864ms)","trace[1954152903] 'compare' (duration: 23.017006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:46.157297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.150108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T05:51:46.157333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.247042ms","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-07-28T05:51:46.157361Z","caller":"traceutil/trace.go:171","msg":"trace[1276104506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"101.333152ms","start":"2026-07-28T05:51:46.05602Z","end":"2026-07-28T05:51:46.157353Z","steps":["trace[1276104506] 'agreement among raft nodes before linearized reading' (duration: 101.186421ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:46.157364Z","caller":"traceutil/trace.go:171","msg":"trace[245722138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1261; }","duration":"143.26869ms","start":"2026-07-28T05:51:46.014081Z","end":"2026-07-28T05:51:46.15735Z","steps":["trace[245722138] 'agreement among raft nodes before linearized reading' (duration: 143.105689ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:46.558069Z","caller":"traceutil/trace.go:171","msg":"trace[1754624588] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"201.129899ms","start":"2026-07-28T05:51:46.356896Z","end":"2026-07-28T05:51:46.558026Z","steps":["trace[1754624588] 'process raft request' (duration: 200.738826ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:50.09787Z","caller":"traceutil/trace.go:171","msg":"trace[365499028] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"118.300864ms","start":"2026-07-28T05:51:49.979549Z","end":"2026-07-28T05:51:50.09785Z","steps":["trace[365499028] 'process raft request' (duration: 118.189041ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:55.50608Z","caller":"traceutil/trace.go:171","msg":"trace[690168036] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"109.715318ms","start":"2026-07-28T05:51:55.396336Z","end":"2026-07-28T05:51:55.506052Z","steps":["trace[690168036] 'process raft request' (duration: 79.024143ms)","trace[690168036] 'compare' (duration: 30.556562ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:55.515843Z","caller":"traceutil/trace.go:171","msg":"trace[1691810702] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"113.249134ms","start":"2026-07-28T05:51:55.402568Z","end":"2026-07-28T05:51:55.515818Z","steps":["trace[1691810702] 'read index received' (duration: 72.804523ms)","trace[1691810702] 'applied index is now lower than readState.Index' (duration: 40.443301ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:55.515878Z","caller":"traceutil/trace.go:171","msg":"trace[653477661] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"114.504718ms","start":"2026-07-28T05:51:55.401337Z","end":"2026-07-28T05:51:55.515842Z","steps":["trace[653477661] 'process raft request' (duration: 114.363193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:51:55.515998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.400658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:51:55.516067Z","caller":"traceutil/trace.go:171","msg":"trace[750304323] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1297; }","duration":"113.498561ms","start":"2026-07-28T05:51:55.40255Z","end":"2026-07-28T05:51:55.516049Z","steps":["trace[750304323] 'agreement among raft nodes before linearized reading' (duration: 113.356937ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:56.447942Z","caller":"traceutil/trace.go:171","msg":"trace[2087961348] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"104.866682ms","start":"2026-07-28T05:51:56.343054Z","end":"2026-07-28T05:51:56.447921Z","steps":["trace[2087961348] 'read index received' (duration: 56.835661ms)","trace[2087961348] 'applied index is now lower than readState.Index' (duration: 48.030011ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:51:56.448042Z","caller":"traceutil/trace.go:171","msg":"trace[389777144] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"106.040293ms","start":"2026-07-28T05:51:56.341974Z","end":"2026-07-28T05:51:56.448015Z","steps":["trace[389777144] 'process raft request' (duration: 57.978861ms)","trace[389777144] 'compare' (duration: 47.872717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:51:56.448135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.069168ms","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-07-28T05:51:56.44816Z","caller":"traceutil/trace.go:171","msg":"trace[720235447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"105.16696ms","start":"2026-07-28T05:51:56.342986Z","end":"2026-07-28T05:51:56.448153Z","steps":["trace[720235447] 'agreement among raft nodes before linearized reading' (duration: 105.015136ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:56.505323Z","caller":"traceutil/trace.go:171","msg":"trace[856685748] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"104.857312ms","start":"2026-07-28T05:51:56.400442Z","end":"2026-07-28T05:51:56.505299Z","steps":["trace[856685748] 'process raft request' (duration: 104.740099ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:51:56.646703Z","caller":"traceutil/trace.go:171","msg":"trace[1451224443] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"194.335456ms","start":"2026-07-28T05:51:56.452261Z","end":"2026-07-28T05:51:56.646597Z","steps":["trace[1451224443] 'process raft request' (duration: 144.598189ms)","trace[1451224443] 'compare' (duration: 49.292156ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:00.854521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.492689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T05:52:00.854639Z","caller":"traceutil/trace.go:171","msg":"trace[1575087097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"104.635452ms","start":"2026-07-28T05:52:00.749983Z","end":"2026-07-28T05:52:00.854619Z","steps":["trace[1575087097] 'range keys from in-memory index tree' (duration: 104.131519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:00.854865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.51718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fdf5bd07-e039-48a5-b968-12073d333211\" ","response":"range_response_count:1 size:3884"} {"level":"info","ts":"2026-07-28T05:52:00.854996Z","caller":"traceutil/trace.go:171","msg":"trace[183352334] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fdf5bd07-e039-48a5-b968-12073d333211; range_end:; response_count:1; response_revision:1326; }","duration":"139.685814ms","start":"2026-07-28T05:52:00.71524Z","end":"2026-07-28T05:52:00.854926Z","steps":["trace[183352334] 'range keys from in-memory index tree' (duration: 139.275034ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:01.074422Z","caller":"traceutil/trace.go:171","msg":"trace[1379497458] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"217.102994ms","start":"2026-07-28T05:52:00.85729Z","end":"2026-07-28T05:52:01.074393Z","steps":["trace[1379497458] 'process raft request' (duration: 216.880717ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:01.111044Z","caller":"traceutil/trace.go:171","msg":"trace[1504078105] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"251.65159ms","start":"2026-07-28T05:52:00.859362Z","end":"2026-07-28T05:52:01.111014Z","steps":["trace[1504078105] 'process raft request' (duration: 251.453914ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:01.262458Z","caller":"traceutil/trace.go:171","msg":"trace[808850306] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"116.347159ms","start":"2026-07-28T05:52:01.146082Z","end":"2026-07-28T05:52:01.262429Z","steps":["trace[808850306] 'process raft request' (duration: 116.199955ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:01.400582Z","caller":"traceutil/trace.go:171","msg":"trace[65906268] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"112.714144ms","start":"2026-07-28T05:52:01.287833Z","end":"2026-07-28T05:52:01.400547Z","steps":["trace[65906268] 'process raft request' (duration: 112.537848ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:04.069187Z","caller":"traceutil/trace.go:171","msg":"trace[92231618] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"367.438015ms","start":"2026-07-28T05:52:03.701707Z","end":"2026-07-28T05:52:04.069145Z","steps":["trace[92231618] 'process raft request' (duration: 367.124947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:04.069434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:03.701666Z","time spent":"367.638551ms","remote":"127.0.0.1:54462","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-07-28T05:52:04.310146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.665134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-28T05:52:04.310241Z","caller":"traceutil/trace.go:171","msg":"trace[533917373] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:1348; }","duration":"106.799478ms","start":"2026-07-28T05:52:04.20342Z","end":"2026-07-28T05:52:04.31022Z","steps":["trace[533917373] 'range keys from in-memory index tree' (duration: 106.432948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:04.310376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.162069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-28T05:52:04.310516Z","caller":"traceutil/trace.go:171","msg":"trace[906096310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"119.374665ms","start":"2026-07-28T05:52:04.19111Z","end":"2026-07-28T05:52:04.310484Z","steps":["trace[906096310] 'range keys from in-memory index tree' (duration: 118.911583ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:04.823205Z","caller":"traceutil/trace.go:171","msg":"trace[1792451870] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"437.564189ms","start":"2026-07-28T05:52:04.385618Z","end":"2026-07-28T05:52:04.823182Z","steps":["trace[1792451870] 'read index received' (duration: 175.571032ms)","trace[1792451870] 'applied index is now lower than readState.Index' (duration: 261.992297ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:04.823392Z","caller":"traceutil/trace.go:171","msg":"trace[1906594860] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"261.118014ms","start":"2026-07-28T05:52:04.56226Z","end":"2026-07-28T05:52:04.823378Z","steps":["trace[1906594860] 'process raft request' (duration: 260.873288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:04.823497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.904457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T05:52:04.823531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.994803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T05:52:04.823571Z","caller":"traceutil/trace.go:171","msg":"trace[1555739425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"223.028901ms","start":"2026-07-28T05:52:04.600522Z","end":"2026-07-28T05:52:04.823551Z","steps":["trace[1555739425] 'agreement among raft nodes before linearized reading' (duration: 222.911458ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:04.823599Z","caller":"traceutil/trace.go:171","msg":"trace[1601474470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"176.062744ms","start":"2026-07-28T05:52:04.647522Z","end":"2026-07-28T05:52:04.823584Z","steps":["trace[1601474470] 'agreement among raft nodes before linearized reading' (duration: 175.88269ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:04.823239Z","caller":"traceutil/trace.go:171","msg":"trace[1111148191] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"508.022959ms","start":"2026-07-28T05:52:04.315179Z","end":"2026-07-28T05:52:04.823202Z","steps":["trace[1111148191] 'process raft request' (duration: 440.954838ms)","trace[1111148191] 'compare' (duration: 66.800874ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:04.824164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:04.315158Z","time spent":"508.917573ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:52:04.824261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.282258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T05:52:04.824349Z","caller":"traceutil/trace.go:171","msg":"trace[586789122] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1350; }","duration":"405.439601ms","start":"2026-07-28T05:52:04.418888Z","end":"2026-07-28T05:52:04.824328Z","steps":["trace[586789122] 'agreement among raft nodes before linearized reading' (duration: 404.444755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:04.824401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:04.41887Z","time spent":"405.520394ms","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-28T05:52:04.824164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.549307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T05:52:04.824791Z","caller":"traceutil/trace.go:171","msg":"trace[709499368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"439.082059ms","start":"2026-07-28T05:52:04.385562Z","end":"2026-07-28T05:52:04.824644Z","steps":["trace[709499368] 'agreement among raft nodes before linearized reading' (duration: 437.737324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:04.82488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:04.385544Z","time spent":"439.319356ms","remote":"127.0.0.1:54462","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-07-28T05:52:05.068518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.603558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:52:05.068899Z","caller":"traceutil/trace.go:171","msg":"trace[442773665] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1408; }","duration":"238.337812ms","start":"2026-07-28T05:52:04.830541Z","end":"2026-07-28T05:52:05.068879Z","steps":["trace[442773665] 'read index received' (duration: 114.261002ms)","trace[442773665] 'applied index is now lower than readState.Index' (duration: 124.07567ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:05.068937Z","caller":"traceutil/trace.go:171","msg":"trace[849095301] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"238.978039ms","start":"2026-07-28T05:52:04.829932Z","end":"2026-07-28T05:52:05.06891Z","steps":["trace[849095301] 'process raft request' (duration: 238.764833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:05.069055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.503477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-28T05:52:05.069124Z","caller":"traceutil/trace.go:171","msg":"trace[2138634329] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1353; }","duration":"238.582019ms","start":"2026-07-28T05:52:04.830527Z","end":"2026-07-28T05:52:05.069109Z","steps":["trace[2138634329] 'agreement among raft nodes before linearized reading' (duration: 238.426295ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:05.069248Z","caller":"traceutil/trace.go:171","msg":"trace[797072067] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"239.241886ms","start":"2026-07-28T05:52:04.829991Z","end":"2026-07-28T05:52:05.069233Z","steps":["trace[797072067] 'process raft request' (duration: 238.835425ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:05.068963Z","caller":"traceutil/trace.go:171","msg":"trace[751258231] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"239.77391ms","start":"2026-07-28T05:52:04.829086Z","end":"2026-07-28T05:52:05.06886Z","steps":["trace[751258231] 'process raft request' (duration: 115.731801ms)","trace[751258231] 'compare' (duration: 123.447384ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:07.200646Z","caller":"traceutil/trace.go:171","msg":"trace[384443985] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"122.266626ms","start":"2026-07-28T05:52:07.078348Z","end":"2026-07-28T05:52:07.200615Z","steps":["trace[384443985] 'process raft request' (duration: 91.540415ms)","trace[384443985] 'compare' (duration: 30.546457ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:07.200813Z","caller":"traceutil/trace.go:171","msg":"trace[822691364] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"122.366158ms","start":"2026-07-28T05:52:07.078417Z","end":"2026-07-28T05:52:07.200783Z","steps":["trace[822691364] 'process raft request' (duration: 122.156273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:07.973173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.478211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:52:07.973271Z","caller":"traceutil/trace.go:171","msg":"trace[73394902] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"393.833196ms","start":"2026-07-28T05:52:07.579421Z","end":"2026-07-28T05:52:07.973254Z","steps":["trace[73394902] 'read index received' (duration: 54.791µs)","trace[73394902] 'applied index is now lower than readState.Index' (duration: 393.776805ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:07.973533Z","caller":"traceutil/trace.go:171","msg":"trace[1117784362] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"737.198994ms","start":"2026-07-28T05:52:07.236313Z","end":"2026-07-28T05:52:07.973512Z","steps":["trace[1117784362] 'process raft request' (duration: 332.305153ms)","trace[1117784362] 'compare' (duration: 404.279905ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:07.97363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:07.236301Z","time spent":"737.284117ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T05:52:07.973683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.74443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:52:07.973756Z","caller":"traceutil/trace.go:171","msg":"trace[185037212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"373.871763ms","start":"2026-07-28T05:52:07.599863Z","end":"2026-07-28T05:52:07.973735Z","steps":["trace[185037212] 'agreement among raft nodes before linearized reading' (duration: 373.73448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:07.9738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:07.599843Z","time spent":"373.949235ms","remote":"127.0.0.1:54178","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-07-28T05:52:07.974083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.125192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T05:52:07.97417Z","caller":"traceutil/trace.go:171","msg":"trace[325273234] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1361; }","duration":"352.521393ms","start":"2026-07-28T05:52:07.621631Z","end":"2026-07-28T05:52:07.974152Z","steps":["trace[325273234] 'agreement among raft nodes before linearized reading' (duration: 352.106162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:07.973908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.480943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T05:52:07.974586Z","caller":"traceutil/trace.go:171","msg":"trace[289140499] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1361; }","duration":"395.194012ms","start":"2026-07-28T05:52:07.579367Z","end":"2026-07-28T05:52:07.974561Z","steps":["trace[289140499] 'agreement among raft nodes before linearized reading' (duration: 394.302919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:07.974635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:07.579344Z","time spent":"395.270753ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-28T05:52:07.975026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:07.621619Z","time spent":"352.583105ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-07-28T05:52:08.371906Z","caller":"traceutil/trace.go:171","msg":"trace[833098570] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"282.956901ms","start":"2026-07-28T05:52:08.088918Z","end":"2026-07-28T05:52:08.371875Z","steps":["trace[833098570] 'read index received' (duration: 270.494132ms)","trace[833098570] 'applied index is now lower than readState.Index' (duration: 12.461329ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:08.372033Z","caller":"traceutil/trace.go:171","msg":"trace[1684191655] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"288.365238ms","start":"2026-07-28T05:52:08.083598Z","end":"2026-07-28T05:52:08.371963Z","steps":["trace[1684191655] 'process raft request' (duration: 275.904459ms)","trace[1684191655] 'compare' (duration: 12.044568ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:08.372204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.268779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:52:08.372401Z","caller":"traceutil/trace.go:171","msg":"trace[561291103] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1362; }","duration":"283.491114ms","start":"2026-07-28T05:52:08.088884Z","end":"2026-07-28T05:52:08.372375Z","steps":["trace[561291103] 'agreement among raft nodes before linearized reading' (duration: 283.261898ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:09.108771Z","caller":"traceutil/trace.go:171","msg":"trace[1798916449] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"205.291158ms","start":"2026-07-28T05:52:08.903458Z","end":"2026-07-28T05:52:09.108749Z","steps":["trace[1798916449] 'process raft request' (duration: 205.130374ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:10.075425Z","caller":"traceutil/trace.go:171","msg":"trace[1066210954] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"103.607964ms","start":"2026-07-28T05:52:09.971793Z","end":"2026-07-28T05:52:10.075401Z","steps":["trace[1066210954] 'process raft request' (duration: 103.393819ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:12.657748Z","caller":"traceutil/trace.go:171","msg":"trace[495302461] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"155.345689ms","start":"2026-07-28T05:52:12.502376Z","end":"2026-07-28T05:52:12.657721Z","steps":["trace[495302461] 'read index received' (duration: 155.207504ms)","trace[495302461] 'applied index is now lower than readState.Index' (duration: 136.585µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:12.657889Z","caller":"traceutil/trace.go:171","msg":"trace[467928130] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"215.083671ms","start":"2026-07-28T05:52:12.44276Z","end":"2026-07-28T05:52:12.657844Z","steps":["trace[467928130] 'process raft request' (duration: 214.775262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:12.657923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.528923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T05:52:12.657985Z","caller":"traceutil/trace.go:171","msg":"trace[1398155809] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1375; }","duration":"155.626945ms","start":"2026-07-28T05:52:12.502335Z","end":"2026-07-28T05:52:12.657962Z","steps":["trace[1398155809] 'agreement among raft nodes before linearized reading' (duration: 155.522123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:12.71063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.92524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:52:12.710776Z","caller":"traceutil/trace.go:171","msg":"trace[1234632083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"111.108795ms","start":"2026-07-28T05:52:12.599649Z","end":"2026-07-28T05:52:12.710758Z","steps":["trace[1234632083] 'agreement among raft nodes before linearized reading' (duration: 110.91327ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:12.710679Z","caller":"traceutil/trace.go:171","msg":"trace[1361201204] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"156.23104ms","start":"2026-07-28T05:52:12.554428Z","end":"2026-07-28T05:52:12.710659Z","steps":["trace[1361201204] 'process raft request' (duration: 156.014644ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:13.318296Z","caller":"traceutil/trace.go:171","msg":"trace[687687202] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"116.156129ms","start":"2026-07-28T05:52:13.202107Z","end":"2026-07-28T05:52:13.318263Z","steps":["trace[687687202] 'process raft request' (duration: 79.777958ms)","trace[687687202] 'compare' (duration: 36.251138ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:13.520842Z","caller":"traceutil/trace.go:171","msg":"trace[1270906725] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"148.249663ms","start":"2026-07-28T05:52:13.372557Z","end":"2026-07-28T05:52:13.520806Z","steps":["trace[1270906725] 'process raft request' (duration: 111.284998ms)","trace[1270906725] 'compare' (duration: 36.706649ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:14.38464Z","caller":"traceutil/trace.go:171","msg":"trace[624785078] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1439; }","duration":"180.194383ms","start":"2026-07-28T05:52:14.204362Z","end":"2026-07-28T05:52:14.384557Z","steps":["trace[624785078] 'read index received' (duration: 180.028679ms)","trace[624785078] 'applied index is now lower than readState.Index' (duration: 164.574µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:14.384696Z","caller":"traceutil/trace.go:171","msg":"trace[595640741] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"212.035436ms","start":"2026-07-28T05:52:14.172629Z","end":"2026-07-28T05:52:14.384665Z","steps":["trace[595640741] 'process raft request' (duration: 211.78171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:14.384839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.46293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-28T05:52:14.384919Z","caller":"traceutil/trace.go:171","msg":"trace[654838518] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:1381; }","duration":"180.581572ms","start":"2026-07-28T05:52:14.204326Z","end":"2026-07-28T05:52:14.384908Z","steps":["trace[654838518] 'agreement among raft nodes before linearized reading' (duration: 180.388107ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:14.583442Z","caller":"traceutil/trace.go:171","msg":"trace[238283131] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"186.328235ms","start":"2026-07-28T05:52:14.39708Z","end":"2026-07-28T05:52:14.583408Z","steps":["trace[238283131] 'process raft request' (duration: 150.567733ms)","trace[238283131] 'compare' (duration: 35.574628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:15.009601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.417034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:52:15.009732Z","caller":"traceutil/trace.go:171","msg":"trace[729562608] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"346.895777ms","start":"2026-07-28T05:52:14.662807Z","end":"2026-07-28T05:52:15.009703Z","steps":["trace[729562608] 'process raft request' (duration: 118.283139ms)","trace[729562608] 'compare' (duration: 228.057275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:15.009813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:14.66278Z","time spent":"346.999559ms","remote":"127.0.0.1:54462","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-07-28T05:52:15.450267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.8114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-28T05:52:15.450378Z","caller":"traceutil/trace.go:171","msg":"trace[258463322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1383; }","duration":"247.993465ms","start":"2026-07-28T05:52:15.20236Z","end":"2026-07-28T05:52:15.450354Z","steps":["trace[258463322] 'range keys from in-memory index tree' (duration: 247.537174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:15.451025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.505375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T05:52:15.451092Z","caller":"traceutil/trace.go:171","msg":"trace[867765302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1383; }","duration":"127.757092ms","start":"2026-07-28T05:52:15.323319Z","end":"2026-07-28T05:52:15.451076Z","steps":["trace[867765302] 'range keys from in-memory index tree' (duration: 126.606443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:15.761352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.122262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T05:52:15.761594Z","caller":"traceutil/trace.go:171","msg":"trace[1862134465] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1444; }","duration":"236.871489ms","start":"2026-07-28T05:52:15.524703Z","end":"2026-07-28T05:52:15.761575Z","steps":["trace[1862134465] 'read index received' (duration: 89.488812ms)","trace[1862134465] 'applied index is now lower than readState.Index' (duration: 147.381617ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:52:15.761739Z","caller":"traceutil/trace.go:171","msg":"trace[1924922262] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"305.889862ms","start":"2026-07-28T05:52:15.455791Z","end":"2026-07-28T05:52:15.761681Z","steps":["trace[1924922262] 'process raft request' (duration: 305.682317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:15.761804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.102456ms","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-07-28T05:52:15.761842Z","caller":"traceutil/trace.go:171","msg":"trace[1990371618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"237.172968ms","start":"2026-07-28T05:52:15.524659Z","end":"2026-07-28T05:52:15.761832Z","steps":["trace[1990371618] 'agreement among raft nodes before linearized reading' (duration: 237.035123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:15.761879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.836707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:52:15.761946Z","caller":"traceutil/trace.go:171","msg":"trace[1109783336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"161.93004ms","start":"2026-07-28T05:52:15.599992Z","end":"2026-07-28T05:52:15.761922Z","steps":["trace[1109783336] 'agreement among raft nodes before linearized reading' (duration: 161.839078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:15.761945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:15.455757Z","time spent":"306.082698ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:52:15.761744Z","caller":"traceutil/trace.go:171","msg":"trace[828223049] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"306.724933ms","start":"2026-07-28T05:52:15.454981Z","end":"2026-07-28T05:52:15.761706Z","steps":["trace[828223049] 'process raft request' (duration: 159.15412ms)","trace[828223049] 'compare' (duration: 146.974548ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:15.762211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.811166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-07-28T05:52:15.762274Z","caller":"traceutil/trace.go:171","msg":"trace[486791568] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1386; }","duration":"188.877788ms","start":"2026-07-28T05:52:15.573382Z","end":"2026-07-28T05:52:15.76226Z","steps":["trace[486791568] 'agreement among raft nodes before linearized reading' (duration: 188.391085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T05:52:15.762338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:15.454961Z","time spent":"307.201126ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T05:52:16.09566Z","caller":"traceutil/trace.go:171","msg":"trace[1786200245] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"232.446348ms","start":"2026-07-28T05:52:15.863166Z","end":"2026-07-28T05:52:16.095613Z","steps":["trace[1786200245] 'read index received' (duration: 181.761376ms)","trace[1786200245] 'applied index is now lower than readState.Index' (duration: 50.683462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:16.0961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.88021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T05:52:16.09616Z","caller":"traceutil/trace.go:171","msg":"trace[1305083304] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1387; }","duration":"233.040323ms","start":"2026-07-28T05:52:15.863103Z","end":"2026-07-28T05:52:16.096143Z","steps":["trace[1305083304] 'agreement among raft nodes before linearized reading' (duration: 232.90081ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:16.096188Z","caller":"traceutil/trace.go:171","msg":"trace[229672528] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"329.820125ms","start":"2026-07-28T05:52:15.766334Z","end":"2026-07-28T05:52:16.096154Z","steps":["trace[229672528] 'process raft request' (duration: 278.558009ms)","trace[229672528] 'compare' (duration: 50.58377ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T05:52:16.096388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T05:52:15.766317Z","time spent":"329.949588ms","remote":"127.0.0.1:54462","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-07-28T05:52:16.154349Z","caller":"traceutil/trace.go:171","msg":"trace[2110292197] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"104.146304ms","start":"2026-07-28T05:52:16.05014Z","end":"2026-07-28T05:52:16.154286Z","steps":["trace[2110292197] 'process raft request' (duration: 103.96232ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:18.225346Z","caller":"traceutil/trace.go:171","msg":"trace[22301965] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"120.221704ms","start":"2026-07-28T05:52:18.1051Z","end":"2026-07-28T05:52:18.225322Z","steps":["trace[22301965] 'process raft request' (duration: 120.095331ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:22.368081Z","caller":"traceutil/trace.go:171","msg":"trace[1527368855] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"122.136448ms","start":"2026-07-28T05:52:22.24592Z","end":"2026-07-28T05:52:22.368056Z","steps":["trace[1527368855] 'process raft request' (duration: 121.977104ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T05:52:26.026284Z","caller":"traceutil/trace.go:171","msg":"trace[359401377] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"153.339739ms","start":"2026-07-28T05:52:25.872924Z","end":"2026-07-28T05:52:26.026264Z","steps":["trace[359401377] 'process raft request' (duration: 87.624411ms)","trace[359401377] 'compare' (duration: 65.625775ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T05:53:14.179806Z","caller":"traceutil/trace.go:171","msg":"trace[1618637739] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"105.947702ms","start":"2026-07-28T05:53:14.073825Z","end":"2026-07-28T05:53:14.179773Z","steps":["trace[1618637739] 'process raft request' (duration: 105.711567ms)"],"step_count":1}