{"level":"warn","ts":"2026-05-12T05:45:05.641228Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T05:45:05.64134Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T05:45:05.64142Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T05:45:05.641431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-12T05:45:05.641454Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T05:45:05.642287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-05-12T05:45:05.642393Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T05:45:05.648668Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.031247ms"} {"level":"info","ts":"2026-05-12T05:45:05.657899Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-12T05:45:05.657988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T05:45:05.658015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-12T05:45:05.65803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T05:45:05.658044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-12T05:45:05.658084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-12T05:45:05.664236Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T05:45:05.666234Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T05:45:05.669087Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T05:45:05.674108Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T05:45:05.675709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T05:45:05.675902Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T05:45:05.675974Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T05:45:05.675988Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T05:45:05.676729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-12T05:45:05.67739Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-12T05:45:05.68424Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T05:45:05.684291Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-12T05:45:05.684341Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-12T05:45:05.68453Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T05:45:05.684577Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T05:45:06.56182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T05:45:06.561862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T05:45:06.561881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-12T05:45:06.561892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-12T05:45:06.561897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-12T05:45:06.561905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-12T05:45:06.561911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-12T05:45:06.56727Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T05:45:06.567285Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:45:06.567409Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:45:06.567499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:45:06.568374Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T05:45:06.568398Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T05:45:06.570636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T05:45:06.571438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-05-12T05:45:06.577159Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:45:06.577219Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:45:06.577273Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:45:30.688337Z","caller":"traceutil/trace.go:171","msg":"trace[993475148] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"250.397015ms","start":"2026-05-12T05:45:30.437919Z","end":"2026-05-12T05:45:30.688316Z","steps":["trace[993475148] 'process raft request' (duration: 250.290042ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:34.819862Z","caller":"traceutil/trace.go:171","msg":"trace[543615405] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"114.488261ms","start":"2026-05-12T05:45:34.705344Z","end":"2026-05-12T05:45:34.819832Z","steps":["trace[543615405] 'process raft request' (duration: 114.377159ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.720752Z","caller":"traceutil/trace.go:171","msg":"trace[518882083] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"570.633773ms","start":"2026-05-12T05:45:35.150084Z","end":"2026-05-12T05:45:35.720717Z","steps":["trace[518882083] 'process raft request' (duration: 570.501139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.721673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.150065Z","time spent":"570.755955ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.731829Z","caller":"traceutil/trace.go:171","msg":"trace[283853385] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"572.675693ms","start":"2026-05-12T05:45:35.159111Z","end":"2026-05-12T05:45:35.731787Z","steps":["trace[283853385] 'process raft request' (duration: 572.533319ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.731859Z","caller":"traceutil/trace.go:171","msg":"trace[1894724272] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"568.211024ms","start":"2026-05-12T05:45:35.16361Z","end":"2026-05-12T05:45:35.731821Z","steps":["trace[1894724272] 'process raft request' (duration: 568.106151ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.731907Z","caller":"traceutil/trace.go:171","msg":"trace[1423650] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"579.96307ms","start":"2026-05-12T05:45:35.151925Z","end":"2026-05-12T05:45:35.731888Z","steps":["trace[1423650] 'process raft request' (duration: 579.619622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.731927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.159104Z","time spent":"572.773715ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.731958Z","caller":"traceutil/trace.go:171","msg":"trace[1185730177] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:484; }","duration":"477.43534ms","start":"2026-05-12T05:45:35.254498Z","end":"2026-05-12T05:45:35.731933Z","steps":["trace[1185730177] 'read index received' (duration: 466.243517ms)","trace[1185730177] 'applied index is now lower than readState.Index' (duration: 11.191153ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:35.731988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.151906Z","time spent":"580.040832ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.732068Z","caller":"traceutil/trace.go:171","msg":"trace[633931283] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"577.54251ms","start":"2026-05-12T05:45:35.154516Z","end":"2026-05-12T05:45:35.732058Z","steps":["trace[633931283] 'process raft request' (duration: 577.10768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.73205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.539864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-12T05:45:35.732054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.163589Z","time spent":"568.329516ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.732091Z","caller":"traceutil/trace.go:171","msg":"trace[2130576839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"477.644556ms","start":"2026-05-12T05:45:35.254442Z","end":"2026-05-12T05:45:35.732086Z","steps":["trace[2130576839] 'agreement among raft nodes before linearized reading' (duration: 477.547124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.7321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.15451Z","time spent":"577.576541ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:45:35.732111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.254418Z","time spent":"477.686596ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T05:45:35.732147Z","caller":"traceutil/trace.go:171","msg":"trace[1823000519] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"559.293826ms","start":"2026-05-12T05:45:35.172835Z","end":"2026-05-12T05:45:35.732129Z","steps":["trace[1823000519] 'process raft request' (duration: 558.979669ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.732188Z","caller":"traceutil/trace.go:171","msg":"trace[2086552548] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"568.220324ms","start":"2026-05-12T05:45:35.16396Z","end":"2026-05-12T05:45:35.73218Z","steps":["trace[2086552548] 'process raft request' (duration: 567.781194ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.732212Z","caller":"traceutil/trace.go:171","msg":"trace[1882431101] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"540.030967ms","start":"2026-05-12T05:45:35.192173Z","end":"2026-05-12T05:45:35.732204Z","steps":["trace[1882431101] 'process raft request' (duration: 539.681138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.732223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.163939Z","time spent":"568.266465ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:45:35.732246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.192157Z","time spent":"540.070907ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.732249Z","caller":"traceutil/trace.go:171","msg":"trace[629811699] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"566.627445ms","start":"2026-05-12T05:45:35.165614Z","end":"2026-05-12T05:45:35.732241Z","steps":["trace[629811699] 'process raft request' (duration: 566.156153ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.73225Z","caller":"traceutil/trace.go:171","msg":"trace[1733461948] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"416.420363ms","start":"2026-05-12T05:45:35.31582Z","end":"2026-05-12T05:45:35.732241Z","steps":["trace[1733461948] 'process raft request' (duration: 416.078285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.73222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.172825Z","time spent":"559.352698ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:45:35.732281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.165603Z","time spent":"566.665945ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:45:35.732292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.315788Z","time spent":"416.485785ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.731876Z","caller":"traceutil/trace.go:171","msg":"trace[645443055] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"570.729936ms","start":"2026-05-12T05:45:35.161126Z","end":"2026-05-12T05:45:35.731856Z","steps":["trace[645443055] 'process raft request' (duration: 570.540901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.732425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.16112Z","time spent":"571.233327ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:35.732395Z","caller":"traceutil/trace.go:171","msg":"trace[428163738] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"571.087244ms","start":"2026-05-12T05:45:35.161293Z","end":"2026-05-12T05:45:35.73238Z","steps":["trace[428163738] 'process raft request' (duration: 570.396858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.732712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.161287Z","time spent":"571.249918ms","remote":"127.0.0.1:40504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:38.069414Z","caller":"traceutil/trace.go:171","msg":"trace[551352507] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"295.410092ms","start":"2026-05-12T05:45:37.773973Z","end":"2026-05-12T05:45:38.069384Z","steps":["trace[551352507] 'read index received' (duration: 295.162346ms)","trace[551352507] 'applied index is now lower than readState.Index' (duration: 246.736µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:38.069589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.593946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:45:38.069635Z","caller":"traceutil/trace.go:171","msg":"trace[2025162010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"295.671808ms","start":"2026-05-12T05:45:37.773947Z","end":"2026-05-12T05:45:38.069619Z","steps":["trace[2025162010] 'agreement among raft nodes before linearized reading' (duration: 295.594466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:38.069645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.391606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:45:38.069712Z","caller":"traceutil/trace.go:171","msg":"trace[1573040624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"150.506009ms","start":"2026-05-12T05:45:37.919188Z","end":"2026-05-12T05:45:38.069694Z","steps":["trace[1573040624] 'agreement among raft nodes before linearized reading' (duration: 150.376515ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:38.069577Z","caller":"traceutil/trace.go:171","msg":"trace[314661365] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"319.314635ms","start":"2026-05-12T05:45:37.75019Z","end":"2026-05-12T05:45:38.069504Z","steps":["trace[314661365] 'process raft request' (duration: 319.040848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:38.070461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:37.750173Z","time spent":"319.981933ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:45:40.997176Z","caller":"traceutil/trace.go:171","msg":"trace[258803361] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"107.621993ms","start":"2026-05-12T05:45:40.889511Z","end":"2026-05-12T05:45:40.997133Z","steps":["trace[258803361] 'process raft request' (duration: 107.449819ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:42.166651Z","caller":"traceutil/trace.go:171","msg":"trace[1720795672] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"130.256266ms","start":"2026-05-12T05:45:42.036348Z","end":"2026-05-12T05:45:42.166604Z","steps":["trace[1720795672] 'process raft request' (duration: 129.675112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:42.693888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.621016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:45:42.694043Z","caller":"traceutil/trace.go:171","msg":"trace[1067079836] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"522.622492ms","start":"2026-05-12T05:45:42.171374Z","end":"2026-05-12T05:45:42.693997Z","steps":["trace[1067079836] 'process raft request' (duration: 14.568125ms)","trace[1067079836] 'compare' (duration: 507.452222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:42.694128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:42.171349Z","time spent":"522.728116ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:45:42.708246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651950808,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T05:45:42.711431Z","caller":"traceutil/trace.go:171","msg":"trace[641104102] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"539.947554ms","start":"2026-05-12T05:45:42.171468Z","end":"2026-05-12T05:45:42.711416Z","steps":["trace[641104102] 'process raft request' (duration: 539.703138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:42.711509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:42.171448Z","time spent":"540.016525ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:45:42.711821Z","caller":"traceutil/trace.go:171","msg":"trace[763059269] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"540.277312ms","start":"2026-05-12T05:45:42.171528Z","end":"2026-05-12T05:45:42.711806Z","steps":["trace[763059269] 'process raft request' (duration: 539.751209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:42.712206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:42.171505Z","time spent":"540.390735ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:45:42.747092Z","caller":"traceutil/trace.go:171","msg":"trace[1449132700] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"539.492274ms","start":"2026-05-12T05:45:42.207575Z","end":"2026-05-12T05:45:42.747067Z","steps":["trace[1449132700] 'read index received' (duration: 503.503516ms)","trace[1449132700] 'applied index is now lower than readState.Index' (duration: 35.987628ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:42.747125Z","caller":"traceutil/trace.go:171","msg":"trace[787135384] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"507.981045ms","start":"2026-05-12T05:45:42.239125Z","end":"2026-05-12T05:45:42.747106Z","steps":["trace[787135384] 'process raft request' (duration: 507.821971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:42.74723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:42.239098Z","time spent":"508.095868ms","remote":"127.0.0.1:40742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:45:42.747454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.667408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-42ntn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-12T05:45:42.747507Z","caller":"traceutil/trace.go:171","msg":"trace[2006303966] range","detail":"{range_begin:/registry/pods/kube-system/cilium-42ntn; range_end:; response_count:1; response_revision:580; }","duration":"539.949745ms","start":"2026-05-12T05:45:42.207543Z","end":"2026-05-12T05:45:42.747493Z","steps":["trace[2006303966] 'agreement among raft nodes before linearized reading' (duration: 539.618587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:42.747555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:42.207527Z","time spent":"540.013266ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-42ntn\" "} {"level":"info","ts":"2026-05-12T05:45:42.998543Z","caller":"traceutil/trace.go:171","msg":"trace[1595784209] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"236.620169ms","start":"2026-05-12T05:45:42.761871Z","end":"2026-05-12T05:45:42.998491Z","steps":["trace[1595784209] 'process raft request' (duration: 236.299341ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:42.998719Z","caller":"traceutil/trace.go:171","msg":"trace[63456047] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"237.397888ms","start":"2026-05-12T05:45:42.761301Z","end":"2026-05-12T05:45:42.998699Z","steps":["trace[63456047] 'process raft request' (duration: 221.836638ms)","trace[63456047] 'compare' (duration: 14.910564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:44.422057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651950821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:45:44.454195Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449812307s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T05:45:44.454777Z","caller":"traceutil/trace.go:171","msg":"trace[1252994863] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.450430673s","start":"2026-05-12T05:45:43.00432Z","end":"2026-05-12T05:45:44.45475Z","steps":["trace[1252994863] 'process raft request' (duration: 1.450281779s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:44.45492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:43.004309Z","time spent":"1.450539585s","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:44.621692Z","caller":"traceutil/trace.go:171","msg":"trace[1819640340] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.617157537s","start":"2026-05-12T05:45:43.004509Z","end":"2026-05-12T05:45:44.621666Z","steps":["trace[1819640340] 'process raft request' (duration: 1.612124334s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:44.621945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:43.004503Z","time spent":"1.617351042s","remote":"127.0.0.1:40742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:45:44.622056Z","caller":"traceutil/trace.go:171","msg":"trace[1169587267] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.616097791s","start":"2026-05-12T05:45:43.005924Z","end":"2026-05-12T05:45:44.622022Z","steps":["trace[1169587267] 'process raft request' (duration: 1.615942127s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:44.622133Z","caller":"traceutil/trace.go:171","msg":"trace[999568186] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:604; }","duration":"700.824856ms","start":"2026-05-12T05:45:43.921291Z","end":"2026-05-12T05:45:44.622116Z","steps":["trace[999568186] 'read index received' (duration: 533.355544ms)","trace[999568186] 'applied index is now lower than readState.Index' (duration: 167.468262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:44.622212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.914098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:45:44.622214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:43.005917Z","time spent":"1.616218305s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:45:44.622248Z","caller":"traceutil/trace.go:171","msg":"trace[1875329427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"700.99501ms","start":"2026-05-12T05:45:43.921243Z","end":"2026-05-12T05:45:44.622238Z","steps":["trace[1875329427] 'agreement among raft nodes before linearized reading' (duration: 700.933629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:44.622283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:43.921223Z","time spent":"701.053361ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T05:45:44.622256Z","caller":"traceutil/trace.go:171","msg":"trace[1220342780] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.616217324s","start":"2026-05-12T05:45:43.006008Z","end":"2026-05-12T05:45:44.622226Z","steps":["trace[1220342780] 'process raft request' (duration: 1.615959648s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:44.622058Z","caller":"traceutil/trace.go:171","msg":"trace[1171991517] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.61727338s","start":"2026-05-12T05:45:43.004735Z","end":"2026-05-12T05:45:44.622009Z","steps":["trace[1171991517] 'process raft request' (duration: 1.616886021s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:44.622418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:43.006003Z","time spent":"1.616333638s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:45:44.622432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:43.004729Z","time spent":"1.617665699s","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:45:47.824684Z","caller":"traceutil/trace.go:171","msg":"trace[1103234395] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"289.899918ms","start":"2026-05-12T05:45:47.534751Z","end":"2026-05-12T05:45:47.824651Z","steps":["trace[1103234395] 'process raft request' (duration: 289.58778ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:47.829063Z","caller":"traceutil/trace.go:171","msg":"trace[1767703110] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"156.381582ms","start":"2026-05-12T05:45:47.672654Z","end":"2026-05-12T05:45:47.829035Z","steps":["trace[1767703110] 'process raft request' (duration: 156.167647ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:01.757751Z","caller":"traceutil/trace.go:171","msg":"trace[549995625] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"437.604419ms","start":"2026-05-12T05:46:01.320104Z","end":"2026-05-12T05:46:01.757708Z","steps":["trace[549995625] 'read index received' (duration: 437.202679ms)","trace[549995625] 'applied index is now lower than readState.Index' (duration: 399.96µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:01.758063Z","caller":"traceutil/trace.go:171","msg":"trace[1076198056] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"622.59369ms","start":"2026-05-12T05:46:01.135413Z","end":"2026-05-12T05:46:01.758007Z","steps":["trace[1076198056] 'process raft request' (duration: 622.059827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:01.758271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.128232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h2nzn\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-12T05:46:01.758334Z","caller":"traceutil/trace.go:171","msg":"trace[380662843] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h2nzn; range_end:; response_count:1; response_revision:747; }","duration":"438.226174ms","start":"2026-05-12T05:46:01.320089Z","end":"2026-05-12T05:46:01.758316Z","steps":["trace[380662843] 'agreement among raft nodes before linearized reading' (duration: 437.891836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:01.758334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:01.135381Z","time spent":"622.827965ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:46:01.758387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:01.320029Z","time spent":"438.347747ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h2nzn\" "} {"level":"info","ts":"2026-05-12T05:46:02.230444Z","caller":"traceutil/trace.go:171","msg":"trace[1116000832] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"464.754271ms","start":"2026-05-12T05:46:01.765658Z","end":"2026-05-12T05:46:02.230412Z","steps":["trace[1116000832] 'process raft request' (duration: 464.193558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:02.231075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:01.765624Z","time spent":"464.917425ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4022,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:46:02.231394Z","caller":"traceutil/trace.go:171","msg":"trace[1580622711] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"310.333986ms","start":"2026-05-12T05:46:01.920989Z","end":"2026-05-12T05:46:02.231323Z","steps":["trace[1580622711] 'read index received' (duration: 308.711997ms)","trace[1580622711] 'applied index is now lower than readState.Index' (duration: 1.619529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:02.23148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.48029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:02.231539Z","caller":"traceutil/trace.go:171","msg":"trace[940052057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"310.585193ms","start":"2026-05-12T05:46:01.920931Z","end":"2026-05-12T05:46:02.231516Z","steps":["trace[940052057] 'agreement among raft nodes before linearized reading' (duration: 310.496681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:02.23158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:01.920909Z","time spent":"310.653844ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:46:35.173723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.941741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-05-12T05:46:35.17382Z","caller":"traceutil/trace.go:171","msg":"trace[671384878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:949; }","duration":"171.082724ms","start":"2026-05-12T05:46:35.002718Z","end":"2026-05-12T05:46:35.173801Z","steps":["trace[671384878] 'range keys from in-memory index tree' (duration: 170.841498ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:36.545521Z","caller":"traceutil/trace.go:171","msg":"trace[948039727] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"571.031538ms","start":"2026-05-12T05:46:35.974469Z","end":"2026-05-12T05:46:36.5455Z","steps":["trace[948039727] 'process raft request' (duration: 570.917185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:36.545658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:35.974441Z","time spent":"571.149731ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:46:36.945576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.442594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:46:36.94566Z","caller":"traceutil/trace.go:171","msg":"trace[1375532487] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"226.855718ms","start":"2026-05-12T05:46:36.718791Z","end":"2026-05-12T05:46:36.945647Z","steps":["trace[1375532487] 'read index received' (duration: 64.089265ms)","trace[1375532487] 'applied index is now lower than readState.Index' (duration: 162.765593ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:36.945742Z","caller":"traceutil/trace.go:171","msg":"trace[1721208247] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"315.876749ms","start":"2026-05-12T05:46:36.629856Z","end":"2026-05-12T05:46:36.945733Z","steps":["trace[1721208247] 'process raft request' (duration: 153.090676ms)","trace[1721208247] 'compare' (duration: 162.235519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:36.945841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:36.629822Z","time spent":"315.936391ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:46:36.946063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.245568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:36.946141Z","caller":"traceutil/trace.go:171","msg":"trace[1826072124] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:958; }","duration":"227.370811ms","start":"2026-05-12T05:46:36.718751Z","end":"2026-05-12T05:46:36.946122Z","steps":["trace[1826072124] 'agreement among raft nodes before linearized reading' (duration: 227.243318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:37.69172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651951664,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T05:46:37.980189Z","caller":"traceutil/trace.go:171","msg":"trace[1265092860] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"789.292756ms","start":"2026-05-12T05:46:37.190865Z","end":"2026-05-12T05:46:37.980158Z","steps":["trace[1265092860] 'read index received' (duration: 789.256996ms)","trace[1265092860] 'applied index is now lower than readState.Index' (duration: 34.74µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:37.980435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.546123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T05:46:37.980489Z","caller":"traceutil/trace.go:171","msg":"trace[1741232001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"789.650106ms","start":"2026-05-12T05:46:37.190823Z","end":"2026-05-12T05:46:37.980473Z","steps":["trace[1741232001] 'agreement among raft nodes before linearized reading' (duration: 789.482171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:37.980524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:37.190804Z","time spent":"789.712107ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:46:37.980516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.597465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-12T05:46:37.980595Z","caller":"traceutil/trace.go:171","msg":"trace[220194854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"789.718618ms","start":"2026-05-12T05:46:37.190859Z","end":"2026-05-12T05:46:37.980577Z","steps":["trace[220194854] 'agreement among raft nodes before linearized reading' (duration: 789.497691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:37.98064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:37.190842Z","time spent":"789.789069ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T05:46:37.980528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.562034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:46:37.980733Z","caller":"traceutil/trace.go:171","msg":"trace[1846429511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"789.80998ms","start":"2026-05-12T05:46:37.190905Z","end":"2026-05-12T05:46:37.980715Z","steps":["trace[1846429511] 'agreement among raft nodes before linearized reading' (duration: 789.490851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:37.980777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:37.190885Z","time spent":"789.883222ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T05:46:37.981062Z","caller":"traceutil/trace.go:171","msg":"trace[557574499] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"981.600333ms","start":"2026-05-12T05:46:36.99869Z","end":"2026-05-12T05:46:37.980291Z","steps":["trace[557574499] 'process raft request' (duration: 981.342176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:37.981168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:36.998671Z","time spent":"982.432264ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:46:38.811703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.737023ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:38.811809Z","caller":"traceutil/trace.go:171","msg":"trace[1904165607] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:959; }","duration":"799.850796ms","start":"2026-05-12T05:46:38.011943Z","end":"2026-05-12T05:46:38.811793Z","steps":["trace[1904165607] 'range keys from in-memory index tree' (duration: 799.432295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:38.811997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.58359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:46:38.81213Z","caller":"traceutil/trace.go:171","msg":"trace[1600624865] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"827.654435ms","start":"2026-05-12T05:46:37.984447Z","end":"2026-05-12T05:46:38.812102Z","steps":["trace[1600624865] 'process raft request' (duration: 201.87105ms)","trace[1600624865] 'compare' (duration: 625.17006ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:38.812214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:37.984428Z","time spent":"827.744197ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:46:38.847102Z","caller":"traceutil/trace.go:171","msg":"trace[1640669815] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"862.082084ms","start":"2026-05-12T05:46:37.985009Z","end":"2026-05-12T05:46:38.847091Z","steps":["trace[1640669815] 'process raft request' (duration: 861.963801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:38.847162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:37.985004Z","time spent":"862.131135ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:46:38.847219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.32965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:46:38.847245Z","caller":"traceutil/trace.go:171","msg":"trace[64931879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"296.369431ms","start":"2026-05-12T05:46:38.550869Z","end":"2026-05-12T05:46:38.847239Z","steps":["trace[64931879] 'agreement among raft nodes before linearized reading' (duration: 296.254498ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:38.847094Z","caller":"traceutil/trace.go:171","msg":"trace[1261604264] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"296.185877ms","start":"2026-05-12T05:46:38.550894Z","end":"2026-05-12T05:46:38.84708Z","steps":["trace[1261604264] 'read index received' (duration: 296.038683ms)","trace[1261604264] 'applied index is now lower than readState.Index' (duration: 146.304µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:38.84733Z","caller":"traceutil/trace.go:171","msg":"trace[1442070814] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"862.396402ms","start":"2026-05-12T05:46:37.984909Z","end":"2026-05-12T05:46:38.847305Z","steps":["trace[1442070814] 'process raft request' (duration: 862.147055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:38.847458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:37.984879Z","time spent":"862.507035ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:46:39.398017Z","caller":"traceutil/trace.go:171","msg":"trace[198292455] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"479.855832ms","start":"2026-05-12T05:46:38.918035Z","end":"2026-05-12T05:46:39.397891Z","steps":["trace[198292455] 'read index received' (duration: 429.625381ms)","trace[198292455] 'applied index is now lower than readState.Index' (duration: 50.015925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:39.398416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.367635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:39.398402Z","caller":"traceutil/trace.go:171","msg":"trace[97187261] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"547.817977ms","start":"2026-05-12T05:46:38.85054Z","end":"2026-05-12T05:46:39.398358Z","steps":["trace[97187261] 'process raft request' (duration: 497.075832ms)","trace[97187261] 'compare' (duration: 49.360009ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:39.398466Z","caller":"traceutil/trace.go:171","msg":"trace[1644652260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"480.449078ms","start":"2026-05-12T05:46:38.917994Z","end":"2026-05-12T05:46:39.398443Z","steps":["trace[1644652260] 'agreement among raft nodes before linearized reading' (duration: 480.116109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:39.398501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:38.917975Z","time spent":"480.5176ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:46:39.398552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:38.850525Z","time spent":"547.938179ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:46:39.438359Z","caller":"traceutil/trace.go:171","msg":"trace[1456868818] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"487.128228ms","start":"2026-05-12T05:46:38.951209Z","end":"2026-05-12T05:46:39.438337Z","steps":["trace[1456868818] 'process raft request' (duration: 486.974514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:39.438473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:38.951192Z","time spent":"487.22094ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:46:39.438591Z","caller":"traceutil/trace.go:171","msg":"trace[212710384] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"148.912949ms","start":"2026-05-12T05:46:39.289653Z","end":"2026-05-12T05:46:39.438566Z","steps":["trace[212710384] 'process raft request' (duration: 148.657652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:40.567459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.238279ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-12T05:46:40.567569Z","caller":"traceutil/trace.go:171","msg":"trace[1482708990] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"646.284659ms","start":"2026-05-12T05:46:39.921261Z","end":"2026-05-12T05:46:40.567546Z","steps":["trace[1482708990] 'read index received' (duration: 181.006569ms)","trace[1482708990] 'applied index is now lower than readState.Index' (duration: 465.27599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:40.567583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:39.488643Z","time spent":"1.078931116s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-12T05:46:40.567744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.490013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:40.567796Z","caller":"traceutil/trace.go:171","msg":"trace[788407276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"646.577566ms","start":"2026-05-12T05:46:39.921201Z","end":"2026-05-12T05:46:40.567778Z","steps":["trace[788407276] 'agreement among raft nodes before linearized reading' (duration: 646.491294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:40.567842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:39.921154Z","time spent":"646.676138ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:46:40.568074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.62192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:46:40.568136Z","caller":"traceutil/trace.go:171","msg":"trace[802856498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"583.722172ms","start":"2026-05-12T05:46:39.984398Z","end":"2026-05-12T05:46:40.56812Z","steps":["trace[802856498] 'agreement among raft nodes before linearized reading' (duration: 583.530008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:40.56811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.703626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T05:46:40.568169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:46:39.984383Z","time spent":"583.780423ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T05:46:40.568194Z","caller":"traceutil/trace.go:171","msg":"trace[515374047] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:965; }","duration":"161.800679ms","start":"2026-05-12T05:46:40.406369Z","end":"2026-05-12T05:46:40.56817Z","steps":["trace[515374047] 'agreement among raft nodes before linearized reading' (duration: 161.649685ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:00.88432Z","caller":"traceutil/trace.go:171","msg":"trace[1285764953] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"139.228952ms","start":"2026-05-12T05:47:00.745065Z","end":"2026-05-12T05:47:00.884294Z","steps":["trace[1285764953] 'process raft request' (duration: 139.096808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:01.627688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651951870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:02.128773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651951870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:02.252811Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.199303057s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T05:47:02.253307Z","caller":"traceutil/trace.go:171","msg":"trace[1094161857] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"1.199856801s","start":"2026-05-12T05:47:01.053418Z","end":"2026-05-12T05:47:02.253275Z","steps":["trace[1094161857] 'process raft request' (duration: 1.199578804s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:02.253465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.053403Z","time spent":"1.199972473s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:02.26517Z","caller":"traceutil/trace.go:171","msg":"trace[930275284] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"1.137610894s","start":"2026-05-12T05:47:01.127544Z","end":"2026-05-12T05:47:02.265154Z","steps":["trace[930275284] 'read index received' (duration: 1.125492615s)","trace[930275284] 'applied index is now lower than readState.Index' (duration: 12.117419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:02.265403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134591277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:47:02.265432Z","caller":"traceutil/trace.go:171","msg":"trace[1980748046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"1.134651017s","start":"2026-05-12T05:47:01.130773Z","end":"2026-05-12T05:47:02.265424Z","steps":["trace[1980748046] 'agreement among raft nodes before linearized reading' (duration: 1.134573825s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:02.265428Z","caller":"traceutil/trace.go:171","msg":"trace[819054480] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"624.43361ms","start":"2026-05-12T05:47:01.640958Z","end":"2026-05-12T05:47:02.265391Z","steps":["trace[819054480] 'process raft request' (duration: 624.139562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:02.265511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.197134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:02.265563Z","caller":"traceutil/trace.go:171","msg":"trace[705857077] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1035; }","duration":"546.269257ms","start":"2026-05-12T05:47:01.719285Z","end":"2026-05-12T05:47:02.265554Z","steps":["trace[705857077] 'agreement among raft nodes before linearized reading' (duration: 546.202115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:02.265564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.335655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-12T05:47:02.265592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.71927Z","time spent":"546.313807ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:02.265603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.626836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:02.265639Z","caller":"traceutil/trace.go:171","msg":"trace[767533472] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1035; }","duration":"684.700399ms","start":"2026-05-12T05:47:01.580926Z","end":"2026-05-12T05:47:02.265626Z","steps":["trace[767533472] 'agreement among raft nodes before linearized reading' (duration: 684.636446ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:02.265628Z","caller":"traceutil/trace.go:171","msg":"trace[1757923762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"522.428069ms","start":"2026-05-12T05:47:01.743184Z","end":"2026-05-12T05:47:02.265612Z","steps":["trace[1757923762] 'agreement among raft nodes before linearized reading' (duration: 522.264744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:02.265673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.580907Z","time spent":"684.757ms","remote":"127.0.0.1:48628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:02.26546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.13076Z","time spent":"1.134690159s","remote":"127.0.0.1:40770","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-05-12T05:47:02.265687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.743169Z","time spent":"522.504501ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:02.265483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13786758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-12T05:47:02.265823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.640925Z","time spent":"624.603114ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:02.265833Z","caller":"traceutil/trace.go:171","msg":"trace[143548227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"1.138303511s","start":"2026-05-12T05:47:01.12751Z","end":"2026-05-12T05:47:02.265813Z","steps":["trace[143548227] 'agreement among raft nodes before linearized reading' (duration: 1.137780957s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:02.265972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:01.127496Z","time spent":"1.138456686s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T05:47:02.381159Z","caller":"traceutil/trace.go:171","msg":"trace[530808368] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"110.834388ms","start":"2026-05-12T05:47:02.270288Z","end":"2026-05-12T05:47:02.381123Z","steps":["trace[530808368] 'process raft request' (duration: 54.009777ms)","trace[530808368] 'compare' (duration: 56.538654ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:02.381187Z","caller":"traceutil/trace.go:171","msg":"trace[1020546400] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"110.632923ms","start":"2026-05-12T05:47:02.270527Z","end":"2026-05-12T05:47:02.38116Z","steps":["trace[1020546400] 'process raft request' (duration: 110.51282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:04.758145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651951883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:05.259186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651951883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:05.760215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651951883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:06.247246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.206525094s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T05:47:06.247651Z","caller":"traceutil/trace.go:171","msg":"trace[455521510] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"2.207029847s","start":"2026-05-12T05:47:04.040594Z","end":"2026-05-12T05:47:06.247623Z","steps":["trace[455521510] 'process raft request' (duration: 2.206867543s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.247838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.040571Z","time spent":"2.2071882s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:06.823918Z","caller":"traceutil/trace.go:171","msg":"trace[2127271200] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"2.553524257s","start":"2026-05-12T05:47:04.27036Z","end":"2026-05-12T05:47:06.823884Z","steps":["trace[2127271200] 'process raft request' (duration: 2.515520157s)","trace[2127271200] 'compare' (duration: 37.117036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:06.824105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.270343Z","time spent":"2.553661051s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:06.850485Z","caller":"traceutil/trace.go:171","msg":"trace[1476710155] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1080; }","duration":"2.593051344s","start":"2026-05-12T05:47:04.257398Z","end":"2026-05-12T05:47:06.850449Z","steps":["trace[1476710155] 'read index received' (duration: 1.990293676s)","trace[1476710155] 'applied index is now lower than readState.Index' (duration: 602.755178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:06.850823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.555642289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-12T05:47:06.850878Z","caller":"traceutil/trace.go:171","msg":"trace[1974133876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1041; }","duration":"2.555735552s","start":"2026-05-12T05:47:04.29513Z","end":"2026-05-12T05:47:06.850865Z","steps":["trace[1974133876] 'agreement among raft nodes before linearized reading' (duration: 2.555567008s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.850924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.295117Z","time spent":"2.555794383s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T05:47:06.850916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.593504905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T05:47:06.850976Z","caller":"traceutil/trace.go:171","msg":"trace[1780997847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"2.593607289s","start":"2026-05-12T05:47:04.257353Z","end":"2026-05-12T05:47:06.85096Z","steps":["trace[1780997847] 'agreement among raft nodes before linearized reading' (duration: 2.593201348s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.851162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.381741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:06.851186Z","caller":"traceutil/trace.go:171","msg":"trace[1559276986] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1041; }","duration":"706.438963ms","start":"2026-05-12T05:47:06.144741Z","end":"2026-05-12T05:47:06.85118Z","steps":["trace[1559276986] 'agreement among raft nodes before linearized reading' (duration: 706.399522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.851207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.144725Z","time spent":"706.477254ms","remote":"127.0.0.1:40618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:06.851184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.257335Z","time spent":"2.593734472s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:06.851316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.465127111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:47:06.851734Z","caller":"traceutil/trace.go:171","msg":"trace[814102790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1041; }","duration":"2.465579614s","start":"2026-05-12T05:47:04.38614Z","end":"2026-05-12T05:47:06.851719Z","steps":["trace[814102790] 'agreement among raft nodes before linearized reading' (duration: 2.46508164s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.85179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.38612Z","time spent":"2.465657985s","remote":"127.0.0.1:40770","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-05-12T05:47:06.851346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77134272s","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-05-12T05:47:06.851857Z","caller":"traceutil/trace.go:171","msg":"trace[758949532] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1041; }","duration":"1.771992948s","start":"2026-05-12T05:47:05.07984Z","end":"2026-05-12T05:47:06.851833Z","steps":["trace[758949532] 'agreement among raft nodes before linearized reading' (duration: 1.77133745s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.851421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.465203893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:47:06.851916Z","caller":"traceutil/trace.go:171","msg":"trace[1179989958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"2.465710016s","start":"2026-05-12T05:47:04.386195Z","end":"2026-05-12T05:47:06.851905Z","steps":["trace[1179989958] 'agreement among raft nodes before linearized reading' (duration: 2.465148331s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.851924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:05.079817Z","time spent":"1.77209016s","remote":"127.0.0.1:40894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:06.851948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.38619Z","time spent":"2.465749137s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:06.851445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915512739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:47:06.852073Z","caller":"traceutil/trace.go:171","msg":"trace[1896445121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"1.916137175s","start":"2026-05-12T05:47:04.935911Z","end":"2026-05-12T05:47:06.852048Z","steps":["trace[1896445121] 'agreement among raft nodes before linearized reading' (duration: 1.915489558s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.851466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.930980263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:47:06.852117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.935904Z","time spent":"1.916202226s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T05:47:06.852147Z","caller":"traceutil/trace.go:171","msg":"trace[336737318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"1.931701412s","start":"2026-05-12T05:47:04.920438Z","end":"2026-05-12T05:47:06.852139Z","steps":["trace[336737318] 'agreement among raft nodes before linearized reading' (duration: 1.930995743s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.85217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:04.920401Z","time spent":"1.931762844s","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:47:06.851565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364609934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:06.852214Z","caller":"traceutil/trace.go:171","msg":"trace[655459591] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1041; }","duration":"1.365307532s","start":"2026-05-12T05:47:05.486894Z","end":"2026-05-12T05:47:06.852202Z","steps":["trace[655459591] 'agreement among raft nodes before linearized reading' (duration: 1.364612794s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:06.852259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:05.486875Z","time spent":"1.365369774s","remote":"127.0.0.1:48660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-12T05:47:07.239565Z","caller":"traceutil/trace.go:171","msg":"trace[900397098] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"382.373156ms","start":"2026-05-12T05:47:06.857159Z","end":"2026-05-12T05:47:07.239532Z","steps":["trace[900397098] 'process raft request' (duration: 382.289574ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:07.239874Z","caller":"traceutil/trace.go:171","msg":"trace[447265261] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1082; }","duration":"378.321762ms","start":"2026-05-12T05:47:06.861496Z","end":"2026-05-12T05:47:07.239818Z","steps":["trace[447265261] 'read index received' (duration: 25.318096ms)","trace[447265261] 'applied index is now lower than readState.Index' (duration: 353.002416ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:07.239881Z","caller":"traceutil/trace.go:171","msg":"trace[1871350608] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"382.669063ms","start":"2026-05-12T05:47:06.857153Z","end":"2026-05-12T05:47:07.239822Z","steps":["trace[1871350608] 'process raft request' (duration: 382.343865ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:07.239984Z","caller":"traceutil/trace.go:171","msg":"trace[604843723] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"382.741595ms","start":"2026-05-12T05:47:06.857226Z","end":"2026-05-12T05:47:07.239968Z","steps":["trace[604843723] 'process raft request' (duration: 382.464118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:07.240207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.85714Z","time spent":"382.887548ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:07.240391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.883726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:47:07.240421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.810012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:47:07.240377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.857216Z","time spent":"383.055662ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:07.240446Z","caller":"traceutil/trace.go:171","msg":"trace[798539764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"378.967078ms","start":"2026-05-12T05:47:06.861466Z","end":"2026-05-12T05:47:07.240433Z","steps":["trace[798539764] 'agreement among raft nodes before linearized reading' (duration: 378.865696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:07.240479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.861457Z","time spent":"379.015089ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T05:47:07.239565Z","caller":"traceutil/trace.go:171","msg":"trace[163741602] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"382.403976ms","start":"2026-05-12T05:47:06.85713Z","end":"2026-05-12T05:47:07.239534Z","steps":["trace[163741602] 'process raft request' (duration: 382.135709ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:07.240607Z","caller":"traceutil/trace.go:171","msg":"trace[1855238499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1046; }","duration":"356.900525ms","start":"2026-05-12T05:47:06.883555Z","end":"2026-05-12T05:47:07.240455Z","steps":["trace[1855238499] 'agreement among raft nodes before linearized reading' (duration: 356.71193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:07.240698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.857121Z","time spent":"383.536275ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:07.240177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.85715Z","time spent":"382.684343ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:07.240684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:06.883541Z","time spent":"357.121501ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:07.572031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.179075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:07.572166Z","caller":"traceutil/trace.go:171","msg":"trace[299464114] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1046; }","duration":"214.346989ms","start":"2026-05-12T05:47:07.357778Z","end":"2026-05-12T05:47:07.572125Z","steps":["trace[299464114] 'count revisions from in-memory index tree' (duration: 214.090943ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:09.352382Z","caller":"traceutil/trace.go:171","msg":"trace[1102760619] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"103.310426ms","start":"2026-05-12T05:47:09.249041Z","end":"2026-05-12T05:47:09.352352Z","steps":["trace[1102760619] 'process raft request' (duration: 58.952814ms)","trace[1102760619] 'compare' (duration: 44.201858ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:09.352741Z","caller":"traceutil/trace.go:171","msg":"trace[697931761] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"103.231033ms","start":"2026-05-12T05:47:09.249488Z","end":"2026-05-12T05:47:09.352719Z","steps":["trace[697931761] 'process raft request' (duration: 102.829893ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:09.35276Z","caller":"traceutil/trace.go:171","msg":"trace[1354323710] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"102.772082ms","start":"2026-05-12T05:47:09.249959Z","end":"2026-05-12T05:47:09.352731Z","steps":["trace[1354323710] 'process raft request' (duration: 102.636618ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:22.500191Z","caller":"traceutil/trace.go:171","msg":"trace[1964536295] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1093; }","duration":"196.447482ms","start":"2026-05-12T05:47:22.303719Z","end":"2026-05-12T05:47:22.500167Z","steps":["trace[1964536295] 'process raft request' (duration: 196.351039ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:22.75598Z","caller":"traceutil/trace.go:171","msg":"trace[210114077] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"123.912195ms","start":"2026-05-12T05:47:22.632037Z","end":"2026-05-12T05:47:22.755949Z","steps":["trace[210114077] 'process raft request' (duration: 95.140592ms)","trace[210114077] 'compare' (duration: 28.598079ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:22.922158Z","caller":"traceutil/trace.go:171","msg":"trace[1270971239] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"116.556996ms","start":"2026-05-12T05:47:22.805576Z","end":"2026-05-12T05:47:22.922133Z","steps":["trace[1270971239] 'process raft request' (duration: 107.23793ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:23.099028Z","caller":"traceutil/trace.go:171","msg":"trace[163542418] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"107.906766ms","start":"2026-05-12T05:47:22.991095Z","end":"2026-05-12T05:47:23.099002Z","steps":["trace[163542418] 'process raft request' (duration: 47.851648ms)","trace[163542418] 'compare' (duration: 59.906134ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:23.099026Z","caller":"traceutil/trace.go:171","msg":"trace[27364074] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"107.528427ms","start":"2026-05-12T05:47:22.991476Z","end":"2026-05-12T05:47:23.099004Z","steps":["trace[27364074] 'process raft request' (duration: 107.488986ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:23.31161Z","caller":"traceutil/trace.go:171","msg":"trace[1623172134] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"197.445657ms","start":"2026-05-12T05:47:23.114129Z","end":"2026-05-12T05:47:23.311575Z","steps":["trace[1623172134] 'read index received' (duration: 122.952611ms)","trace[1623172134] 'applied index is now lower than readState.Index' (duration: 74.491346ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:23.311666Z","caller":"traceutil/trace.go:171","msg":"trace[2040014101] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"208.383025ms","start":"2026-05-12T05:47:23.103243Z","end":"2026-05-12T05:47:23.311626Z","steps":["trace[2040014101] 'process raft request' (duration: 133.828987ms)","trace[2040014101] 'compare' (duration: 74.363653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:23.311995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.824276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-12T05:47:23.312119Z","caller":"traceutil/trace.go:171","msg":"trace[239120546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1107; }","duration":"197.994551ms","start":"2026-05-12T05:47:23.114093Z","end":"2026-05-12T05:47:23.312088Z","steps":["trace[239120546] 'agreement among raft nodes before linearized reading' (duration: 197.615561ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:23.549006Z","caller":"traceutil/trace.go:171","msg":"trace[2096190574] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"122.710544ms","start":"2026-05-12T05:47:23.426264Z","end":"2026-05-12T05:47:23.548975Z","steps":["trace[2096190574] 'process raft request' (duration: 122.494339ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:23.747985Z","caller":"traceutil/trace.go:171","msg":"trace[48953759] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"120.104488ms","start":"2026-05-12T05:47:23.627848Z","end":"2026-05-12T05:47:23.747953Z","steps":["trace[48953759] 'process raft request' (duration: 120.043366ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:23.748059Z","caller":"traceutil/trace.go:171","msg":"trace[987787268] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"121.325859ms","start":"2026-05-12T05:47:23.626666Z","end":"2026-05-12T05:47:23.747992Z","steps":["trace[987787268] 'process raft request' (duration: 121.097173ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:25.131865Z","caller":"traceutil/trace.go:171","msg":"trace[1583239592] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"108.483462ms","start":"2026-05-12T05:47:25.023363Z","end":"2026-05-12T05:47:25.131846Z","steps":["trace[1583239592] 'process raft request' (duration: 108.361429ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:25.468731Z","caller":"traceutil/trace.go:171","msg":"trace[1094891259] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"103.370922ms","start":"2026-05-12T05:47:25.365295Z","end":"2026-05-12T05:47:25.468666Z","steps":["trace[1094891259] 'process raft request' (duration: 102.937902ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:27.404723Z","caller":"traceutil/trace.go:171","msg":"trace[763314673] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"533.125963ms","start":"2026-05-12T05:47:26.871577Z","end":"2026-05-12T05:47:27.404703Z","steps":["trace[763314673] 'process raft request' (duration: 532.982059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:27.404851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:26.871552Z","time spent":"533.236356ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:27.449961Z","caller":"traceutil/trace.go:171","msg":"trace[1461309075] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"313.151832ms","start":"2026-05-12T05:47:27.136793Z","end":"2026-05-12T05:47:27.449945Z","steps":["trace[1461309075] 'process raft request' (duration: 310.994148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:27.450053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.136745Z","time spent":"313.262595ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:28.2293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.482327ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:28.229418Z","caller":"traceutil/trace.go:171","msg":"trace[1301972820] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1158; }","duration":"217.61444ms","start":"2026-05-12T05:47:28.011783Z","end":"2026-05-12T05:47:28.229398Z","steps":["trace[1301972820] 'range keys from in-memory index tree' (duration: 217.468127ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:28.229686Z","caller":"traceutil/trace.go:171","msg":"trace[1842510452] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"460.659278ms","start":"2026-05-12T05:47:27.769013Z","end":"2026-05-12T05:47:28.229672Z","steps":["trace[1842510452] 'read index received' (duration: 460.562736ms)","trace[1842510452] 'applied index is now lower than readState.Index' (duration: 95.782µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:28.229851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.840413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:28.229835Z","caller":"traceutil/trace.go:171","msg":"trace[570112570] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"754.777966ms","start":"2026-05-12T05:47:27.474964Z","end":"2026-05-12T05:47:28.229742Z","steps":["trace[570112570] 'process raft request' (duration: 754.620912ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:28.229878Z","caller":"traceutil/trace.go:171","msg":"trace[2095430880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"460.870273ms","start":"2026-05-12T05:47:27.769001Z","end":"2026-05-12T05:47:28.229871Z","steps":["trace[2095430880] 'agreement among raft nodes before linearized reading' (duration: 460.7475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:28.229902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.76896Z","time spent":"460.936015ms","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:47:28.229951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.474942Z","time spent":"754.945751ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:28.230016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.563393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T05:47:28.230065Z","caller":"traceutil/trace.go:171","msg":"trace[1153031200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"452.638114ms","start":"2026-05-12T05:47:27.777415Z","end":"2026-05-12T05:47:28.230053Z","steps":["trace[1153031200] 'agreement among raft nodes before linearized reading' (duration: 452.49519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:28.230064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.931809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:47:28.230098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.7774Z","time spent":"452.691226ms","remote":"127.0.0.1:40770","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-05-12T05:47:28.230128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.544032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:28.230155Z","caller":"traceutil/trace.go:171","msg":"trace[1546647113] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1159; }","duration":"117.606884ms","start":"2026-05-12T05:47:28.112539Z","end":"2026-05-12T05:47:28.230146Z","steps":["trace[1546647113] 'agreement among raft nodes before linearized reading' (duration: 117.567373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:28.230158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.944195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:28.230146Z","caller":"traceutil/trace.go:171","msg":"trace[1088936892] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1159; }","duration":"361.060102ms","start":"2026-05-12T05:47:27.869067Z","end":"2026-05-12T05:47:28.230127Z","steps":["trace[1088936892] 'agreement among raft nodes before linearized reading' (duration: 360.924328ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:28.230187Z","caller":"traceutil/trace.go:171","msg":"trace[646224766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"308.990986ms","start":"2026-05-12T05:47:27.921188Z","end":"2026-05-12T05:47:28.230179Z","steps":["trace[646224766] 'agreement among raft nodes before linearized reading' (duration: 308.935145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:28.230208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.921174Z","time spent":"309.029368ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:47:28.230202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.869052Z","time spent":"361.141254ms","remote":"127.0.0.1:48566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:28.230341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.69431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T05:47:28.230356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.820552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:47:28.230381Z","caller":"traceutil/trace.go:171","msg":"trace[384458967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"451.871544ms","start":"2026-05-12T05:47:27.778504Z","end":"2026-05-12T05:47:28.230376Z","steps":["trace[384458967] 'agreement among raft nodes before linearized reading' (duration: 451.803112ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:28.230384Z","caller":"traceutil/trace.go:171","msg":"trace[1874208906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"451.742671ms","start":"2026-05-12T05:47:27.778631Z","end":"2026-05-12T05:47:28.230373Z","steps":["trace[1874208906] 'agreement among raft nodes before linearized reading' (duration: 451.644998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:28.230399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.77849Z","time spent":"451.905774ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:28.230413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.778626Z","time spent":"451.780542ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:28.230447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.823463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:47:28.230475Z","caller":"traceutil/trace.go:171","msg":"trace[368690894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"451.872484ms","start":"2026-05-12T05:47:27.778595Z","end":"2026-05-12T05:47:28.230468Z","steps":["trace[368690894] 'agreement among raft nodes before linearized reading' (duration: 451.751531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:28.230499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:27.778583Z","time spent":"451.911225ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T05:47:41.649826Z","caller":"traceutil/trace.go:171","msg":"trace[1178174288] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"151.362423ms","start":"2026-05-12T05:47:41.498436Z","end":"2026-05-12T05:47:41.649798Z","steps":["trace[1178174288] 'process raft request' (duration: 151.108257ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:42.561907Z","caller":"traceutil/trace.go:171","msg":"trace[758066586] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"127.590869ms","start":"2026-05-12T05:47:42.434289Z","end":"2026-05-12T05:47:42.561879Z","steps":["trace[758066586] 'read index received' (duration: 127.509177ms)","trace[758066586] 'applied index is now lower than readState.Index' (duration: 80.812µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:42.562198Z","caller":"traceutil/trace.go:171","msg":"trace[448499581] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"164.018366ms","start":"2026-05-12T05:47:42.398163Z","end":"2026-05-12T05:47:42.562182Z","steps":["trace[448499581] 'process raft request' (duration: 163.509394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:42.562461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.154893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:47:42.562502Z","caller":"traceutil/trace.go:171","msg":"trace[727548579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"128.230705ms","start":"2026-05-12T05:47:42.434262Z","end":"2026-05-12T05:47:42.562493Z","steps":["trace[727548579] 'agreement among raft nodes before linearized reading' (duration: 128.099422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:42.562678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.433974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T05:47:42.562712Z","caller":"traceutil/trace.go:171","msg":"trace[2002502123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"127.482605ms","start":"2026-05-12T05:47:42.435221Z","end":"2026-05-12T05:47:42.562704Z","steps":["trace[2002502123] 'agreement among raft nodes before linearized reading' (duration: 127.397163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:42.563049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.42491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:47:42.563088Z","caller":"traceutil/trace.go:171","msg":"trace[1997797538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"119.486461ms","start":"2026-05-12T05:47:42.443592Z","end":"2026-05-12T05:47:42.563079Z","steps":["trace[1997797538] 'agreement among raft nodes before linearized reading' (duration: 119.401909ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:43.106262Z","caller":"traceutil/trace.go:171","msg":"trace[2060542322] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"538.52048ms","start":"2026-05-12T05:47:42.567709Z","end":"2026-05-12T05:47:43.106229Z","steps":["trace[2060542322] 'process raft request' (duration: 475.341052ms)","trace[2060542322] 'compare' (duration: 62.880891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:43.106428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:42.567696Z","time spent":"538.648593ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:43.115708Z","caller":"traceutil/trace.go:171","msg":"trace[1034587634] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"547.350546ms","start":"2026-05-12T05:47:42.568334Z","end":"2026-05-12T05:47:43.115684Z","steps":["trace[1034587634] 'process raft request' (duration: 547.298014ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:43.115757Z","caller":"traceutil/trace.go:171","msg":"trace[1398503576] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"547.983522ms","start":"2026-05-12T05:47:42.567741Z","end":"2026-05-12T05:47:43.115725Z","steps":["trace[1398503576] 'process raft request' (duration: 547.780767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:43.115842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:42.568324Z","time spent":"547.443738ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:43.115903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:42.567727Z","time spent":"548.096984ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:43.116179Z","caller":"traceutil/trace.go:171","msg":"trace[480742060] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1256; }","duration":"195.909498ms","start":"2026-05-12T05:47:42.920255Z","end":"2026-05-12T05:47:43.116164Z","steps":["trace[480742060] 'read index received' (duration: 122.790486ms)","trace[480742060] 'applied index is now lower than readState.Index' (duration: 73.117612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:43.116305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.053051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:43.116335Z","caller":"traceutil/trace.go:171","msg":"trace[2104763064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"196.128093ms","start":"2026-05-12T05:47:42.9202Z","end":"2026-05-12T05:47:43.116328Z","steps":["trace[2104763064] 'agreement among raft nodes before linearized reading' (duration: 196.00824ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:43.116342Z","caller":"traceutil/trace.go:171","msg":"trace[174902871] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"547.641363ms","start":"2026-05-12T05:47:42.568687Z","end":"2026-05-12T05:47:43.116328Z","steps":["trace[174902871] 'process raft request' (duration: 546.975086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:43.116405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:42.568677Z","time spent":"547.695925ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:44.306872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651952279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T05:47:44.436307Z","caller":"traceutil/trace.go:171","msg":"trace[1304901288] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"861.414381ms","start":"2026-05-12T05:47:43.574868Z","end":"2026-05-12T05:47:44.436283Z","steps":["trace[1304901288] 'process raft request' (duration: 861.260897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:44.436433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:43.574848Z","time spent":"861.527234ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:47:45.275356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.999819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:47:45.275466Z","caller":"traceutil/trace.go:171","msg":"trace[1743065096] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1260; }","duration":"1.469429412s","start":"2026-05-12T05:47:43.806017Z","end":"2026-05-12T05:47:45.275446Z","steps":["trace[1743065096] 'read index received' (duration: 630.152144ms)","trace[1743065096] 'applied index is now lower than readState.Index' (duration: 839.276058ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:45.275507Z","caller":"traceutil/trace.go:171","msg":"trace[561214830] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"1.620916149s","start":"2026-05-12T05:47:43.654546Z","end":"2026-05-12T05:47:45.275462Z","steps":["trace[561214830] 'process raft request' (duration: 937.715405ms)","trace[561214830] 'compare' (duration: 682.889935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:45.275594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469555415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:45.275663Z","caller":"traceutil/trace.go:171","msg":"trace[471802903] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1212; }","duration":"1.469638427s","start":"2026-05-12T05:47:43.806009Z","end":"2026-05-12T05:47:45.275648Z","steps":["trace[471802903] 'agreement among raft nodes before linearized reading' (duration: 1.469520584s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.275697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:43.805941Z","time spent":"1.469748029s","remote":"127.0.0.1:40786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:45.27569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:43.654367Z","time spent":"1.621227776s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:45.422503Z","caller":"traceutil/trace.go:171","msg":"trace[143478329] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"146.960511ms","start":"2026-05-12T05:47:45.275509Z","end":"2026-05-12T05:47:45.422469Z","steps":["trace[143478329] 'read index received' (duration: 58.071418ms)","trace[143478329] 'applied index is now lower than readState.Index' (duration: 88.887183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:45.422654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.277766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T05:47:45.422717Z","caller":"traceutil/trace.go:171","msg":"trace[1994941982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"302.362768ms","start":"2026-05-12T05:47:45.120338Z","end":"2026-05-12T05:47:45.422701Z","steps":["trace[1994941982] 'agreement among raft nodes before linearized reading' (duration: 302.136052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.422713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.384099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-12T05:47:45.422703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448658242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:45.422754Z","caller":"traceutil/trace.go:171","msg":"trace[1750252510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1212; }","duration":"302.452781ms","start":"2026-05-12T05:47:45.120291Z","end":"2026-05-12T05:47:45.422744Z","steps":["trace[1750252510] 'agreement among raft nodes before linearized reading' (duration: 302.350818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.422724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.354622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-12T05:47:45.422779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:45.120278Z","time spent":"302.495991ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T05:47:45.422787Z","caller":"traceutil/trace.go:171","msg":"trace[1745027525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"1.448804176s","start":"2026-05-12T05:47:43.973967Z","end":"2026-05-12T05:47:45.422771Z","steps":["trace[1745027525] 'agreement among raft nodes before linearized reading' (duration: 1.448622342s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.422812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.935647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:47:45.422833Z","caller":"traceutil/trace.go:171","msg":"trace[1545344562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1212; }","duration":"301.956098ms","start":"2026-05-12T05:47:45.120871Z","end":"2026-05-12T05:47:45.422827Z","steps":["trace[1545344562] 'agreement among raft nodes before linearized reading' (duration: 301.898526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.422855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:45.12081Z","time spent":"302.04055ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T05:47:45.422815Z","caller":"traceutil/trace.go:171","msg":"trace[1256091157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1212; }","duration":"312.491135ms","start":"2026-05-12T05:47:45.110301Z","end":"2026-05-12T05:47:45.422792Z","steps":["trace[1256091157] 'agreement among raft nodes before linearized reading' (duration: 312.240929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.422843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:43.973955Z","time spent":"1.448875728s","remote":"127.0.0.1:40460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:47:45.422898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:45.110281Z","time spent":"312.607178ms","remote":"127.0.0.1:40770","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-05-12T05:47:45.422753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:45.120333Z","time spent":"302.41366ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:45.423003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.179332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T05:47:45.423053Z","caller":"traceutil/trace.go:171","msg":"trace[1688556561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1212; }","duration":"855.260924ms","start":"2026-05-12T05:47:44.567779Z","end":"2026-05-12T05:47:45.42304Z","steps":["trace[1688556561] 'agreement among raft nodes before linearized reading' (duration: 855.09175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.423095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:44.567752Z","time spent":"855.333236ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T05:47:45.56632Z","caller":"traceutil/trace.go:171","msg":"trace[771433551] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"136.71979ms","start":"2026-05-12T05:47:45.429575Z","end":"2026-05-12T05:47:45.566294Z","steps":["trace[771433551] 'process raft request' (duration: 136.652588ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:45.566352Z","caller":"traceutil/trace.go:171","msg":"trace[1237984907] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"138.387352ms","start":"2026-05-12T05:47:45.427922Z","end":"2026-05-12T05:47:45.566309Z","steps":["trace[1237984907] 'process raft request' (duration: 122.283903ms)","trace[1237984907] 'compare' (duration: 15.842563ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:45.566395Z","caller":"traceutil/trace.go:171","msg":"trace[1609191266] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"138.384883ms","start":"2026-05-12T05:47:45.42797Z","end":"2026-05-12T05:47:45.566355Z","steps":["trace[1609191266] 'process raft request' (duration: 138.217698ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:45.566481Z","caller":"traceutil/trace.go:171","msg":"trace[335751666] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1263; }","duration":"133.792545ms","start":"2026-05-12T05:47:45.432665Z","end":"2026-05-12T05:47:45.566458Z","steps":["trace[335751666] 'read index received' (duration: 8.281461ms)","trace[335751666] 'applied index is now lower than readState.Index' (duration: 125.509104ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:45.566566Z","caller":"traceutil/trace.go:171","msg":"trace[915401706] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"134.261667ms","start":"2026-05-12T05:47:45.432283Z","end":"2026-05-12T05:47:45.566545Z","steps":["trace[915401706] 'process raft request' (duration: 133.97644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:45.566622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.93448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:45.566658Z","caller":"traceutil/trace.go:171","msg":"trace[388428771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"133.999102ms","start":"2026-05-12T05:47:45.432647Z","end":"2026-05-12T05:47:45.566646Z","steps":["trace[388428771] 'agreement among raft nodes before linearized reading' (duration: 133.884948ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:46.210464Z","caller":"traceutil/trace.go:171","msg":"trace[148659775] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"189.849193ms","start":"2026-05-12T05:47:46.020592Z","end":"2026-05-12T05:47:46.210441Z","steps":["trace[148659775] 'process raft request' (duration: 189.566886ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:46.21045Z","caller":"traceutil/trace.go:171","msg":"trace[994416996] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"149.430523ms","start":"2026-05-12T05:47:46.060991Z","end":"2026-05-12T05:47:46.210422Z","steps":["trace[994416996] 'read index received' (duration: 149.033383ms)","trace[994416996] 'applied index is now lower than readState.Index' (duration: 396.24µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:46.210567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.560007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:46.210783Z","caller":"traceutil/trace.go:171","msg":"trace[1977428287] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1219; }","duration":"149.784993ms","start":"2026-05-12T05:47:46.060981Z","end":"2026-05-12T05:47:46.210766Z","steps":["trace[1977428287] 'agreement among raft nodes before linearized reading' (duration: 149.517026ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:47.588192Z","caller":"traceutil/trace.go:171","msg":"trace[1737269451] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"142.753874ms","start":"2026-05-12T05:47:47.445413Z","end":"2026-05-12T05:47:47.588167Z","steps":["trace[1737269451] 'read index received' (duration: 142.59405ms)","trace[1737269451] 'applied index is now lower than readState.Index' (duration: 158.904µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:47.588302Z","caller":"traceutil/trace.go:171","msg":"trace[1593865243] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"306.913033ms","start":"2026-05-12T05:47:47.281377Z","end":"2026-05-12T05:47:47.58829Z","steps":["trace[1593865243] 'process raft request' (duration: 306.625796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:47.588399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:47.281355Z","time spent":"306.972244ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:47:47.588919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.511564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T05:47:47.588957Z","caller":"traceutil/trace.go:171","msg":"trace[1754022611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"143.584236ms","start":"2026-05-12T05:47:47.445361Z","end":"2026-05-12T05:47:47.588946Z","steps":["trace[1754022611] 'agreement among raft nodes before linearized reading' (duration: 143.497054ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:47.890142Z","caller":"traceutil/trace.go:171","msg":"trace[1762719047] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"296.034517ms","start":"2026-05-12T05:47:47.594084Z","end":"2026-05-12T05:47:47.890119Z","steps":["trace[1762719047] 'process raft request' (duration: 296.003626ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:47.890149Z","caller":"traceutil/trace.go:171","msg":"trace[1596424767] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"297.251378ms","start":"2026-05-12T05:47:47.592858Z","end":"2026-05-12T05:47:47.890109Z","steps":["trace[1596424767] 'process raft request' (duration: 275.020932ms)","trace[1596424767] 'compare' (duration: 22.045641ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:47:47.890304Z","caller":"traceutil/trace.go:171","msg":"trace[1683598716] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"297.34203ms","start":"2026-05-12T05:47:47.592955Z","end":"2026-05-12T05:47:47.890297Z","steps":["trace[1683598716] 'process raft request' (duration: 297.076533ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:47.890357Z","caller":"traceutil/trace.go:171","msg":"trace[1486965312] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"296.93224ms","start":"2026-05-12T05:47:47.593418Z","end":"2026-05-12T05:47:47.89035Z","steps":["trace[1486965312] 'process raft request' (duration: 296.650133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:48.351363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.683241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T05:47:48.351445Z","caller":"traceutil/trace.go:171","msg":"trace[778495769] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1226; }","duration":"323.814844ms","start":"2026-05-12T05:47:48.027612Z","end":"2026-05-12T05:47:48.351426Z","steps":["trace[778495769] 'count revisions from in-memory index tree' (duration: 323.591479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:48.351489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:48.027589Z","time spent":"323.883746ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:48.351468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.111543ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:47:48.351588Z","caller":"traceutil/trace.go:171","msg":"trace[1356384146] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1226; }","duration":"339.237846ms","start":"2026-05-12T05:47:48.012331Z","end":"2026-05-12T05:47:48.351569Z","steps":["trace[1356384146] 'range keys from in-memory index tree' (duration: 339.096083ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:48.352157Z","caller":"traceutil/trace.go:171","msg":"trace[1910070958] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"271.306148ms","start":"2026-05-12T05:47:48.080837Z","end":"2026-05-12T05:47:48.352143Z","steps":["trace[1910070958] 'process raft request' (duration: 271.197625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.164547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651952458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:58.665001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102298651952458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:47:58.761375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.102725615s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T05:47:58.761893Z","caller":"traceutil/trace.go:171","msg":"trace[901362889] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.103321511s","start":"2026-05-12T05:47:57.658536Z","end":"2026-05-12T05:47:58.761858Z","steps":["trace[901362889] 'process raft request' (duration: 1.103125496s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.762127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:57.658513Z","time spent":"1.103450924s","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:47:58.766653Z","caller":"traceutil/trace.go:171","msg":"trace[341962525] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1365; }","duration":"1.102447618s","start":"2026-05-12T05:47:57.664195Z","end":"2026-05-12T05:47:58.766643Z","steps":["trace[341962525] 'read index received' (duration: 1.097595755s)","trace[341962525] 'applied index is now lower than readState.Index' (duration: 4.851123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:47:58.766997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.931393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-12T05:47:58.76705Z","caller":"traceutil/trace.go:171","msg":"trace[275398623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"739.003506ms","start":"2026-05-12T05:47:58.028016Z","end":"2026-05-12T05:47:58.76702Z","steps":["trace[275398623] 'agreement among raft nodes before linearized reading' (duration: 738.903993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.767078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:58.027991Z","time spent":"739.081307ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:58.767056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102840048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T05:47:58.767163Z","caller":"traceutil/trace.go:171","msg":"trace[1725546774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"1.102987912s","start":"2026-05-12T05:47:57.664147Z","end":"2026-05-12T05:47:58.767134Z","steps":["trace[1725546774] 'agreement among raft nodes before linearized reading' (duration: 1.102712515s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.76722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:57.664121Z","time spent":"1.103084655s","remote":"127.0.0.1:40770","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-05-12T05:47:58.767435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.668906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-12T05:47:58.767467Z","caller":"traceutil/trace.go:171","msg":"trace[875360700] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1314; }","duration":"476.726677ms","start":"2026-05-12T05:47:58.290731Z","end":"2026-05-12T05:47:58.767458Z","steps":["trace[875360700] 'agreement among raft nodes before linearized reading' (duration: 476.654815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.767512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:58.290714Z","time spent":"476.791869ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-12T05:47:58.767522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.080307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T05:47:58.767586Z","caller":"traceutil/trace.go:171","msg":"trace[1323265763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"739.17572ms","start":"2026-05-12T05:47:58.028396Z","end":"2026-05-12T05:47:58.767572Z","steps":["trace[1323265763] 'agreement among raft nodes before linearized reading' (duration: 739.047917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.767631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:58.028371Z","time spent":"739.252962ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:58.767563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.714408ms","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-05-12T05:47:58.767737Z","caller":"traceutil/trace.go:171","msg":"trace[1050261571] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1314; }","duration":"705.971495ms","start":"2026-05-12T05:47:58.061746Z","end":"2026-05-12T05:47:58.767718Z","steps":["trace[1050261571] 'agreement among raft nodes before linearized reading' (duration: 705.750739ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:58.767366Z","caller":"traceutil/trace.go:171","msg":"trace[784222100] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"209.187866ms","start":"2026-05-12T05:47:58.558165Z","end":"2026-05-12T05:47:58.767353Z","steps":["trace[784222100] 'process raft request' (duration: 208.418456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.767814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:58.061728Z","time spent":"706.071927ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:47:58.767729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.661516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T05:47:58.768024Z","caller":"traceutil/trace.go:171","msg":"trace[1077786269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"736.984064ms","start":"2026-05-12T05:47:58.031015Z","end":"2026-05-12T05:47:58.767999Z","steps":["trace[1077786269] 'agreement among raft nodes before linearized reading' (duration: 736.519962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.76808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:58.031004Z","time spent":"737.068035ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T05:47:58.768301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.848161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-12T05:47:58.768339Z","caller":"traceutil/trace.go:171","msg":"trace[672694135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"736.906452ms","start":"2026-05-12T05:47:58.031423Z","end":"2026-05-12T05:47:58.768329Z","steps":["trace[672694135] 'agreement among raft nodes before linearized reading' (duration: 736.05108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:47:58.768503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:47:58.031413Z","time spent":"737.081346ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T05:48:11.005989Z","caller":"traceutil/trace.go:171","msg":"trace[1789039418] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"125.699941ms","start":"2026-05-12T05:48:10.880262Z","end":"2026-05-12T05:48:11.005962Z","steps":["trace[1789039418] 'process raft request' (duration: 97.253516ms)","trace[1789039418] 'compare' (duration: 28.30271ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:48:11.006346Z","caller":"traceutil/trace.go:171","msg":"trace[1376320916] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"126.050759ms","start":"2026-05-12T05:48:10.880267Z","end":"2026-05-12T05:48:11.006318Z","steps":["trace[1376320916] 'process raft request' (duration: 125.664619ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:48:11.006375Z","caller":"traceutil/trace.go:171","msg":"trace[873669808] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"125.736012ms","start":"2026-05-12T05:48:10.880616Z","end":"2026-05-12T05:48:11.006352Z","steps":["trace[873669808] 'process raft request' (duration: 125.574178ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:48:11.006988Z","caller":"traceutil/trace.go:171","msg":"trace[1466653748] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"126.336396ms","start":"2026-05-12T05:48:10.880632Z","end":"2026-05-12T05:48:11.006968Z","steps":["trace[1466653748] 'process raft request' (duration: 125.68413ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:48:11.006978Z","caller":"traceutil/trace.go:171","msg":"trace[126891863] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"126.244555ms","start":"2026-05-12T05:48:10.8807Z","end":"2026-05-12T05:48:11.006944Z","steps":["trace[126891863] 'process raft request' (duration: 126.105281ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:48:59.555296Z","caller":"traceutil/trace.go:171","msg":"trace[1397905817] linearizableReadLoop","detail":"{readStateIndex:1659; appliedIndex:1658; }","duration":"173.412499ms","start":"2026-05-12T05:48:59.381851Z","end":"2026-05-12T05:48:59.555264Z","steps":["trace[1397905817] 'read index received' (duration: 159.529866ms)","trace[1397905817] 'applied index is now lower than readState.Index' (duration: 13.881353ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:48:59.555445Z","caller":"traceutil/trace.go:171","msg":"trace[350949533] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"176.760033ms","start":"2026-05-12T05:48:59.378649Z","end":"2026-05-12T05:48:59.555409Z","steps":["trace[350949533] 'process raft request' (duration: 162.658215ms)","trace[350949533] 'compare' (duration: 13.798501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:48:59.555706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.793478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-12T05:48:59.555683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.814318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:48:59.556431Z","caller":"traceutil/trace.go:171","msg":"trace[1857977606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1594; }","duration":"174.721011ms","start":"2026-05-12T05:48:59.381686Z","end":"2026-05-12T05:48:59.556407Z","steps":["trace[1857977606] 'agreement among raft nodes before linearized reading' (duration: 173.746347ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:48:59.556361Z","caller":"traceutil/trace.go:171","msg":"trace[537609375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1594; }","duration":"174.478745ms","start":"2026-05-12T05:48:59.381843Z","end":"2026-05-12T05:48:59.556322Z","steps":["trace[537609375] 'agreement among raft nodes before linearized reading' (duration: 173.722176ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:49:15.806141Z","caller":"traceutil/trace.go:171","msg":"trace[1051331653] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"133.789371ms","start":"2026-05-12T05:49:15.672317Z","end":"2026-05-12T05:49:15.806107Z","steps":["trace[1051331653] 'process raft request' (duration: 129.248825ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:49:16.016647Z","caller":"traceutil/trace.go:171","msg":"trace[359367790] linearizableReadLoop","detail":"{readStateIndex:1739; appliedIndex:1738; }","duration":"193.472698ms","start":"2026-05-12T05:49:15.823142Z","end":"2026-05-12T05:49:16.016615Z","steps":["trace[359367790] 'read index received' (duration: 120.944804ms)","trace[359367790] 'applied index is now lower than readState.Index' (duration: 72.526704ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:49:16.01692Z","caller":"traceutil/trace.go:171","msg":"trace[785601655] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"206.241463ms","start":"2026-05-12T05:49:15.81066Z","end":"2026-05-12T05:49:16.016901Z","steps":["trace[785601655] 'process raft request' (duration: 133.423411ms)","trace[785601655] 'compare' (duration: 72.411961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:49:16.017293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.129265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:49:16.017346Z","caller":"traceutil/trace.go:171","msg":"trace[2063069270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1670; }","duration":"194.218897ms","start":"2026-05-12T05:49:15.823113Z","end":"2026-05-12T05:49:16.017331Z","steps":["trace[2063069270] 'agreement among raft nodes before linearized reading' (duration: 193.981871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:49:16.017556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.595782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:49:16.017595Z","caller":"traceutil/trace.go:171","msg":"trace[1780929354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1670; }","duration":"191.666893ms","start":"2026-05-12T05:49:15.825919Z","end":"2026-05-12T05:49:16.017586Z","steps":["trace[1780929354] 'agreement among raft nodes before linearized reading' (duration: 191.565409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:49:16.018018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.774192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-12T05:49:16.018061Z","caller":"traceutil/trace.go:171","msg":"trace[1829554192] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1670; }","duration":"113.847094ms","start":"2026-05-12T05:49:15.904204Z","end":"2026-05-12T05:49:16.018051Z","steps":["trace[1829554192] 'agreement among raft nodes before linearized reading' (duration: 113.750172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:49:16.291847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.991174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:49:16.292713Z","caller":"traceutil/trace.go:171","msg":"trace[424369539] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"254.09101ms","start":"2026-05-12T05:49:16.038595Z","end":"2026-05-12T05:49:16.292686Z","steps":["trace[424369539] 'process raft request' (duration: 253.941516ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:49:16.292798Z","caller":"traceutil/trace.go:171","msg":"trace[2082454425] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"266.140345ms","start":"2026-05-12T05:49:16.026565Z","end":"2026-05-12T05:49:16.292706Z","steps":["trace[2082454425] 'process raft request' (duration: 146.153515ms)","trace[2082454425] 'compare' (duration: 118.772029ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:49:56.48071Z","caller":"traceutil/trace.go:171","msg":"trace[2093261027] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"112.935828ms","start":"2026-05-12T05:49:56.367741Z","end":"2026-05-12T05:49:56.480677Z","steps":["trace[2093261027] 'read index received' (duration: 75.62284ms)","trace[2093261027] 'applied index is now lower than readState.Index' (duration: 37.311878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:49:56.480881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.123623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-12T05:49:56.48085Z","caller":"traceutil/trace.go:171","msg":"trace[166941977] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"125.915746ms","start":"2026-05-12T05:49:56.354891Z","end":"2026-05-12T05:49:56.480807Z","steps":["trace[166941977] 'process raft request' (duration: 88.507566ms)","trace[166941977] 'compare' (duration: 37.162813ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:49:56.480909Z","caller":"traceutil/trace.go:171","msg":"trace[1901498235] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1855; }","duration":"113.187264ms","start":"2026-05-12T05:49:56.367712Z","end":"2026-05-12T05:49:56.4809Z","steps":["trace[1901498235] 'agreement among raft nodes before linearized reading' (duration: 113.067011ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:50:44.97506Z","caller":"traceutil/trace.go:171","msg":"trace[546184084] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"127.357559ms","start":"2026-05-12T05:50:44.847669Z","end":"2026-05-12T05:50:44.975026Z","steps":["trace[546184084] 'process raft request' (duration: 40.357476ms)","trace[546184084] 'compare' (duration: 86.703816ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:50:50.27039Z","caller":"traceutil/trace.go:171","msg":"trace[1485734393] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"130.208853ms","start":"2026-05-12T05:50:50.140146Z","end":"2026-05-12T05:50:50.270354Z","steps":["trace[1485734393] 'process raft request' (duration: 41.466125ms)","trace[1485734393] 'compare' (duration: 88.547813ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:50:55.735232Z","caller":"traceutil/trace.go:171","msg":"trace[1493874374] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"136.537373ms","start":"2026-05-12T05:50:55.598667Z","end":"2026-05-12T05:50:55.735204Z","steps":["trace[1493874374] 'process raft request' (duration: 136.376189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:51:11.266646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.109411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:51:11.266715Z","caller":"traceutil/trace.go:171","msg":"trace[789097947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2223; }","duration":"176.230304ms","start":"2026-05-12T05:51:11.090469Z","end":"2026-05-12T05:51:11.266699Z","steps":["trace[789097947] 'range keys from in-memory index tree' (duration: 175.954237ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:51:20.764391Z","caller":"traceutil/trace.go:171","msg":"trace[29297592] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"129.33053ms","start":"2026-05-12T05:51:20.635035Z","end":"2026-05-12T05:51:20.764366Z","steps":["trace[29297592] 'process raft request' (duration: 129.153596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:51:22.045921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.422701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:51:22.04602Z","caller":"traceutil/trace.go:171","msg":"trace[676185265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2276; }","duration":"127.560655ms","start":"2026-05-12T05:51:21.918431Z","end":"2026-05-12T05:51:22.045991Z","steps":["trace[676185265] 'range keys from in-memory index tree' (duration: 127.34074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:51:22.046038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.664653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-12T05:51:22.046128Z","caller":"traceutil/trace.go:171","msg":"trace[587805658] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2276; }","duration":"323.796728ms","start":"2026-05-12T05:51:21.722306Z","end":"2026-05-12T05:51:22.046103Z","steps":["trace[587805658] 'range keys from in-memory index tree' (duration: 323.469219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:51:22.04617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:51:21.722286Z","time spent":"323.87481ms","remote":"127.0.0.1:40770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-12T05:51:22.226672Z","caller":"traceutil/trace.go:171","msg":"trace[1178045504] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"176.185792ms","start":"2026-05-12T05:51:22.050443Z","end":"2026-05-12T05:51:22.226629Z","steps":["trace[1178045504] 'process raft request' (duration: 133.287151ms)","trace[1178045504] 'compare' (duration: 42.747118ms)"],"step_count":2}