{"level":"warn","ts":"2026-05-05T10:16:26.382897Z","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-05T10:16:26.382989Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-05T10:16:26.383057Z","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-05T10:16:26.383073Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-05T10:16:26.383093Z","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-05T10:16:26.38389Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-05-05T10:16:26.383973Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-05T10:16:26.389413Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.175693ms"} {"level":"info","ts":"2026-05-05T10:16:26.396167Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-05-05T10:16:26.396228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T10:16:26.396248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-05-05T10:16:26.396261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T10:16:26.396271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-05-05T10:16:26.396305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-05-05T10:16:26.403123Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T10:16:26.40441Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T10:16:26.405865Z","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-05T10:16:26.408574Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T10:16:26.40938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T10:16:26.40944Z","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-05T10:16:26.409476Z","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-05T10:16:26.409484Z","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-05T10:16:26.41023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-05-05T10:16:26.410354Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-05T10:16:26.413129Z","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-05T10:16:26.413315Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T10:16:26.413343Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T10:16:26.413445Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-05T10:16:26.413456Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-05T10:16:27.397463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T10:16:27.397524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T10:16:27.397564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-05-05T10:16:27.397591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-05-05T10:16:27.397608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-05T10:16:27.39763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-05-05T10:16:27.397669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-05T10:16:27.398707Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:27.400958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:16:27.400964Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T10:16:27.400979Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:16:27.401319Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T10:16:27.401389Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T10:16:27.402475Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:27.402664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:27.40274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:16:27.403391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-05-05T10:16:27.404223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T10:16:30.214464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393146614226140,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T10:16:30.560102Z","caller":"traceutil/trace.go:171","msg":"trace[1588302647] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"845.90466ms","start":"2026-05-05T10:16:29.714145Z","end":"2026-05-05T10:16:30.560049Z","steps":["trace[1588302647] 'read index received' (duration: 845.529243ms)","trace[1588302647] 'applied index is now lower than readState.Index' (duration: 286.426µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:30.560296Z","caller":"traceutil/trace.go:171","msg":"trace[779044242] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"846.912541ms","start":"2026-05-05T10:16:29.713348Z","end":"2026-05-05T10:16:30.56026Z","steps":["trace[779044242] 'process raft request' (duration: 846.271099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:30.561309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.144935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T10:16:30.561393Z","caller":"traceutil/trace.go:171","msg":"trace[29016006] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"847.248908ms","start":"2026-05-05T10:16:29.714123Z","end":"2026-05-05T10:16:30.561372Z","steps":["trace[29016006] 'agreement among raft nodes before linearized reading' (duration: 846.233387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:30.561425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:29.714116Z","time spent":"847.300308ms","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-05-05T10:16:30.561924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:29.713331Z","time spent":"847.054374ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:16:30.561981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.596616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T10:16:30.562092Z","caller":"traceutil/trace.go:171","msg":"trace[1009597544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"834.763149ms","start":"2026-05-05T10:16:29.727312Z","end":"2026-05-05T10:16:30.562076Z","steps":["trace[1009597544] 'agreement among raft nodes before linearized reading' (duration: 833.30375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:30.562274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:29.727294Z","time spent":"834.870301ms","remote":"127.0.0.1:33050","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-05T10:16:30.717449Z","caller":"traceutil/trace.go:171","msg":"trace[666965165] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"146.324876ms","start":"2026-05-05T10:16:30.571098Z","end":"2026-05-05T10:16:30.717423Z","steps":["trace[666965165] 'process raft request' (duration: 92.636549ms)","trace[666965165] 'compare' (duration: 53.565493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:16:56.667409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.427112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:16:56.667532Z","caller":"traceutil/trace.go:171","msg":"trace[1203876371] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"173.368672ms","start":"2026-05-05T10:16:56.494142Z","end":"2026-05-05T10:16:56.66751Z","steps":["trace[1203876371] 'process raft request' (duration: 55.560452ms)","trace[1203876371] 'compare' (duration: 117.301549ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:56.690919Z","caller":"traceutil/trace.go:171","msg":"trace[369058243] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"191.679766ms","start":"2026-05-05T10:16:56.49919Z","end":"2026-05-05T10:16:56.69087Z","steps":["trace[369058243] 'process raft request' (duration: 191.454132ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.690944Z","caller":"traceutil/trace.go:171","msg":"trace[1920484592] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"178.356121ms","start":"2026-05-05T10:16:56.512557Z","end":"2026-05-05T10:16:56.690913Z","steps":["trace[1920484592] 'process raft request' (duration: 178.30508ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.691505Z","caller":"traceutil/trace.go:171","msg":"trace[103996336] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"172.114637ms","start":"2026-05-05T10:16:56.51938Z","end":"2026-05-05T10:16:56.691495Z","steps":["trace[103996336] 'process raft request' (duration: 171.914193ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.691451Z","caller":"traceutil/trace.go:171","msg":"trace[1076057828] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"174.533436ms","start":"2026-05-05T10:16:56.516902Z","end":"2026-05-05T10:16:56.691435Z","steps":["trace[1076057828] 'process raft request' (duration: 173.990215ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.69103Z","caller":"traceutil/trace.go:171","msg":"trace[1818092908] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"191.648715ms","start":"2026-05-05T10:16:56.499375Z","end":"2026-05-05T10:16:56.691024Z","steps":["trace[1818092908] 'process raft request' (duration: 191.37894ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.691049Z","caller":"traceutil/trace.go:171","msg":"trace[1937057541] transaction","detail":"{read_only:false; number_of_response:1; response_revision:475; }","duration":"186.411342ms","start":"2026-05-05T10:16:56.504633Z","end":"2026-05-05T10:16:56.691044Z","steps":["trace[1937057541] 'process raft request' (duration: 186.151227ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.691118Z","caller":"traceutil/trace.go:171","msg":"trace[235505629] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"180.292269ms","start":"2026-05-05T10:16:56.510795Z","end":"2026-05-05T10:16:56.691087Z","steps":["trace[235505629] 'process raft request' (duration: 180.014444ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.691183Z","caller":"traceutil/trace.go:171","msg":"trace[719063661] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"179.29993ms","start":"2026-05-05T10:16:56.511859Z","end":"2026-05-05T10:16:56.691159Z","steps":["trace[719063661] 'process raft request' (duration: 178.979364ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.697463Z","caller":"traceutil/trace.go:171","msg":"trace[543225341] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"177.086817ms","start":"2026-05-05T10:16:56.520349Z","end":"2026-05-05T10:16:56.697436Z","steps":["trace[543225341] 'process raft request' (duration: 171.092358ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.697534Z","caller":"traceutil/trace.go:171","msg":"trace[1377181406] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"175.597466ms","start":"2026-05-05T10:16:56.521912Z","end":"2026-05-05T10:16:56.697509Z","steps":["trace[1377181406] 'process raft request' (duration: 169.570207ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.699545Z","caller":"traceutil/trace.go:171","msg":"trace[1309716998] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"152.889495ms","start":"2026-05-05T10:16:56.546643Z","end":"2026-05-05T10:16:56.699533Z","steps":["trace[1309716998] 'process raft request' (duration: 151.908266ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.704459Z","caller":"traceutil/trace.go:171","msg":"trace[1272476450] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"154.228673ms","start":"2026-05-05T10:16:56.54546Z","end":"2026-05-05T10:16:56.699689Z","steps":["trace[1272476450] 'process raft request' (duration: 152.952398ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.704495Z","caller":"traceutil/trace.go:171","msg":"trace[18783290] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"162.467207ms","start":"2026-05-05T10:16:56.537215Z","end":"2026-05-05T10:16:56.699682Z","steps":["trace[18783290] 'process raft request' (duration: 161.066099ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.704635Z","caller":"traceutil/trace.go:171","msg":"trace[118794284] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"168.034646ms","start":"2026-05-05T10:16:56.531668Z","end":"2026-05-05T10:16:56.699702Z","steps":["trace[118794284] 'process raft request' (duration: 159.860844ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.824262Z","caller":"traceutil/trace.go:171","msg":"trace[1273274104] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"119.458251ms","start":"2026-05-05T10:16:56.704786Z","end":"2026-05-05T10:16:56.824244Z","steps":["trace[1273274104] 'process raft request' (duration: 100.555926ms)","trace[1273274104] 'compare' (duration: 18.803093ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:56.8255Z","caller":"traceutil/trace.go:171","msg":"trace[401702245] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:507; }","duration":"116.384221ms","start":"2026-05-05T10:16:56.709097Z","end":"2026-05-05T10:16:56.825482Z","steps":["trace[401702245] 'read index received' (duration: 96.494386ms)","trace[401702245] 'applied index is now lower than readState.Index' (duration: 19.889125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:16:56.825672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.555464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-05T10:16:56.825702Z","caller":"traceutil/trace.go:171","msg":"trace[163094867] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:496; }","duration":"116.616806ms","start":"2026-05-05T10:16:56.709076Z","end":"2026-05-05T10:16:56.825693Z","steps":["trace[163094867] 'agreement among raft nodes before linearized reading' (duration: 116.471972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.825913Z","caller":"traceutil/trace.go:171","msg":"trace[1158230615] transaction","detail":"{read_only:false; number_of_response:1; response_revision:490; }","duration":"119.034043ms","start":"2026-05-05T10:16:56.70687Z","end":"2026-05-05T10:16:56.825904Z","steps":["trace[1158230615] 'process raft request' (duration: 118.287868ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.825919Z","caller":"traceutil/trace.go:171","msg":"trace[751272378] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"120.533103ms","start":"2026-05-05T10:16:56.705364Z","end":"2026-05-05T10:16:56.825897Z","steps":["trace[751272378] 'process raft request' (duration: 119.738267ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:56.826001Z","caller":"traceutil/trace.go:171","msg":"trace[1389221260] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"120.674656ms","start":"2026-05-05T10:16:56.70532Z","end":"2026-05-05T10:16:56.825994Z","steps":["trace[1389221260] 'process raft request' (duration: 119.700816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:57.698324Z","caller":"traceutil/trace.go:171","msg":"trace[1814160466] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"789.121948ms","start":"2026-05-05T10:16:56.909124Z","end":"2026-05-05T10:16:57.698246Z","steps":["trace[1814160466] 'process raft request' (duration: 728.2885ms)","trace[1814160466] 'compare' (duration: 59.780047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:16:57.698882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:56.909106Z","time spent":"789.422184ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:16:57.700734Z","caller":"traceutil/trace.go:171","msg":"trace[2007631905] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"786.625498ms","start":"2026-05-05T10:16:56.914041Z","end":"2026-05-05T10:16:57.700667Z","steps":["trace[2007631905] 'process raft request' (duration: 784.421764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:57.700996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:56.914026Z","time spent":"786.859573ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:16:57.702843Z","caller":"traceutil/trace.go:171","msg":"trace[1487467070] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"777.881576ms","start":"2026-05-05T10:16:56.924522Z","end":"2026-05-05T10:16:57.702404Z","steps":["trace[1487467070] 'process raft request' (duration: 777.589779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:57.702885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.416961ms","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-05T10:16:57.702915Z","caller":"traceutil/trace.go:171","msg":"trace[1124890534] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:527; }","duration":"326.37872ms","start":"2026-05-05T10:16:57.376463Z","end":"2026-05-05T10:16:57.702842Z","steps":["trace[1124890534] 'read index received' (duration: 261.015402ms)","trace[1124890534] 'applied index is now lower than readState.Index' (duration: 64.91601ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:57.702963Z","caller":"traceutil/trace.go:171","msg":"trace[1543988867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"326.515993ms","start":"2026-05-05T10:16:57.376422Z","end":"2026-05-05T10:16:57.702938Z","steps":["trace[1543988867] 'agreement among raft nodes before linearized reading' (duration: 326.35199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:57.703002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:57.376403Z","time spent":"326.591024ms","remote":"127.0.0.1:33304","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-05T10:16:57.70309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:56.924505Z","time spent":"778.432106ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T10:16:58.632294Z","caller":"traceutil/trace.go:171","msg":"trace[28610293] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"200.348278ms","start":"2026-05-05T10:16:58.431917Z","end":"2026-05-05T10:16:58.632265Z","steps":["trace[28610293] 'process raft request' (duration: 115.664376ms)","trace[28610293] 'compare' (duration: 84.548159ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:16:58.639615Z","caller":"traceutil/trace.go:171","msg":"trace[1274098255] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"113.706808ms","start":"2026-05-05T10:16:58.525876Z","end":"2026-05-05T10:16:58.639583Z","steps":["trace[1274098255] 'process raft request' (duration: 113.598026ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:58.639873Z","caller":"traceutil/trace.go:171","msg":"trace[482726274] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"131.160184ms","start":"2026-05-05T10:16:58.508692Z","end":"2026-05-05T10:16:58.639852Z","steps":["trace[482726274] 'process raft request' (duration: 126.126524ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:01.636951Z","caller":"traceutil/trace.go:171","msg":"trace[1372584489] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"198.453341ms","start":"2026-05-05T10:17:01.438474Z","end":"2026-05-05T10:17:01.636927Z","steps":["trace[1372584489] 'process raft request' (duration: 196.809889ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:02.700279Z","caller":"traceutil/trace.go:171","msg":"trace[378344322] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"113.945252ms","start":"2026-05-05T10:17:02.586311Z","end":"2026-05-05T10:17:02.700256Z","steps":["trace[378344322] 'process raft request' (duration: 112.072145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:04.202293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.195939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:17:04.202358Z","caller":"traceutil/trace.go:171","msg":"trace[1383443352] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"210.073791ms","start":"2026-05-05T10:17:03.992274Z","end":"2026-05-05T10:17:04.202348Z","steps":["trace[1383443352] 'read index received' (duration: 104.77658ms)","trace[1383443352] 'applied index is now lower than readState.Index' (duration: 105.296201ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:04.202405Z","caller":"traceutil/trace.go:171","msg":"trace[588569840] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"248.213228ms","start":"2026-05-05T10:17:03.954186Z","end":"2026-05-05T10:17:04.202399Z","steps":["trace[588569840] 'process raft request' (duration: 142.850486ms)","trace[588569840] 'compare' (duration: 104.987045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:04.202524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.256564ms","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-05T10:17:04.202544Z","caller":"traceutil/trace.go:171","msg":"trace[1187020050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"210.310865ms","start":"2026-05-05T10:17:03.992227Z","end":"2026-05-05T10:17:04.202538Z","steps":["trace[1187020050] 'agreement among raft nodes before linearized reading' (duration: 210.236084ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:04.483784Z","caller":"traceutil/trace.go:171","msg":"trace[979232016] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"281.351767ms","start":"2026-05-05T10:17:04.202372Z","end":"2026-05-05T10:17:04.483724Z","steps":["trace[979232016] 'read index received' (duration: 265.89602ms)","trace[979232016] 'applied index is now lower than readState.Index' (duration: 15.454656ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:04.48382Z","caller":"traceutil/trace.go:171","msg":"trace[1180394828] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"419.50478ms","start":"2026-05-05T10:17:04.064283Z","end":"2026-05-05T10:17:04.483787Z","steps":["trace[1180394828] 'process raft request' (duration: 405.071504ms)","trace[1180394828] 'compare' (duration: 13.761813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:04.483929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.064254Z","time spent":"419.617841ms","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:17:04.484123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.739845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:04.484153Z","caller":"traceutil/trace.go:171","msg":"trace[1522524573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"339.797886ms","start":"2026-05-05T10:17:04.144346Z","end":"2026-05-05T10:17:04.484144Z","steps":["trace[1522524573] 'agreement among raft nodes before linearized reading' (duration: 339.748625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:04.484172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.144325Z","time spent":"339.842717ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:17:04.484189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.92914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dn9dk\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-05T10:17:04.484589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.783364ms","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-05T10:17:04.484752Z","caller":"traceutil/trace.go:171","msg":"trace[1108549944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"357.984738ms","start":"2026-05-05T10:17:04.126745Z","end":"2026-05-05T10:17:04.48473Z","steps":["trace[1108549944] 'agreement among raft nodes before linearized reading' (duration: 357.401946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:04.484244Z","caller":"traceutil/trace.go:171","msg":"trace[1706857328] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dn9dk; range_end:; response_count:1; response_revision:579; }","duration":"396.037562ms","start":"2026-05-05T10:17:04.08819Z","end":"2026-05-05T10:17:04.484228Z","steps":["trace[1706857328] 'agreement among raft nodes before linearized reading' (duration: 395.665825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:04.485203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.088167Z","time spent":"396.972472ms","remote":"127.0.0.1:33230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-dn9dk\" "} {"level":"warn","ts":"2026-05-05T10:17:04.484842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.126731Z","time spent":"358.09855ms","remote":"127.0.0.1:33304","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":"info","ts":"2026-05-05T10:17:04.502818Z","caller":"traceutil/trace.go:171","msg":"trace[1842342353] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"296.668311ms","start":"2026-05-05T10:17:04.206132Z","end":"2026-05-05T10:17:04.5028Z","steps":["trace[1842342353] 'process raft request' (duration: 296.567039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:04.991327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.003174ms","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-05T10:17:04.991465Z","caller":"traceutil/trace.go:171","msg":"trace[1897101297] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:580; }","duration":"255.163577ms","start":"2026-05-05T10:17:04.736282Z","end":"2026-05-05T10:17:04.991446Z","steps":["trace[1897101297] 'range keys from in-memory index tree' (duration: 254.982213ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:05.156713Z","caller":"traceutil/trace.go:171","msg":"trace[2086662501] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"667.003424ms","start":"2026-05-05T10:17:04.489682Z","end":"2026-05-05T10:17:05.156686Z","steps":["trace[2086662501] 'process raft request' (duration: 666.448902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:05.156895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.489668Z","time spent":"667.140337ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:05.156943Z","caller":"traceutil/trace.go:171","msg":"trace[1972781720] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"658.26424ms","start":"2026-05-05T10:17:04.498653Z","end":"2026-05-05T10:17:05.156918Z","steps":["trace[1972781720] 'process raft request' (duration: 658.175928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:05.157107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.498636Z","time spent":"658.379442ms","remote":"127.0.0.1:33230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:05.561253Z","caller":"traceutil/trace.go:171","msg":"trace[692807754] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:603; }","duration":"811.626535ms","start":"2026-05-05T10:17:04.749595Z","end":"2026-05-05T10:17:05.561221Z","steps":["trace[692807754] 'read index received' (duration: 406.611592ms)","trace[692807754] 'applied index is now lower than readState.Index' (duration: 405.013893ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:05.561465Z","caller":"traceutil/trace.go:171","msg":"trace[496520731] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.020411831s","start":"2026-05-05T10:17:04.541006Z","end":"2026-05-05T10:17:05.561418Z","steps":["trace[496520731] 'process raft request' (duration: 958.080873ms)","trace[496520731] 'compare' (duration: 61.879108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:05.561515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.909301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:17:05.561554Z","caller":"traceutil/trace.go:171","msg":"trace[1106699213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"812.004563ms","start":"2026-05-05T10:17:04.749538Z","end":"2026-05-05T10:17:05.561543Z","steps":["trace[1106699213] 'agreement among raft nodes before linearized reading' (duration: 811.794809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:05.561594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.749516Z","time spent":"812.069614ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T10:17:05.561611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:04.540982Z","time spent":"1.020564794s","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:17:06.644118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393146614227164,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T10:17:07.137924Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.366757878s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T10:17:07.138517Z","caller":"traceutil/trace.go:171","msg":"trace[1499627003] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.367441022s","start":"2026-05-05T10:17:05.771056Z","end":"2026-05-05T10:17:07.138497Z","steps":["trace[1499627003] 'process raft request' (duration: 1.367246778s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.138669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:05.771041Z","time spent":"1.367536043s","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:07.157798Z","caller":"traceutil/trace.go:171","msg":"trace[1651355159] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.185599171s","start":"2026-05-05T10:17:05.972171Z","end":"2026-05-05T10:17:07.15777Z","steps":["trace[1651355159] 'process raft request' (duration: 1.185037539s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.157984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:05.97215Z","time spent":"1.185748334s","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:07.167553Z","caller":"traceutil/trace.go:171","msg":"trace[115631222] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"1.023968281s","start":"2026-05-05T10:17:06.143568Z","end":"2026-05-05T10:17:07.167536Z","steps":["trace[115631222] 'read index received' (duration: 994.773771ms)","trace[115631222] 'applied index is now lower than readState.Index' (duration: 29.19369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:07.167932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02434666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.167995Z","caller":"traceutil/trace.go:171","msg":"trace[1061210087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.024464331s","start":"2026-05-05T10:17:06.143516Z","end":"2026-05-05T10:17:07.16798Z","steps":["trace[1061210087] 'agreement among raft nodes before linearized reading' (duration: 1.024340729s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:06.143496Z","time spent":"1.024528163s","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:17:07.168079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.712899ms","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-05T10:17:07.16817Z","caller":"traceutil/trace.go:171","msg":"trace[341650254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"660.835452ms","start":"2026-05-05T10:17:06.507316Z","end":"2026-05-05T10:17:07.168152Z","steps":["trace[341650254] 'agreement among raft nodes before linearized reading' (duration: 660.585927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:06.507296Z","time spent":"660.909593ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:17:07.168285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.259139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168315Z","caller":"traceutil/trace.go:171","msg":"trace[1820989894] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"183.313011ms","start":"2026-05-05T10:17:06.984993Z","end":"2026-05-05T10:17:07.168306Z","steps":["trace[1820989894] 'agreement among raft nodes before linearized reading' (duration: 183.26557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.063906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:17:07.168445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.848075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168536Z","caller":"traceutil/trace.go:171","msg":"trace[1519669027] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"289.999779ms","start":"2026-05-05T10:17:06.878527Z","end":"2026-05-05T10:17:07.168527Z","steps":["trace[1519669027] 'agreement among raft nodes before linearized reading' (duration: 289.852435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.16855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.958539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168634Z","caller":"traceutil/trace.go:171","msg":"trace[1795015082] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"208.097702ms","start":"2026-05-05T10:17:06.960517Z","end":"2026-05-05T10:17:07.168615Z","steps":["trace[1795015082] 'agreement among raft nodes before linearized reading' (duration: 207.937208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.908646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:17:07.168731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.605968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168824Z","caller":"traceutil/trace.go:171","msg":"trace[689038426] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:586; }","duration":"240.7436ms","start":"2026-05-05T10:17:06.928016Z","end":"2026-05-05T10:17:07.16876Z","steps":["trace[689038426] 'agreement among raft nodes before linearized reading' (duration: 240.591287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.614067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168888Z","caller":"traceutil/trace.go:171","msg":"trace[1130799764] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:586; }","duration":"241.685159ms","start":"2026-05-05T10:17:06.927188Z","end":"2026-05-05T10:17:07.168873Z","steps":["trace[1130799764] 'agreement among raft nodes before linearized reading' (duration: 241.601368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.137244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168944Z","caller":"traceutil/trace.go:171","msg":"trace[1207507378] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"134.188675ms","start":"2026-05-05T10:17:07.034747Z","end":"2026-05-05T10:17:07.168936Z","steps":["trace[1207507378] 'agreement among raft nodes before linearized reading' (duration: 134.121023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.934215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168986Z","caller":"traceutil/trace.go:171","msg":"trace[779443091] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"306.007656ms","start":"2026-05-05T10:17:06.862965Z","end":"2026-05-05T10:17:07.168972Z","steps":["trace[779443091] 'agreement among raft nodes before linearized reading' (duration: 305.938225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:06.862949Z","time spent":"306.060237ms","remote":"127.0.0.1:45782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-05T10:17:07.169067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.383618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168723Z","caller":"traceutil/trace.go:171","msg":"trace[1489142751] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"290.155742ms","start":"2026-05-05T10:17:06.878536Z","end":"2026-05-05T10:17:07.168692Z","steps":["trace[1489142751] 'agreement among raft nodes before linearized reading' (duration: 289.824955ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:07.169093Z","caller":"traceutil/trace.go:171","msg":"trace[1885833363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"134.419819ms","start":"2026-05-05T10:17:07.034665Z","end":"2026-05-05T10:17:07.169085Z","steps":["trace[1885833363] 'agreement among raft nodes before linearized reading' (duration: 134.382369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.22051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:17:07.1692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.539342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.169223Z","caller":"traceutil/trace.go:171","msg":"trace[1741984781] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"134.585152ms","start":"2026-05-05T10:17:07.034632Z","end":"2026-05-05T10:17:07.169217Z","steps":["trace[1741984781] 'agreement among raft nodes before linearized reading' (duration: 134.529301ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:07.169212Z","caller":"traceutil/trace.go:171","msg":"trace[643096682] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"307.299771ms","start":"2026-05-05T10:17:06.861903Z","end":"2026-05-05T10:17:07.169203Z","steps":["trace[643096682] 'agreement among raft nodes before linearized reading' (duration: 307.226169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:06.861886Z","time spent":"307.390783ms","remote":"127.0.0.1:45782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T10:17:07.169334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.584085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.169364Z","caller":"traceutil/trace.go:171","msg":"trace[1872130377] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"184.638507ms","start":"2026-05-05T10:17:06.984719Z","end":"2026-05-05T10:17:07.169357Z","steps":["trace[1872130377] 'agreement among raft nodes before linearized reading' (duration: 184.592266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.280307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.168479Z","caller":"traceutil/trace.go:171","msg":"trace[138506446] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:586; }","duration":"241.279752ms","start":"2026-05-05T10:17:06.927188Z","end":"2026-05-05T10:17:07.168468Z","steps":["trace[138506446] 'agreement among raft nodes before linearized reading' (duration: 240.934934ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:07.169403Z","caller":"traceutil/trace.go:171","msg":"trace[1357823070] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"265.354998ms","start":"2026-05-05T10:17:06.904033Z","end":"2026-05-05T10:17:07.169388Z","steps":["trace[1357823070] 'agreement among raft nodes before linearized reading' (duration: 265.266747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.168544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.582728ms","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":"info","ts":"2026-05-05T10:17:07.169511Z","caller":"traceutil/trace.go:171","msg":"trace[1240367599] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"307.585206ms","start":"2026-05-05T10:17:06.861913Z","end":"2026-05-05T10:17:07.169499Z","steps":["trace[1240367599] 'agreement among raft nodes before linearized reading' (duration: 306.563287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:06.861906Z","time spent":"307.650277ms","remote":"127.0.0.1:45782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:17:07.169589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.891631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.169619Z","caller":"traceutil/trace.go:171","msg":"trace[427340420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"184.925612ms","start":"2026-05-05T10:17:06.984687Z","end":"2026-05-05T10:17:07.169613Z","steps":["trace[427340420] 'agreement among raft nodes before linearized reading' (duration: 184.880871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.642661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.169646Z","caller":"traceutil/trace.go:171","msg":"trace[1370710516] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"290.679241ms","start":"2026-05-05T10:17:06.878961Z","end":"2026-05-05T10:17:07.16964Z","steps":["trace[1370710516] 'agreement among raft nodes before linearized reading' (duration: 290.64482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.206525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.169727Z","caller":"traceutil/trace.go:171","msg":"trace[1947987047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"266.256767ms","start":"2026-05-05T10:17:06.903465Z","end":"2026-05-05T10:17:07.169722Z","steps":["trace[1947987047] 'agreement among raft nodes before linearized reading' (duration: 266.227416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.169754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.311777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.169778Z","caller":"traceutil/trace.go:171","msg":"trace[727601873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"266.351259ms","start":"2026-05-05T10:17:06.90342Z","end":"2026-05-05T10:17:07.169771Z","steps":["trace[727601873] 'agreement among raft nodes before linearized reading' (duration: 266.316678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:07.170022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.376038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:07.170092Z","caller":"traceutil/trace.go:171","msg":"trace[196659687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"209.463829ms","start":"2026-05-05T10:17:06.960609Z","end":"2026-05-05T10:17:07.170072Z","steps":["trace[196659687] 'agreement among raft nodes before linearized reading' (duration: 209.358237ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:09.291739Z","caller":"traceutil/trace.go:171","msg":"trace[318317440] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"148.256444ms","start":"2026-05-05T10:17:09.143461Z","end":"2026-05-05T10:17:09.291717Z","steps":["trace[318317440] 'read index received' (duration: 1.801695ms)","trace[318317440] 'applied index is now lower than readState.Index' (duration: 146.453839ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:09.29184Z","caller":"traceutil/trace.go:171","msg":"trace[1935570535] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"170.974404ms","start":"2026-05-05T10:17:09.120821Z","end":"2026-05-05T10:17:09.291795Z","steps":["trace[1935570535] 'process raft request' (duration: 103.353552ms)","trace[1935570535] 'compare' (duration: 67.405008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:09.291912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.433967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:09.291959Z","caller":"traceutil/trace.go:171","msg":"trace[449172339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"148.531839ms","start":"2026-05-05T10:17:09.143416Z","end":"2026-05-05T10:17:09.291948Z","steps":["trace[449172339] 'agreement among raft nodes before linearized reading' (duration: 148.427687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:09.292108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.126572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T10:17:09.292179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.712468ms","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-05T10:17:09.292203Z","caller":"traceutil/trace.go:171","msg":"trace[1048394055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"148.272844ms","start":"2026-05-05T10:17:09.143906Z","end":"2026-05-05T10:17:09.292179Z","steps":["trace[1048394055] 'agreement among raft nodes before linearized reading' (duration: 148.028309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:09.292268Z","caller":"traceutil/trace.go:171","msg":"trace[1221019373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"108.858971ms","start":"2026-05-05T10:17:09.183387Z","end":"2026-05-05T10:17:09.292246Z","steps":["trace[1221019373] 'agreement among raft nodes before linearized reading' (duration: 108.615996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:09.29226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.699048ms","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-05T10:17:09.29237Z","caller":"traceutil/trace.go:171","msg":"trace[1080222182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"104.849771ms","start":"2026-05-05T10:17:09.187497Z","end":"2026-05-05T10:17:09.292346Z","steps":["trace[1080222182] 'agreement among raft nodes before linearized reading' (duration: 104.515094ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:20.397385Z","caller":"traceutil/trace.go:171","msg":"trace[666769163] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"253.764218ms","start":"2026-05-05T10:17:20.143598Z","end":"2026-05-05T10:17:20.397362Z","steps":["trace[666769163] 'read index received' (duration: 253.603255ms)","trace[666769163] 'applied index is now lower than readState.Index' (duration: 159.923µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:20.397498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.890341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:20.397528Z","caller":"traceutil/trace.go:171","msg":"trace[1050697457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"253.976333ms","start":"2026-05-05T10:17:20.143543Z","end":"2026-05-05T10:17:20.397519Z","steps":["trace[1050697457] 'agreement among raft nodes before linearized reading' (duration: 253.909391ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:20.397705Z","caller":"traceutil/trace.go:171","msg":"trace[426930175] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"434.282653ms","start":"2026-05-05T10:17:19.963384Z","end":"2026-05-05T10:17:20.397667Z","steps":["trace[426930175] 'process raft request' (duration: 433.810753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:20.39789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:19.963359Z","time spent":"434.427965ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:23.674943Z","caller":"traceutil/trace.go:171","msg":"trace[307527390] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"307.18471ms","start":"2026-05-05T10:17:23.367737Z","end":"2026-05-05T10:17:23.674922Z","steps":["trace[307527390] 'read index received' (duration: 307.013336ms)","trace[307527390] 'applied index is now lower than readState.Index' (duration: 170.534µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:23.674988Z","caller":"traceutil/trace.go:171","msg":"trace[1064647424] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"465.502423ms","start":"2026-05-05T10:17:23.209463Z","end":"2026-05-05T10:17:23.674966Z","steps":["trace[1064647424] 'process raft request' (duration: 465.32104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:23.675112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.365513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T10:17:23.675123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:23.209444Z","time spent":"465.596685ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:23.67514Z","caller":"traceutil/trace.go:171","msg":"trace[1616118671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"307.427225ms","start":"2026-05-05T10:17:23.367705Z","end":"2026-05-05T10:17:23.675132Z","steps":["trace[1616118671] 'agreement among raft nodes before linearized reading' (duration: 307.317382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:23.675163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:23.367689Z","time spent":"307.468505ms","remote":"127.0.0.1:33304","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-05T10:17:23.675384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.070216ms","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-05T10:17:23.675518Z","caller":"traceutil/trace.go:171","msg":"trace[1886309364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"307.22895ms","start":"2026-05-05T10:17:23.36827Z","end":"2026-05-05T10:17:23.675499Z","steps":["trace[1886309364] 'agreement among raft nodes before linearized reading' (duration: 306.823432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:23.675564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:23.368262Z","time spent":"307.290901ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:17:23.67541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.668519ms","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-05T10:17:23.675622Z","caller":"traceutil/trace.go:171","msg":"trace[2141389703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"306.880303ms","start":"2026-05-05T10:17:23.368721Z","end":"2026-05-05T10:17:23.675601Z","steps":["trace[2141389703] 'agreement among raft nodes before linearized reading' (duration: 306.631458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:23.675658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:23.368714Z","time spent":"306.934683ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:17:36.513895Z","caller":"traceutil/trace.go:171","msg":"trace[1090111992] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"135.278419ms","start":"2026-05-05T10:17:36.37859Z","end":"2026-05-05T10:17:36.513868Z","steps":["trace[1090111992] 'process raft request' (duration: 135.110265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:59.672373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.313025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:59.672465Z","caller":"traceutil/trace.go:171","msg":"trace[402700350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"528.414587ms","start":"2026-05-05T10:17:59.144032Z","end":"2026-05-05T10:17:59.672447Z","steps":["trace[402700350] 'range keys from in-memory index tree' (duration: 528.238293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:59.672504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.143986Z","time spent":"528.51074ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:17:59.673023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.75126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:17:59.673103Z","caller":"traceutil/trace.go:171","msg":"trace[1956299918] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:969; }","duration":"616.903963ms","start":"2026-05-05T10:17:59.05618Z","end":"2026-05-05T10:17:59.673084Z","steps":["trace[1956299918] 'count revisions from in-memory index tree' (duration: 616.694809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:59.673147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.056158Z","time spent":"616.978506ms","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:18:00.448512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393146614227989,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T10:18:00.508826Z","caller":"traceutil/trace.go:171","msg":"trace[1722565957] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"616.450653ms","start":"2026-05-05T10:17:59.892354Z","end":"2026-05-05T10:18:00.508805Z","steps":["trace[1722565957] 'process raft request' (duration: 616.209177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:00.508927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.892337Z","time spent":"616.531485ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:00.509131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.635046ms","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-05T10:18:00.509187Z","caller":"traceutil/trace.go:171","msg":"trace[701969120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"561.738999ms","start":"2026-05-05T10:17:59.947434Z","end":"2026-05-05T10:18:00.509173Z","steps":["trace[701969120] 'agreement among raft nodes before linearized reading' (duration: 561.549854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:00.509227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.947411Z","time spent":"561.806741ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T10:18:00.508955Z","caller":"traceutil/trace.go:171","msg":"trace[1980070484] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"561.212947ms","start":"2026-05-05T10:17:59.947497Z","end":"2026-05-05T10:18:00.50871Z","steps":["trace[1980070484] 'read index received' (duration: 560.959441ms)","trace[1980070484] 'applied index is now lower than readState.Index' (duration: 252.586µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:00.509502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.406532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:18:00.509536Z","caller":"traceutil/trace.go:171","msg":"trace[1207527696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"509.461644ms","start":"2026-05-05T10:18:00.000064Z","end":"2026-05-05T10:18:00.509526Z","steps":["trace[1207527696] 'agreement among raft nodes before linearized reading' (duration: 509.374401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:00.509564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.999882Z","time spent":"509.675669ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T10:18:00.509558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.747201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T10:18:00.5096Z","caller":"traceutil/trace.go:171","msg":"trace[1361515422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"518.831374ms","start":"2026-05-05T10:17:59.990757Z","end":"2026-05-05T10:18:00.509589Z","steps":["trace[1361515422] 'agreement among raft nodes before linearized reading' (duration: 518.673819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:00.509601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.552386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T10:18:00.509641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.990744Z","time spent":"518.888945ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T10:18:00.509655Z","caller":"traceutil/trace.go:171","msg":"trace[1434836021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"509.657109ms","start":"2026-05-05T10:17:59.999982Z","end":"2026-05-05T10:18:00.509639Z","steps":["trace[1434836021] 'agreement among raft nodes before linearized reading' (duration: 509.496334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:00.509689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:59.999961Z","time spent":"509.723321ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T10:18:00.695488Z","caller":"traceutil/trace.go:171","msg":"trace[104334510] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"183.246068ms","start":"2026-05-05T10:18:00.512217Z","end":"2026-05-05T10:18:00.695463Z","steps":["trace[104334510] 'process raft request' (duration: 183.070444ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:00.698028Z","caller":"traceutil/trace.go:171","msg":"trace[1344944864] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"180.924432ms","start":"2026-05-05T10:18:00.517088Z","end":"2026-05-05T10:18:00.698013Z","steps":["trace[1344944864] 'process raft request' (duration: 180.893131ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:00.698276Z","caller":"traceutil/trace.go:171","msg":"trace[1815076592] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"185.164475ms","start":"2026-05-05T10:18:00.513071Z","end":"2026-05-05T10:18:00.698236Z","steps":["trace[1815076592] 'process raft request' (duration: 184.879008ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:00.698478Z","caller":"traceutil/trace.go:171","msg":"trace[2068750652] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"185.417432ms","start":"2026-05-05T10:18:00.51305Z","end":"2026-05-05T10:18:00.698468Z","steps":["trace[2068750652] 'process raft request' (duration: 184.834968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:21.231175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.116473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:18:21.231427Z","caller":"traceutil/trace.go:171","msg":"trace[84773005] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"395.867099ms","start":"2026-05-05T10:18:20.835528Z","end":"2026-05-05T10:18:21.231395Z","steps":["trace[84773005] 'process raft request' (duration: 395.741567ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:21.231461Z","caller":"traceutil/trace.go:171","msg":"trace[221647008] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"396.304618ms","start":"2026-05-05T10:18:20.835104Z","end":"2026-05-05T10:18:21.231409Z","steps":["trace[221647008] 'process raft request' (duration: 166.767448ms)","trace[221647008] 'compare' (duration: 228.882008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:21.231585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:20.835517Z","time spent":"395.986411ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:21.23162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:20.835082Z","time spent":"396.458962ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:21.547915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.432243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:18:21.548018Z","caller":"traceutil/trace.go:171","msg":"trace[350247189] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"115.112822ms","start":"2026-05-05T10:18:21.43289Z","end":"2026-05-05T10:18:21.548003Z","steps":["trace[350247189] 'read index received' (duration: 9.424544ms)","trace[350247189] 'applied index is now lower than readState.Index' (duration: 105.687258ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:21.548106Z","caller":"traceutil/trace.go:171","msg":"trace[1232238050] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"192.982009ms","start":"2026-05-05T10:18:21.355091Z","end":"2026-05-05T10:18:21.548073Z","steps":["trace[1232238050] 'process raft request' (duration: 87.28794ms)","trace[1232238050] 'compare' (duration: 105.230859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:21.548158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.275716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T10:18:21.548195Z","caller":"traceutil/trace.go:171","msg":"trace[863974265] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1042; }","duration":"115.359148ms","start":"2026-05-05T10:18:21.432827Z","end":"2026-05-05T10:18:21.548186Z","steps":["trace[863974265] 'agreement among raft nodes before linearized reading' (duration: 115.218485ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:23.001889Z","caller":"traceutil/trace.go:171","msg":"trace[590780000] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"151.474881ms","start":"2026-05-05T10:18:22.850387Z","end":"2026-05-05T10:18:23.001862Z","steps":["trace[590780000] 'process raft request' (duration: 151.297048ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:23.012651Z","caller":"traceutil/trace.go:171","msg":"trace[1072268054] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"162.227721ms","start":"2026-05-05T10:18:22.850396Z","end":"2026-05-05T10:18:23.012624Z","steps":["trace[1072268054] 'process raft request' (duration: 162.085388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:35.076892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.700729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:35.076959Z","caller":"traceutil/trace.go:171","msg":"trace[923188222] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1134; }","duration":"217.807381ms","start":"2026-05-05T10:18:34.859138Z","end":"2026-05-05T10:18:35.076945Z","steps":["trace[923188222] 'count revisions from in-memory index tree' (duration: 217.640768ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:35.077464Z","caller":"traceutil/trace.go:171","msg":"trace[758610118] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"192.53779ms","start":"2026-05-05T10:18:34.884917Z","end":"2026-05-05T10:18:35.077454Z","steps":["trace[758610118] 'process raft request' (duration: 160.948245ms)","trace[758610118] 'compare' (duration: 31.411561ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:35.219897Z","caller":"traceutil/trace.go:171","msg":"trace[996759275] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"139.21093ms","start":"2026-05-05T10:18:35.080628Z","end":"2026-05-05T10:18:35.219839Z","steps":["trace[996759275] 'process raft request' (duration: 139.16286ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:35.219922Z","caller":"traceutil/trace.go:171","msg":"trace[1167591521] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"139.769252ms","start":"2026-05-05T10:18:35.08012Z","end":"2026-05-05T10:18:35.21989Z","steps":["trace[1167591521] 'process raft request' (duration: 133.876348ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:36.649598Z","caller":"traceutil/trace.go:171","msg":"trace[1349694801] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"327.250282ms","start":"2026-05-05T10:18:36.322328Z","end":"2026-05-05T10:18:36.649579Z","steps":["trace[1349694801] 'process raft request' (duration: 327.12311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:36.649733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:36.322306Z","time spent":"327.351704ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:51.644292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.801508ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-05T10:18:51.6444Z","caller":"traceutil/trace.go:171","msg":"trace[142809088] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"144.099661ms","start":"2026-05-05T10:18:51.500283Z","end":"2026-05-05T10:18:51.644383Z","steps":["trace[142809088] 'read index received' (duration: 54.991µs)","trace[142809088] 'applied index is now lower than readState.Index' (duration: 144.043319ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:51.64464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.349615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q6cvb\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-05-05T10:18:51.644687Z","caller":"traceutil/trace.go:171","msg":"trace[1548916042] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q6cvb; range_end:; response_count:1; response_revision:1205; }","duration":"144.440027ms","start":"2026-05-05T10:18:51.500232Z","end":"2026-05-05T10:18:51.644672Z","steps":["trace[1548916042] 'agreement among raft nodes before linearized reading' (duration: 144.205433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:52.365435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.457793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:52.365515Z","caller":"traceutil/trace.go:171","msg":"trace[1993509920] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1211; }","duration":"130.595296ms","start":"2026-05-05T10:18:52.234899Z","end":"2026-05-05T10:18:52.365494Z","steps":["trace[1993509920] 'count revisions from in-memory index tree' (duration: 130.344961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:52.365664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.501599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-05-05T10:18:52.365792Z","caller":"traceutil/trace.go:171","msg":"trace[1295599734] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1211; }","duration":"211.696973ms","start":"2026-05-05T10:18:52.154063Z","end":"2026-05-05T10:18:52.36576Z","steps":["trace[1295599734] 'range keys from in-memory index tree' (duration: 211.313166ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:57.208671Z","caller":"traceutil/trace.go:171","msg":"trace[1373045736] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"247.622606ms","start":"2026-05-05T10:18:56.961024Z","end":"2026-05-05T10:18:57.208647Z","steps":["trace[1373045736] 'process raft request' (duration: 247.490684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.713527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393146614228614,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T10:18:57.972477Z","caller":"traceutil/trace.go:171","msg":"trace[1151575308] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1295; }","duration":"759.912413ms","start":"2026-05-05T10:18:57.21255Z","end":"2026-05-05T10:18:57.972462Z","steps":["trace[1151575308] 'read index received' (duration: 704.114271ms)","trace[1151575308] 'applied index is now lower than readState.Index' (duration: 55.797572ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:18:57.972553Z","caller":"traceutil/trace.go:171","msg":"trace[1397918596] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"759.071657ms","start":"2026-05-05T10:18:57.213476Z","end":"2026-05-05T10:18:57.972548Z","steps":["trace[1397918596] 'process raft request' (duration: 758.944814ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:57.972544Z","caller":"traceutil/trace.go:171","msg":"trace[284629366] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"872.332593ms","start":"2026-05-05T10:18:57.100189Z","end":"2026-05-05T10:18:57.972521Z","steps":["trace[284629366] 'process raft request' (duration: 872.185369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.972614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.213461Z","time spent":"759.106238ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:18:57.972642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.100167Z","time spent":"872.419444ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:18:57.972755Z","caller":"traceutil/trace.go:171","msg":"trace[1321260955] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"669.470803ms","start":"2026-05-05T10:18:57.303278Z","end":"2026-05-05T10:18:57.972749Z","steps":["trace[1321260955] 'process raft request' (duration: 669.162877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.972814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.303264Z","time spent":"669.528064ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:57.972912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.362841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-05T10:18:57.972972Z","caller":"traceutil/trace.go:171","msg":"trace[1558714214] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1247; }","duration":"760.465783ms","start":"2026-05-05T10:18:57.212501Z","end":"2026-05-05T10:18:57.972967Z","steps":["trace[1558714214] 'agreement among raft nodes before linearized reading' (duration: 760.347201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.972989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.212485Z","time spent":"760.501425ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T10:18:57.973052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.578678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:18:57.97307Z","caller":"traceutil/trace.go:171","msg":"trace[1868910072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"569.633849ms","start":"2026-05-05T10:18:57.403432Z","end":"2026-05-05T10:18:57.973066Z","steps":["trace[1868910072] 'agreement among raft nodes before linearized reading' (duration: 569.591798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.973081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.403375Z","time spent":"569.70422ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T10:18:57.972485Z","caller":"traceutil/trace.go:171","msg":"trace[1609087399] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"988.022808ms","start":"2026-05-05T10:18:56.984405Z","end":"2026-05-05T10:18:57.972428Z","steps":["trace[1609087399] 'process raft request' (duration: 932.293707ms)","trace[1609087399] 'compare' (duration: 55.065747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:57.973173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.553967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-05T10:18:57.97319Z","caller":"traceutil/trace.go:171","msg":"trace[758511561] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1247; }","duration":"381.603769ms","start":"2026-05-05T10:18:57.591583Z","end":"2026-05-05T10:18:57.973187Z","steps":["trace[758511561] 'agreement among raft nodes before linearized reading' (duration: 381.561039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.973203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.591567Z","time spent":"381.63339ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T10:18:57.973246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.883909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:18:57.973263Z","caller":"traceutil/trace.go:171","msg":"trace[2065987060] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1247; }","duration":"414.942031ms","start":"2026-05-05T10:18:57.558316Z","end":"2026-05-05T10:18:57.973258Z","steps":["trace[2065987060] 'agreement among raft nodes before linearized reading' (duration: 414.91735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.973276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.558293Z","time spent":"414.978961ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:18:57.973529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.572419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T10:18:57.973546Z","caller":"traceutil/trace.go:171","msg":"trace[713118791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"472.626439ms","start":"2026-05-05T10:18:57.500916Z","end":"2026-05-05T10:18:57.973542Z","steps":["trace[713118791] 'agreement among raft nodes before linearized reading' (duration: 472.587008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.973558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.500901Z","time spent":"472.65443ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T10:18:57.973516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:56.984378Z","time spent":"988.763971ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:18:57.973607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.81188ms","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-05T10:18:57.973622Z","caller":"traceutil/trace.go:171","msg":"trace[870456030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"481.867471ms","start":"2026-05-05T10:18:57.491751Z","end":"2026-05-05T10:18:57.973618Z","steps":["trace[870456030] 'agreement among raft nodes before linearized reading' (duration: 481.83249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.973635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.491732Z","time spent":"481.899122ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:18:57.973686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.18658ms","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-05T10:18:57.973701Z","caller":"traceutil/trace.go:171","msg":"trace[1615104032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"570.23915ms","start":"2026-05-05T10:18:57.403458Z","end":"2026-05-05T10:18:57.973697Z","steps":["trace[1615104032] 'agreement among raft nodes before linearized reading' (duration: 570.204619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:57.973712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.403437Z","time spent":"570.272791ms","remote":"127.0.0.1:33304","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-05T10:18:58.490302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.72079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:18:58.490478Z","caller":"traceutil/trace.go:171","msg":"trace[2054501369] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"511.857877ms","start":"2026-05-05T10:18:57.978581Z","end":"2026-05-05T10:18:58.490439Z","steps":["trace[2054501369] 'process raft request' (duration: 175.920864ms)","trace[2054501369] 'compare' (duration: 335.594687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:58.490563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.978565Z","time spent":"511.96566ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:18:58.490858Z","caller":"traceutil/trace.go:171","msg":"trace[1332744537] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"511.96545ms","start":"2026-05-05T10:18:57.978862Z","end":"2026-05-05T10:18:58.490828Z","steps":["trace[1332744537] 'process raft request' (duration: 511.530831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:58.490987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.978841Z","time spent":"512.072492ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:18:58.490999Z","caller":"traceutil/trace.go:171","msg":"trace[766610680] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"511.587824ms","start":"2026-05-05T10:18:57.979385Z","end":"2026-05-05T10:18:58.490973Z","steps":["trace[766610680] 'process raft request' (duration: 511.238607ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:18:58.490984Z","caller":"traceutil/trace.go:171","msg":"trace[1158726877] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"511.337477ms","start":"2026-05-05T10:18:57.97961Z","end":"2026-05-05T10:18:58.490947Z","steps":["trace[1158726877] 'process raft request' (duration: 511.166344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:58.49111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.039992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:18:58.491113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.979377Z","time spent":"511.676855ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:18:58.491146Z","caller":"traceutil/trace.go:171","msg":"trace[1962816236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"347.102274ms","start":"2026-05-05T10:18:58.144034Z","end":"2026-05-05T10:18:58.491136Z","steps":["trace[1962816236] 'agreement among raft nodes before linearized reading' (duration: 347.036242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:58.491175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:58.144018Z","time spent":"347.150214ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T10:18:58.491218Z","caller":"traceutil/trace.go:171","msg":"trace[604337591] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"511.532061ms","start":"2026-05-05T10:18:57.979675Z","end":"2026-05-05T10:18:58.491207Z","steps":["trace[604337591] 'process raft request' (duration: 511.206535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:58.491252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.979671Z","time spent":"511.561031ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:18:58.491236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.979603Z","time spent":"511.48949ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:18:58.491322Z","caller":"traceutil/trace.go:171","msg":"trace[1235193009] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"510.158014ms","start":"2026-05-05T10:18:57.981155Z","end":"2026-05-05T10:18:58.491313Z","steps":["trace[1235193009] 'process raft request' (duration: 509.818058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:58.491368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:57.981148Z","time spent":"510.196765ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T10:18:58.491042Z","caller":"traceutil/trace.go:171","msg":"trace[1710148480] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1300; }","duration":"346.957841ms","start":"2026-05-05T10:18:58.144072Z","end":"2026-05-05T10:18:58.49103Z","steps":["trace[1710148480] 'read index received' (duration: 10.445575ms)","trace[1710148480] 'applied index is now lower than readState.Index' (duration: 336.511296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:18:58.491597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.842928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:18:58.49165Z","caller":"traceutil/trace.go:171","msg":"trace[569763517] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1254; }","duration":"322.92869ms","start":"2026-05-05T10:18:58.168701Z","end":"2026-05-05T10:18:58.49163Z","steps":["trace[569763517] 'agreement among raft nodes before linearized reading' (duration: 322.841848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:18:58.491677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:18:58.16868Z","time spent":"322.991071ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-05T10:19:06.257477Z","caller":"traceutil/trace.go:171","msg":"trace[150754220] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"262.56847ms","start":"2026-05-05T10:19:05.994888Z","end":"2026-05-05T10:19:06.257457Z","steps":["trace[150754220] 'process raft request' (duration: 262.443477ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:06.257652Z","caller":"traceutil/trace.go:171","msg":"trace[756922016] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1356; }","duration":"180.073305ms","start":"2026-05-05T10:19:06.077548Z","end":"2026-05-05T10:19:06.257621Z","steps":["trace[756922016] 'read index received' (duration: 179.769439ms)","trace[756922016] 'applied index is now lower than readState.Index' (duration: 301.776µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:06.25783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.578833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:19:06.257871Z","caller":"traceutil/trace.go:171","msg":"trace[2041484171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"113.670835ms","start":"2026-05-05T10:19:06.144188Z","end":"2026-05-05T10:19:06.257859Z","steps":["trace[2041484171] 'agreement among raft nodes before linearized reading' (duration: 113.575263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:06.258111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.488982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T10:19:06.258213Z","caller":"traceutil/trace.go:171","msg":"trace[1483451000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"180.694676ms","start":"2026-05-05T10:19:06.077494Z","end":"2026-05-05T10:19:06.258189Z","steps":["trace[1483451000] 'agreement among raft nodes before linearized reading' (duration: 180.189587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.122303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.993138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:19:07.12265Z","caller":"traceutil/trace.go:171","msg":"trace[366534086] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"860.38142ms","start":"2026-05-05T10:19:06.262236Z","end":"2026-05-05T10:19:07.122618Z","steps":["trace[366534086] 'process raft request' (duration: 692.989903ms)","trace[366534086] 'compare' (duration: 166.732913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:07.122801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:06.262216Z","time spent":"860.504983ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:07.123042Z","caller":"traceutil/trace.go:171","msg":"trace[1479934628] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"860.458501ms","start":"2026-05-05T10:19:06.262563Z","end":"2026-05-05T10:19:07.123021Z","steps":["trace[1479934628] 'process raft request' (duration: 859.86236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.123135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:06.262542Z","time spent":"860.549214ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:19:07.130216Z","caller":"traceutil/trace.go:171","msg":"trace[193942073] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1356; }","duration":"607.697764ms","start":"2026-05-05T10:19:06.522506Z","end":"2026-05-05T10:19:07.130204Z","steps":["trace[193942073] 'read index received' (duration: 432.705809ms)","trace[193942073] 'applied index is now lower than readState.Index' (duration: 174.990975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:19:07.130357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.024298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:19:07.130395Z","caller":"traceutil/trace.go:171","msg":"trace[1687345535] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1305; }","duration":"497.119599ms","start":"2026-05-05T10:19:06.633267Z","end":"2026-05-05T10:19:07.130387Z","steps":["trace[1687345535] 'agreement among raft nodes before linearized reading' (duration: 497.033168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.130394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.249145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T10:19:07.130414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:06.633245Z","time spent":"497.164651ms","remote":"127.0.0.1:33570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-05T10:19:07.130415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.891607ms","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-05T10:19:07.130431Z","caller":"traceutil/trace.go:171","msg":"trace[547952808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"606.322577ms","start":"2026-05-05T10:19:06.524099Z","end":"2026-05-05T10:19:07.130421Z","steps":["trace[547952808] 'agreement among raft nodes before linearized reading' (duration: 606.200075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.130458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:06.524078Z","time spent":"606.374598ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T10:19:07.130469Z","caller":"traceutil/trace.go:171","msg":"trace[796584697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"607.98142ms","start":"2026-05-05T10:19:06.522465Z","end":"2026-05-05T10:19:07.130447Z","steps":["trace[796584697] 'agreement among raft nodes before linearized reading' (duration: 607.794076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.13052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:06.522451Z","time spent":"608.062221ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:19:07.130537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.082646ms","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-05T10:19:07.130604Z","caller":"traceutil/trace.go:171","msg":"trace[1335868738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"273.191847ms","start":"2026-05-05T10:19:06.857384Z","end":"2026-05-05T10:19:07.130575Z","steps":["trace[1335868738] 'agreement among raft nodes before linearized reading' (duration: 273.005133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.130688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.507051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T10:19:07.130715Z","caller":"traceutil/trace.go:171","msg":"trace[1440407275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1305; }","duration":"606.548242ms","start":"2026-05-05T10:19:06.524159Z","end":"2026-05-05T10:19:07.130708Z","steps":["trace[1440407275] 'agreement among raft nodes before linearized reading' (duration: 606.227095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:07.130747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:06.524155Z","time spent":"606.585913ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T10:19:44.692628Z","caller":"traceutil/trace.go:171","msg":"trace[1802389975] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"264.217861ms","start":"2026-05-05T10:19:44.428384Z","end":"2026-05-05T10:19:44.692602Z","steps":["trace[1802389975] 'process raft request' (duration: 264.066988ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:45.644661Z","caller":"traceutil/trace.go:171","msg":"trace[2030422858] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"183.846615ms","start":"2026-05-05T10:19:45.460786Z","end":"2026-05-05T10:19:45.644633Z","steps":["trace[2030422858] 'process raft request' (duration: 115.49282ms)","trace[2030422858] 'compare' (duration: 68.155691ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:45.644924Z","caller":"traceutil/trace.go:171","msg":"trace[1785404226] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"183.007259ms","start":"2026-05-05T10:19:45.461594Z","end":"2026-05-05T10:19:45.644602Z","steps":["trace[1785404226] 'process raft request' (duration: 182.963438ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:47.634798Z","caller":"traceutil/trace.go:171","msg":"trace[625283538] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"128.47416ms","start":"2026-05-05T10:19:47.5063Z","end":"2026-05-05T10:19:47.634774Z","steps":["trace[625283538] 'process raft request' (duration: 127.336088ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:47.634961Z","caller":"traceutil/trace.go:171","msg":"trace[634087588] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"128.615513ms","start":"2026-05-05T10:19:47.506314Z","end":"2026-05-05T10:19:47.63493Z","steps":["trace[634087588] 'process raft request' (duration: 128.419259ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:57.659992Z","caller":"traceutil/trace.go:171","msg":"trace[1948535495] linearizableReadLoop","detail":"{readStateIndex:1598; appliedIndex:1597; }","duration":"102.832707ms","start":"2026-05-05T10:19:57.557144Z","end":"2026-05-05T10:19:57.659977Z","steps":["trace[1948535495] 'read index received' (duration: 102.639853ms)","trace[1948535495] 'applied index is now lower than readState.Index' (duration: 192.274µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:57.660066Z","caller":"traceutil/trace.go:171","msg":"trace[1539651436] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"157.926275ms","start":"2026-05-05T10:19:57.5021Z","end":"2026-05-05T10:19:57.660026Z","steps":["trace[1539651436] 'process raft request' (duration: 157.766733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.660126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.97611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-05T10:19:57.660146Z","caller":"traceutil/trace.go:171","msg":"trace[509532211] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1534; }","duration":"103.049152ms","start":"2026-05-05T10:19:57.557091Z","end":"2026-05-05T10:19:57.66014Z","steps":["trace[509532211] 'agreement among raft nodes before linearized reading' (duration: 102.946409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:57.861098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.292694ms","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-05T10:19:57.861231Z","caller":"traceutil/trace.go:171","msg":"trace[713181668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1535; }","duration":"118.486019ms","start":"2026-05-05T10:19:57.742729Z","end":"2026-05-05T10:19:57.861215Z","steps":["trace[713181668] 'range keys from in-memory index tree' (duration: 117.603182ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:20:06.112569Z","caller":"traceutil/trace.go:171","msg":"trace[1897254722] linearizableReadLoop","detail":"{readStateIndex:1641; appliedIndex:1639; }","duration":"118.08939ms","start":"2026-05-05T10:20:05.99446Z","end":"2026-05-05T10:20:06.11255Z","steps":["trace[1897254722] 'read index received' (duration: 11.600733ms)","trace[1897254722] 'applied index is now lower than readState.Index' (duration: 106.487787ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:20:06.112651Z","caller":"traceutil/trace.go:171","msg":"trace[260328488] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"137.181268ms","start":"2026-05-05T10:20:05.975435Z","end":"2026-05-05T10:20:06.112617Z","steps":["trace[260328488] 'process raft request' (duration: 133.815423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:20:06.112827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.354356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T10:20:06.112869Z","caller":"traceutil/trace.go:171","msg":"trace[1055119329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1576; }","duration":"118.410067ms","start":"2026-05-05T10:20:05.994447Z","end":"2026-05-05T10:20:06.112857Z","steps":["trace[1055119329] 'agreement among raft nodes before linearized reading' (duration: 118.230713ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:40.885237Z","caller":"traceutil/trace.go:171","msg":"trace[315958898] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2091; }","duration":"131.961448ms","start":"2026-05-05T10:21:40.753248Z","end":"2026-05-05T10:21:40.88521Z","steps":["trace[315958898] 'read index received' (duration: 21.00243ms)","trace[315958898] 'applied index is now lower than readState.Index' (duration: 110.957928ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:40.885583Z","caller":"traceutil/trace.go:171","msg":"trace[409946705] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"144.386525ms","start":"2026-05-05T10:21:40.741182Z","end":"2026-05-05T10:21:40.885569Z","steps":["trace[409946705] 'process raft request' (duration: 101.564867ms)","trace[409946705] 'compare' (duration: 42.339389ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:40.885688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.389987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T10:21:40.88583Z","caller":"traceutil/trace.go:171","msg":"trace[573140892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2009; }","duration":"132.56555ms","start":"2026-05-05T10:21:40.753213Z","end":"2026-05-05T10:21:40.885779Z","steps":["trace[573140892] 'agreement among raft nodes before linearized reading' (duration: 132.234043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:59.733693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.270268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:21:59.733802Z","caller":"traceutil/trace.go:171","msg":"trace[921073613] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"193.998491ms","start":"2026-05-05T10:21:59.539783Z","end":"2026-05-05T10:21:59.733782Z","steps":["trace[921073613] 'process raft request' (duration: 52.5565ms)","trace[921073613] 'compare' (duration: 141.054503ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:11.940259Z","caller":"traceutil/trace.go:171","msg":"trace[2091278467] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"168.53282ms","start":"2026-05-05T10:22:11.771705Z","end":"2026-05-05T10:22:11.940238Z","steps":["trace[2091278467] 'process raft request' (duration: 168.366647ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:22:19.91649Z","caller":"traceutil/trace.go:171","msg":"trace[1835703981] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2308; }","duration":"256.086176ms","start":"2026-05-05T10:22:19.66038Z","end":"2026-05-05T10:22:19.916467Z","steps":["trace[1835703981] 'read index received' (duration: 255.889373ms)","trace[1835703981] 'applied index is now lower than readState.Index' (duration: 195.903µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:22:19.916709Z","caller":"traceutil/trace.go:171","msg":"trace[173799051] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"372.580086ms","start":"2026-05-05T10:22:19.54411Z","end":"2026-05-05T10:22:19.91669Z","steps":["trace[173799051] 'process raft request' (duration: 372.226039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:22:19.916875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:22:19.544094Z","time spent":"372.699429ms","remote":"127.0.0.1:33304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:22:19.917178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.24771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:22:19.917224Z","caller":"traceutil/trace.go:171","msg":"trace[110386871] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2216; }","duration":"256.87098ms","start":"2026-05-05T10:22:19.66034Z","end":"2026-05-05T10:22:19.917211Z","steps":["trace[110386871] 'agreement among raft nodes before linearized reading' (duration: 256.219569ms)"],"step_count":1}