{"level":"warn","ts":"2026-07-30T21:04:20.473991Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:04:20.474103Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.140:2380","--initial-cluster=instance=https://199.19.213.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T21:04:20.474196Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:04:20.47421Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-07-30T21:04:20.474232Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T21:04:20.475208Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"]} {"level":"info","ts":"2026-07-30T21:04:20.475332Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T21:04:20.480564Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.325627ms"} {"level":"info","ts":"2026-07-30T21:04:20.489554Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6366444c8cd268d","cluster-id":"b92fe020fa22927c"} {"level":"info","ts":"2026-07-30T21:04:20.489624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T21:04:20.489645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 0"} {"level":"info","ts":"2026-07-30T21:04:20.489657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6366444c8cd268d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T21:04:20.489667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 1"} {"level":"info","ts":"2026-07-30T21:04:20.489751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"warn","ts":"2026-07-30T21:04:20.503973Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T21:04:20.505986Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T21:04:20.507343Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T21:04:20.511242Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6366444c8cd268d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T21:04:20.51156Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6366444c8cd268d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T21:04:20.511649Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T21:04:20.511674Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T21:04:20.511681Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T21:04:20.514553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"info","ts":"2026-07-30T21:04:20.515273Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","added-peer-id":"b6366444c8cd268d","added-peer-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-07-30T21:04:20.516124Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T21:04:20.516335Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6366444c8cd268d","initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T21:04:20.516363Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T21:04:20.516481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-07-30T21:04:20.516494Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-07-30T21:04:21.190862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T21:04:21.19091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T21:04:21.190955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgPreVoteResp from b6366444c8cd268d at term 1"} {"level":"info","ts":"2026-07-30T21:04:21.190967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became candidate at term 2"} {"level":"info","ts":"2026-07-30T21:04:21.190973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgVoteResp from b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-07-30T21:04:21.191005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became leader at term 2"} {"level":"info","ts":"2026-07-30T21:04:21.191017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6366444c8cd268d elected leader b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-07-30T21:04:21.192533Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:04:21.193535Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6366444c8cd268d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.140:2379]}","request-path":"/0/members/b6366444c8cd268d/attributes","cluster-id":"b92fe020fa22927c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T21:04:21.193648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:04:21.193739Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:04:21.1955Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T21:04:21.195528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T21:04:21.195936Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:04:21.196108Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:04:21.19613Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.140:2379"} {"level":"info","ts":"2026-07-30T21:04:21.196158Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:04:21.197364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-30T21:04:47.559607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218639628,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:04:47.58245Z","caller":"traceutil/trace.go:171","msg":"trace[1560632818] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"523.474173ms","start":"2026-07-30T21:04:47.058941Z","end":"2026-07-30T21:04:47.582415Z","steps":["trace[1560632818] 'read index received' (duration: 523.245747ms)","trace[1560632818] 'applied index is now lower than readState.Index' (duration: 227.416µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:47.582699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.723629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:04:47.582735Z","caller":"traceutil/trace.go:171","msg":"trace[411834838] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"599.887234ms","start":"2026-07-30T21:04:46.982796Z","end":"2026-07-30T21:04:47.582683Z","steps":["trace[411834838] 'process raft request' (duration: 599.463094ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:47.582791Z","caller":"traceutil/trace.go:171","msg":"trace[1091130844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"523.863472ms","start":"2026-07-30T21:04:47.058906Z","end":"2026-07-30T21:04:47.582769Z","steps":["trace[1091130844] 'agreement among raft nodes before linearized reading' (duration: 523.622156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:47.582856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:47.058893Z","time spent":"523.947774ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:04:47.583843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:46.982761Z","time spent":"600.076379ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:48.10058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.915927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:48.100655Z","caller":"traceutil/trace.go:171","msg":"trace[806150824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"139.03097ms","start":"2026-07-30T21:04:47.961607Z","end":"2026-07-30T21:04:48.100638Z","steps":["trace[806150824] 'range keys from in-memory index tree' (duration: 138.836675ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:48.607588Z","caller":"traceutil/trace.go:171","msg":"trace[617769750] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"203.435451ms","start":"2026-07-30T21:04:48.404127Z","end":"2026-07-30T21:04:48.607562Z","steps":["trace[617769750] 'process raft request' (duration: 203.278107ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:57.760681Z","caller":"traceutil/trace.go:171","msg":"trace[1878053666] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"336.601816ms","start":"2026-07-30T21:04:57.424055Z","end":"2026-07-30T21:04:57.760657Z","steps":["trace[1878053666] 'process raft request' (duration: 336.452162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.760951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.424034Z","time spent":"336.833662ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.768872Z","caller":"traceutil/trace.go:171","msg":"trace[1090867389] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"343.032203ms","start":"2026-07-30T21:04:57.425829Z","end":"2026-07-30T21:04:57.768861Z","steps":["trace[1090867389] 'process raft request' (duration: 342.881749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.768949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.425821Z","time spent":"343.081834ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.769009Z","caller":"traceutil/trace.go:171","msg":"trace[850180305] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"340.748367ms","start":"2026-07-30T21:04:57.428197Z","end":"2026-07-30T21:04:57.768945Z","steps":["trace[850180305] 'process raft request' (duration: 340.623344ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:57.769093Z","caller":"traceutil/trace.go:171","msg":"trace[213469277] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"333.489231ms","start":"2026-07-30T21:04:57.435576Z","end":"2026-07-30T21:04:57.769066Z","steps":["trace[213469277] 'process raft request' (duration: 333.277046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.769189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.435557Z","time spent":"333.587143ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:04:57.769208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.428173Z","time spent":"340.944042ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.769453Z","caller":"traceutil/trace.go:171","msg":"trace[1889009012] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"333.804189ms","start":"2026-07-30T21:04:57.435616Z","end":"2026-07-30T21:04:57.76942Z","steps":["trace[1889009012] 'process raft request' (duration: 333.267945ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:57.769518Z","caller":"traceutil/trace.go:171","msg":"trace[1191539635] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"332.235921ms","start":"2026-07-30T21:04:57.437276Z","end":"2026-07-30T21:04:57.769512Z","steps":["trace[1191539635] 'process raft request' (duration: 332.149069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.769675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.437268Z","time spent":"332.383594ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.769653Z","caller":"traceutil/trace.go:171","msg":"trace[883271013] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"327.706709ms","start":"2026-07-30T21:04:57.441883Z","end":"2026-07-30T21:04:57.769589Z","steps":["trace[883271013] 'process raft request' (duration: 327.583036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.769581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.435597Z","time spent":"333.923462ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.769783Z","caller":"traceutil/trace.go:171","msg":"trace[1036689913] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"323.378875ms","start":"2026-07-30T21:04:57.44639Z","end":"2026-07-30T21:04:57.769769Z","steps":["trace[1036689913] 'process raft request' (duration: 323.112258ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:57.769895Z","caller":"traceutil/trace.go:171","msg":"trace[41158436] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"318.623268ms","start":"2026-07-30T21:04:57.451256Z","end":"2026-07-30T21:04:57.769879Z","steps":["trace[41158436] 'process raft request' (duration: 318.403233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.769855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.441872Z","time spent":"327.918265ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:04:57.769891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.446366Z","time spent":"323.493938ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.769484Z","caller":"traceutil/trace.go:171","msg":"trace[967931360] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"332.351354ms","start":"2026-07-30T21:04:57.437121Z","end":"2026-07-30T21:04:57.769472Z","steps":["trace[967931360] 'process raft request' (duration: 332.258971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.770003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.437109Z","time spent":"332.874396ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:04:57.769953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.451245Z","time spent":"318.684571ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.770326Z","caller":"traceutil/trace.go:171","msg":"trace[1735474336] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"305.179371ms","start":"2026-07-30T21:04:57.465138Z","end":"2026-07-30T21:04:57.770317Z","steps":["trace[1735474336] 'process raft request' (duration: 304.680759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.770374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:57.465126Z","time spent":"305.227632ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:04:57.770374Z","caller":"traceutil/trace.go:171","msg":"trace[1080749292] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:544; }","duration":"149.022723ms","start":"2026-07-30T21:04:57.62133Z","end":"2026-07-30T21:04:57.770353Z","steps":["trace[1080749292] 'read index received' (duration: 139.150552ms)","trace[1080749292] 'applied index is now lower than readState.Index' (duration: 9.870521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:57.770517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.181797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:04:57.770561Z","caller":"traceutil/trace.go:171","msg":"trace[1798720063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"149.265809ms","start":"2026-07-30T21:04:57.621285Z","end":"2026-07-30T21:04:57.770551Z","steps":["trace[1798720063] 'agreement among raft nodes before linearized reading' (duration: 149.151376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:57.775721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.342302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T21:04:57.777183Z","caller":"traceutil/trace.go:171","msg":"trace[447990498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"118.855278ms","start":"2026-07-30T21:04:57.658312Z","end":"2026-07-30T21:04:57.777168Z","steps":["trace[447990498] 'agreement among raft nodes before linearized reading' (duration: 117.338601ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:57.775938Z","caller":"traceutil/trace.go:171","msg":"trace[1817039058] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"169.593056ms","start":"2026-07-30T21:04:57.606323Z","end":"2026-07-30T21:04:57.775916Z","steps":["trace[1817039058] 'process raft request' (duration: 163.862826ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:59.064148Z","caller":"traceutil/trace.go:171","msg":"trace[1218611568] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"315.071552ms","start":"2026-07-30T21:04:58.749026Z","end":"2026-07-30T21:04:59.064097Z","steps":["trace[1218611568] 'read index received' (duration: 309.142768ms)","trace[1218611568] 'applied index is now lower than readState.Index' (duration: 5.927434ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:59.064364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.331019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-07-30T21:04:59.064397Z","caller":"traceutil/trace.go:171","msg":"trace[1285131285] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:599; }","duration":"315.39906ms","start":"2026-07-30T21:04:58.748987Z","end":"2026-07-30T21:04:59.064386Z","steps":["trace[1285131285] 'agreement among raft nodes before linearized reading' (duration: 315.222636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:59.064426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:58.74897Z","time spent":"315.449812ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151260,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-30T21:04:59.064621Z","caller":"traceutil/trace.go:171","msg":"trace[2121883322] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"324.166644ms","start":"2026-07-30T21:04:58.740432Z","end":"2026-07-30T21:04:59.064598Z","steps":["trace[2121883322] 'process raft request' (duration: 318.177758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:59.064699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:58.740409Z","time spent":"324.237075ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:59.071406Z","caller":"traceutil/trace.go:171","msg":"trace[1692997407] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"292.238405ms","start":"2026-07-30T21:04:58.779147Z","end":"2026-07-30T21:04:59.071385Z","steps":["trace[1692997407] 'process raft request' (duration: 287.285315ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:00.847588Z","caller":"traceutil/trace.go:171","msg":"trace[673388581] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"158.76228ms","start":"2026-07-30T21:05:00.688778Z","end":"2026-07-30T21:05:00.847541Z","steps":["trace[673388581] 'process raft request' (duration: 158.413592ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:03.968929Z","caller":"traceutil/trace.go:171","msg":"trace[2132802252] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"261.202899ms","start":"2026-07-30T21:05:03.707701Z","end":"2026-07-30T21:05:03.968904Z","steps":["trace[2132802252] 'process raft request' (duration: 260.685816ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:03.969439Z","caller":"traceutil/trace.go:171","msg":"trace[579403980] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:654; }","duration":"146.244066ms","start":"2026-07-30T21:05:03.823178Z","end":"2026-07-30T21:05:03.969422Z","steps":["trace[579403980] 'read index received' (duration: 146.237926ms)","trace[579403980] 'applied index is now lower than readState.Index' (duration: 3.87µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:05:03.969608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.257626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T21:05:03.969641Z","caller":"traceutil/trace.go:171","msg":"trace[817571087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"146.323348ms","start":"2026-07-30T21:05:03.823306Z","end":"2026-07-30T21:05:03.969629Z","steps":["trace[817571087] 'agreement among raft nodes before linearized reading' (duration: 146.194685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:03.96984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.665146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:05:03.969869Z","caller":"traceutil/trace.go:171","msg":"trace[31587643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"146.748587ms","start":"2026-07-30T21:05:03.823112Z","end":"2026-07-30T21:05:03.96986Z","steps":["trace[31587643] 'agreement among raft nodes before linearized reading' (duration: 146.669585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:04.482783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.883498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:05:04.482903Z","caller":"traceutil/trace.go:171","msg":"trace[213275423] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"507.878062ms","start":"2026-07-30T21:05:03.975007Z","end":"2026-07-30T21:05:04.482885Z","steps":["trace[213275423] 'process raft request' (duration: 363.663677ms)","trace[213275423] 'compare' (duration: 143.725153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:05:04.482971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:03.97497Z","time spent":"507.970844ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:05:04.549878Z","caller":"traceutil/trace.go:171","msg":"trace[970472239] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"389.944917ms","start":"2026-07-30T21:05:04.159911Z","end":"2026-07-30T21:05:04.549856Z","steps":["trace[970472239] 'read index received' (duration: 178.770369ms)","trace[970472239] 'applied index is now lower than readState.Index' (duration: 211.173928ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:05:04.549958Z","caller":"traceutil/trace.go:171","msg":"trace[604423125] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"474.105248ms","start":"2026-07-30T21:05:04.075844Z","end":"2026-07-30T21:05:04.549949Z","steps":["trace[604423125] 'process raft request' (duration: 473.980285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:04.550024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.075821Z","time spent":"474.1547ms","remote":"127.0.0.1:47336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:05:04.550051Z","caller":"traceutil/trace.go:171","msg":"trace[630686712] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"574.985408ms","start":"2026-07-30T21:05:03.975039Z","end":"2026-07-30T21:05:04.550024Z","steps":["trace[630686712] 'process raft request' (duration: 574.66041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:04.550173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:03.975018Z","time spent":"575.10137ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:05:04.550193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.249244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:05:04.550266Z","caller":"traceutil/trace.go:171","msg":"trace[1214864551] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:632; }","duration":"390.380868ms","start":"2026-07-30T21:05:04.159868Z","end":"2026-07-30T21:05:04.550249Z","steps":["trace[1214864551] 'agreement among raft nodes before linearized reading' (duration: 390.231114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:04.55031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.159847Z","time spent":"390.454779ms","remote":"127.0.0.1:46814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.140\" "} {"level":"warn","ts":"2026-07-30T21:05:04.55043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.002572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T21:05:04.550518Z","caller":"traceutil/trace.go:171","msg":"trace[859785768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"334.132436ms","start":"2026-07-30T21:05:04.216369Z","end":"2026-07-30T21:05:04.550501Z","steps":["trace[859785768] 'agreement among raft nodes before linearized reading' (duration: 333.872229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:04.550583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.216352Z","time spent":"334.219608ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:05:04.550601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.145294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j6rlt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T21:05:04.550688Z","caller":"traceutil/trace.go:171","msg":"trace[223567947] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j6rlt; range_end:; response_count:1; response_revision:632; }","duration":"338.278158ms","start":"2026-07-30T21:05:04.212389Z","end":"2026-07-30T21:05:04.550667Z","steps":["trace[223567947] 'agreement among raft nodes before linearized reading' (duration: 337.790286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:04.550739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.212369Z","time spent":"338.35898ms","remote":"127.0.0.1:46960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-j6rlt\" "} {"level":"warn","ts":"2026-07-30T21:05:06.384369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445266977s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-30T21:05:06.384703Z","caller":"traceutil/trace.go:171","msg":"trace[1707772227] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"1.824120524s","start":"2026-07-30T21:05:04.560547Z","end":"2026-07-30T21:05:06.384667Z","steps":["trace[1707772227] 'read index received' (duration: 378.604271ms)","trace[1707772227] 'applied index is now lower than readState.Index' (duration: 1.445515313s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:05:06.384795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.55116Z","time spent":"1.833632297s","remote":"127.0.0.1:46814","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T21:05:06.385113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824540874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:05:06.385183Z","caller":"traceutil/trace.go:171","msg":"trace[529328400] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:634; }","duration":"1.824639347s","start":"2026-07-30T21:05:04.560525Z","end":"2026-07-30T21:05:06.385164Z","steps":["trace[529328400] 'agreement among raft nodes before linearized reading' (duration: 1.824509364s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:06.385223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.560514Z","time spent":"1.824700368s","remote":"127.0.0.1:47038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-30T21:05:06.385266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522717755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:05:06.385271Z","caller":"traceutil/trace.go:171","msg":"trace[1943185600] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.830666523s","start":"2026-07-30T21:05:04.554582Z","end":"2026-07-30T21:05:06.385248Z","steps":["trace[1943185600] 'process raft request' (duration: 1.829886594s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:06.385332Z","caller":"traceutil/trace.go:171","msg":"trace[1965269433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"1.522825037s","start":"2026-07-30T21:05:04.86249Z","end":"2026-07-30T21:05:06.385315Z","steps":["trace[1965269433] 'agreement among raft nodes before linearized reading' (duration: 1.522603472s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:06.385339Z","caller":"traceutil/trace.go:171","msg":"trace[238031860] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.819238245s","start":"2026-07-30T21:05:04.566088Z","end":"2026-07-30T21:05:06.385326Z","steps":["trace[238031860] 'process raft request' (duration: 1.818531138s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:06.38536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420626196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:05:06.385372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.862454Z","time spent":"1.52290978s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T21:05:06.385405Z","caller":"traceutil/trace.go:171","msg":"trace[963949149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.420706508s","start":"2026-07-30T21:05:04.964686Z","end":"2026-07-30T21:05:06.385392Z","steps":["trace[963949149] 'agreement among raft nodes before linearized reading' (duration: 1.420635587s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:06.385441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.964671Z","time spent":"1.420762929s","remote":"127.0.0.1:46772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:05:06.385437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.566053Z","time spent":"1.819319457s","remote":"127.0.0.1:46960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:05:06.385432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:04.554568Z","time spent":"1.830786786s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:05:11.476718Z","caller":"traceutil/trace.go:171","msg":"trace[1314044015] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"105.002271ms","start":"2026-07-30T21:05:11.371672Z","end":"2026-07-30T21:05:11.476675Z","steps":["trace[1314044015] 'process raft request' (duration: 104.380825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.464226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218640312,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:05:22.868204Z","caller":"traceutil/trace.go:171","msg":"trace[529849815] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"930.727192ms","start":"2026-07-30T21:05:21.937451Z","end":"2026-07-30T21:05:22.868178Z","steps":["trace[529849815] 'process raft request' (duration: 930.567489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.86836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:21.937434Z","time spent":"930.849915ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:05:22.870765Z","caller":"traceutil/trace.go:171","msg":"trace[1984630136] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"538.93234ms","start":"2026-07-30T21:05:22.331804Z","end":"2026-07-30T21:05:22.870736Z","steps":["trace[1984630136] 'process raft request' (duration: 538.693404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.870843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.687486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:05:22.870891Z","caller":"traceutil/trace.go:171","msg":"trace[393817268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"906.786579ms","start":"2026-07-30T21:05:21.964092Z","end":"2026-07-30T21:05:22.870878Z","steps":["trace[393817268] 'agreement among raft nodes before linearized reading' (duration: 906.699757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.870894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:22.331776Z","time spent":"539.065632ms","remote":"127.0.0.1:47038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:05:22.870933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:21.964071Z","time spent":"906.85405ms","remote":"127.0.0.1:46772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:05:22.870919Z","caller":"traceutil/trace.go:171","msg":"trace[945552589] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"906.505342ms","start":"2026-07-30T21:05:21.964145Z","end":"2026-07-30T21:05:22.87065Z","steps":["trace[945552589] 'read index received' (duration: 904.052972ms)","trace[945552589] 'applied index is now lower than readState.Index' (duration: 2.45114ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:05:22.87131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.404681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T21:05:22.871348Z","caller":"traceutil/trace.go:171","msg":"trace[1198100037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"313.482563ms","start":"2026-07-30T21:05:22.557857Z","end":"2026-07-30T21:05:22.871339Z","steps":["trace[1198100037] 'agreement among raft nodes before linearized reading' (duration: 313.284938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.871406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:22.557815Z","time spent":"313.583546ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:05:22.871372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.360242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:05:22.871506Z","caller":"traceutil/trace.go:171","msg":"trace[1875143487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"245.542736ms","start":"2026-07-30T21:05:22.625942Z","end":"2026-07-30T21:05:22.871485Z","steps":["trace[1875143487] 'agreement among raft nodes before linearized reading' (duration: 245.155986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.871653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.306006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:05:22.871773Z","caller":"traceutil/trace.go:171","msg":"trace[457150408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"333.481321ms","start":"2026-07-30T21:05:22.538264Z","end":"2026-07-30T21:05:22.871745Z","steps":["trace[457150408] 'agreement among raft nodes before linearized reading' (duration: 332.968379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.871848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:22.538244Z","time spent":"333.594153ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:05:22.871707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.479144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:05:22.872004Z","caller":"traceutil/trace.go:171","msg":"trace[542262898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"416.808261ms","start":"2026-07-30T21:05:22.455186Z","end":"2026-07-30T21:05:22.871994Z","steps":["trace[542262898] 'agreement among raft nodes before linearized reading' (duration: 415.909799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:22.872032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:22.45517Z","time spent":"416.854743ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:05:58.13486Z","caller":"traceutil/trace.go:171","msg":"trace[1674466892] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"454.027471ms","start":"2026-07-30T21:05:57.680813Z","end":"2026-07-30T21:05:58.13484Z","steps":["trace[1674466892] 'read index received' (duration: 453.905309ms)","trace[1674466892] 'applied index is now lower than readState.Index' (duration: 120.942µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:05:58.134973Z","caller":"traceutil/trace.go:171","msg":"trace[222481654] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"592.278186ms","start":"2026-07-30T21:05:57.542659Z","end":"2026-07-30T21:05:58.134937Z","steps":["trace[222481654] 'process raft request' (duration: 592.03304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:58.135032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.198066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:05:58.135062Z","caller":"traceutil/trace.go:171","msg":"trace[493824608] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1015; }","duration":"454.288129ms","start":"2026-07-30T21:05:57.680767Z","end":"2026-07-30T21:05:58.135055Z","steps":["trace[493824608] 'agreement among raft nodes before linearized reading' (duration: 454.205627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:58.135088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:57.680745Z","time spent":"454.334299ms","remote":"127.0.0.1:47256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:05:58.135112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.83948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:05:58.135168Z","caller":"traceutil/trace.go:171","msg":"trace[1787697226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"170.926132ms","start":"2026-07-30T21:05:57.964224Z","end":"2026-07-30T21:05:58.135151Z","steps":["trace[1787697226] 'agreement among raft nodes before linearized reading' (duration: 170.84657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:58.135193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:57.542637Z","time spent":"592.42599ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:05:58.681298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.829042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:05:58.681384Z","caller":"traceutil/trace.go:171","msg":"trace[2027534941] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1015; }","duration":"318.989086ms","start":"2026-07-30T21:05:58.362376Z","end":"2026-07-30T21:05:58.681365Z","steps":["trace[2027534941] 'count revisions from in-memory index tree' (duration: 318.696739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:58.681439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:58.362347Z","time spent":"319.068918ms","remote":"127.0.0.1:46960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:05:58.68142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.438023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:05:58.681491Z","caller":"traceutil/trace.go:171","msg":"trace[2107602037] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1015; }","duration":"377.567946ms","start":"2026-07-30T21:05:58.303905Z","end":"2026-07-30T21:05:58.681473Z","steps":["trace[2107602037] 'count revisions from in-memory index tree' (duration: 377.214628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:58.681532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:58.303883Z","time spent":"377.638288ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:05:58.69685Z","caller":"traceutil/trace.go:171","msg":"trace[1907225580] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"324.275285ms","start":"2026-07-30T21:05:58.372553Z","end":"2026-07-30T21:05:58.696828Z","steps":["trace[1907225580] 'process raft request' (duration: 324.106761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:58.696989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:58.372534Z","time spent":"324.377608ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:05:59.659361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.556784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T21:05:59.659492Z","caller":"traceutil/trace.go:171","msg":"trace[522491939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"556.698847ms","start":"2026-07-30T21:05:59.102746Z","end":"2026-07-30T21:05:59.659445Z","steps":["trace[522491939] 'range keys from in-memory index tree' (duration: 556.358829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:59.659566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:59.10273Z","time spent":"556.818201ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:05:59.659819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.702016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:05:59.659899Z","caller":"traceutil/trace.go:171","msg":"trace[1505958837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"694.816939ms","start":"2026-07-30T21:05:58.965063Z","end":"2026-07-30T21:05:59.65988Z","steps":["trace[1505958837] 'range keys from in-memory index tree' (duration: 694.641505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:59.659951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:58.965048Z","time spent":"694.893881ms","remote":"127.0.0.1:46772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:05:59.659957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.720559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T21:05:59.660025Z","caller":"traceutil/trace.go:171","msg":"trace[1645072502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"588.831992ms","start":"2026-07-30T21:05:59.071178Z","end":"2026-07-30T21:05:59.66001Z","steps":["trace[1645072502] 'range keys from in-memory index tree' (duration: 588.491444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:59.660069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:59.071154Z","time spent":"588.902234ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:05:59.660193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.651625ms","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-07-30T21:05:59.660187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.504117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:05:59.660229Z","caller":"traceutil/trace.go:171","msg":"trace[626840176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"560.737677ms","start":"2026-07-30T21:05:59.099482Z","end":"2026-07-30T21:05:59.66022Z","steps":["trace[626840176] 'range keys from in-memory index tree' (duration: 560.495101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:59.660253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:59.09943Z","time spent":"560.818399ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:05:59.660249Z","caller":"traceutil/trace.go:171","msg":"trace[558371359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"557.59094ms","start":"2026-07-30T21:05:59.102643Z","end":"2026-07-30T21:05:59.660234Z","steps":["trace[558371359] 'range keys from in-memory index tree' (duration: 557.388244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:59.660293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:59.102632Z","time spent":"557.649272ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:05:59.660455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.501953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:05:59.660558Z","caller":"traceutil/trace.go:171","msg":"trace[601751138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"574.709258ms","start":"2026-07-30T21:05:59.085826Z","end":"2026-07-30T21:05:59.660535Z","steps":["trace[601751138] 'range keys from in-memory index tree' (duration: 574.338609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:05:59.660622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:05:59.085816Z","time spent":"574.78985ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T21:05:59.77679Z","caller":"traceutil/trace.go:171","msg":"trace[149700895] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"114.922415ms","start":"2026-07-30T21:05:59.661843Z","end":"2026-07-30T21:05:59.776765Z","steps":["trace[149700895] 'read index received' (duration: 112.483635ms)","trace[149700895] 'applied index is now lower than readState.Index' (duration: 2.43724ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:05:59.776933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.075828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:05:59.777018Z","caller":"traceutil/trace.go:171","msg":"trace[207202524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"115.179881ms","start":"2026-07-30T21:05:59.661812Z","end":"2026-07-30T21:05:59.776992Z","steps":["trace[207202524] 'agreement among raft nodes before linearized reading' (duration: 115.039788ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:59.77916Z","caller":"traceutil/trace.go:171","msg":"trace[1966445679] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"114.810152ms","start":"2026-07-30T21:05:59.664329Z","end":"2026-07-30T21:05:59.77914Z","steps":["trace[1966445679] 'process raft request' (duration: 114.572226ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:59.779167Z","caller":"traceutil/trace.go:171","msg":"trace[1676101874] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"114.418153ms","start":"2026-07-30T21:05:59.664728Z","end":"2026-07-30T21:05:59.779146Z","steps":["trace[1676101874] 'process raft request' (duration: 114.317291ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:59.779498Z","caller":"traceutil/trace.go:171","msg":"trace[255428599] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"114.006533ms","start":"2026-07-30T21:05:59.665469Z","end":"2026-07-30T21:05:59.779476Z","steps":["trace[255428599] 'process raft request' (duration: 113.934661ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:59.779569Z","caller":"traceutil/trace.go:171","msg":"trace[1704775659] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"114.369342ms","start":"2026-07-30T21:05:59.665185Z","end":"2026-07-30T21:05:59.779554Z","steps":["trace[1704775659] 'process raft request' (duration: 113.916351ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:59.779773Z","caller":"traceutil/trace.go:171","msg":"trace[1604650278] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"114.336031ms","start":"2026-07-30T21:05:59.665426Z","end":"2026-07-30T21:05:59.779762Z","steps":["trace[1604650278] 'process raft request' (duration: 113.87593ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:20.064032Z","caller":"traceutil/trace.go:171","msg":"trace[1026272929] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1126; }","duration":"179.88479ms","start":"2026-07-30T21:06:19.884121Z","end":"2026-07-30T21:06:20.064006Z","steps":["trace[1026272929] 'read index received' (duration: 34.615524ms)","trace[1026272929] 'applied index is now lower than readState.Index' (duration: 145.267976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:06:20.064351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.207688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:06:20.064348Z","caller":"traceutil/trace.go:171","msg":"trace[984747480] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"181.324076ms","start":"2026-07-30T21:06:19.882987Z","end":"2026-07-30T21:06:20.064311Z","steps":["trace[984747480] 'process raft request' (duration: 165.851339ms)","trace[984747480] 'compare' (duration: 14.931894ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:20.0644Z","caller":"traceutil/trace.go:171","msg":"trace[1214034634] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1084; }","duration":"180.31487ms","start":"2026-07-30T21:06:19.884073Z","end":"2026-07-30T21:06:20.064387Z","steps":["trace[1214034634] 'agreement among raft nodes before linearized reading' (duration: 180.218378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:20.064433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.110446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:06:20.064502Z","caller":"traceutil/trace.go:171","msg":"trace[208602425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"180.202408ms","start":"2026-07-30T21:06:19.88428Z","end":"2026-07-30T21:06:20.064482Z","steps":["trace[208602425] 'agreement among raft nodes before linearized reading' (duration: 180.007333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:20.06452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.499611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T21:06:20.064543Z","caller":"traceutil/trace.go:171","msg":"trace[1795882932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"179.524142ms","start":"2026-07-30T21:06:19.88501Z","end":"2026-07-30T21:06:20.064534Z","steps":["trace[1795882932] 'agreement among raft nodes before linearized reading' (duration: 179.46532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:20.064434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.567484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:06:20.064617Z","caller":"traceutil/trace.go:171","msg":"trace[813096375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1084; }","duration":"177.791009ms","start":"2026-07-30T21:06:19.88682Z","end":"2026-07-30T21:06:20.064611Z","steps":["trace[813096375] 'agreement among raft nodes before linearized reading' (duration: 177.499592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.170761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.643225Z","time spent":"527.530386ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T21:06:21.171316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.997685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:06:21.171436Z","caller":"traceutil/trace.go:171","msg":"trace[1274420443] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"964.351208ms","start":"2026-07-30T21:06:20.207073Z","end":"2026-07-30T21:06:21.171424Z","steps":["trace[1274420443] 'process raft request' (duration: 964.325227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.171499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.207054Z","time spent":"964.421339ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:21.171695Z","caller":"traceutil/trace.go:171","msg":"trace[1944397779] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.103329019s","start":"2026-07-30T21:06:20.068358Z","end":"2026-07-30T21:06:21.171687Z","steps":["trace[1944397779] 'process raft request' (duration: 443.712869ms)","trace[1944397779] 'compare' (duration: 658.499293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:06:21.171729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.068347Z","time spent":"1.10336353s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:21.171731Z","caller":"traceutil/trace.go:171","msg":"trace[743652554] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1129; }","duration":"988.671531ms","start":"2026-07-30T21:06:20.183029Z","end":"2026-07-30T21:06:21.171701Z","steps":["trace[743652554] 'read index received' (duration: 329.054721ms)","trace[743652554] 'applied index is now lower than readState.Index' (duration: 659.613519ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:21.171882Z","caller":"traceutil/trace.go:171","msg":"trace[970558706] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.102835767s","start":"2026-07-30T21:06:20.069029Z","end":"2026-07-30T21:06:21.171864Z","steps":["trace[970558706] 'process raft request' (duration: 1.102328715s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.171929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.49955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T21:06:21.171982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.584808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:06:21.172022Z","caller":"traceutil/trace.go:171","msg":"trace[1067295416] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1088; }","duration":"122.590532ms","start":"2026-07-30T21:06:21.049421Z","end":"2026-07-30T21:06:21.172011Z","steps":["trace[1067295416] 'agreement among raft nodes before linearized reading' (duration: 122.477559ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:21.172062Z","caller":"traceutil/trace.go:171","msg":"trace[1054161683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"772.691501ms","start":"2026-07-30T21:06:20.399348Z","end":"2026-07-30T21:06:21.17204Z","steps":["trace[1054161683] 'agreement among raft nodes before linearized reading' (duration: 772.550977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.172109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.399333Z","time spent":"772.764142ms","remote":"127.0.0.1:46772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:06:21.17211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.410644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:06:21.172158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.133333ms","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-07-30T21:06:21.172179Z","caller":"traceutil/trace.go:171","msg":"trace[1436849362] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1088; }","duration":"989.183645ms","start":"2026-07-30T21:06:20.18299Z","end":"2026-07-30T21:06:21.172173Z","steps":["trace[1436849362] 'agreement among raft nodes before linearized reading' (duration: 989.135904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.172163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.528099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:06:21.172198Z","caller":"traceutil/trace.go:171","msg":"trace[1694068466] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1088; }","duration":"221.495606ms","start":"2026-07-30T21:06:20.950647Z","end":"2026-07-30T21:06:21.172143Z","steps":["trace[1694068466] 'agreement among raft nodes before linearized reading' (duration: 221.419454ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:21.172232Z","caller":"traceutil/trace.go:171","msg":"trace[203041758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"210.619632ms","start":"2026-07-30T21:06:20.961596Z","end":"2026-07-30T21:06:21.172216Z","steps":["trace[203041758] 'agreement among raft nodes before linearized reading' (duration: 210.506639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.172388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.347683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T21:06:21.172196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.18297Z","time spent":"989.222116ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:06:21.172445Z","caller":"traceutil/trace.go:171","msg":"trace[156375949] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1088; }","duration":"736.467986ms","start":"2026-07-30T21:06:20.435959Z","end":"2026-07-30T21:06:21.172427Z","steps":["trace[156375949] 'agreement among raft nodes before linearized reading' (duration: 736.375704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:21.172522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.435938Z","time spent":"736.566758ms","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:06:21.172005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:20.069019Z","time spent":"1.10289909s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:22.42299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.906453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:06:22.423054Z","caller":"traceutil/trace.go:171","msg":"trace[1220768509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"355.013796ms","start":"2026-07-30T21:06:22.068026Z","end":"2026-07-30T21:06:22.42304Z","steps":["trace[1220768509] 'range keys from in-memory index tree' (duration: 354.768559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:22.423084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:22.068015Z","time spent":"355.063217ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:06:22.423307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.770054ms","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-07-30T21:06:22.42339Z","caller":"traceutil/trace.go:171","msg":"trace[1136118221] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1089; }","duration":"279.323077ms","start":"2026-07-30T21:06:22.144047Z","end":"2026-07-30T21:06:22.42337Z","steps":["trace[1136118221] 'count revisions from in-memory index tree' (duration: 278.642451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:22.423486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.408539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:06:22.423591Z","caller":"traceutil/trace.go:171","msg":"trace[1749927039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"347.573343ms","start":"2026-07-30T21:06:22.075998Z","end":"2026-07-30T21:06:22.423571Z","steps":["trace[1749927039] 'range keys from in-memory index tree' (duration: 347.186664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:22.423661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:22.075967Z","time spent":"347.682106ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T21:06:22.748768Z","caller":"traceutil/trace.go:171","msg":"trace[1321760184] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"322.258125ms","start":"2026-07-30T21:06:22.426483Z","end":"2026-07-30T21:06:22.748741Z","steps":["trace[1321760184] 'process raft request' (duration: 322.096831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:22.748972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:22.42646Z","time spent":"322.384069ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:22.755968Z","caller":"traceutil/trace.go:171","msg":"trace[889731206] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"328.54679ms","start":"2026-07-30T21:06:22.427392Z","end":"2026-07-30T21:06:22.755938Z","steps":["trace[889731206] 'process raft request' (duration: 328.388056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:22.756218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:22.427371Z","time spent":"328.681682ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:24.322245Z","caller":"traceutil/trace.go:171","msg":"trace[1528397950] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"159.608096ms","start":"2026-07-30T21:06:24.162575Z","end":"2026-07-30T21:06:24.322183Z","steps":["trace[1528397950] 'read index received' (duration: 159.465572ms)","trace[1528397950] 'applied index is now lower than readState.Index' (duration: 141.083µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:24.322296Z","caller":"traceutil/trace.go:171","msg":"trace[962344819] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"343.394102ms","start":"2026-07-30T21:06:23.978875Z","end":"2026-07-30T21:06:24.322269Z","steps":["trace[962344819] 'process raft request' (duration: 343.127745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:24.32244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:23.978857Z","time spent":"343.503605ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:24.322647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.912131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:06:24.322689Z","caller":"traceutil/trace.go:171","msg":"trace[1425788482] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1095; }","duration":"111.996963ms","start":"2026-07-30T21:06:24.210681Z","end":"2026-07-30T21:06:24.322678Z","steps":["trace[1425788482] 'agreement among raft nodes before linearized reading' (duration: 111.924512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:24.322743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.131048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:06:24.322857Z","caller":"traceutil/trace.go:171","msg":"trace[1156052077] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:1095; }","duration":"160.271072ms","start":"2026-07-30T21:06:24.162509Z","end":"2026-07-30T21:06:24.32278Z","steps":["trace[1156052077] 'agreement among raft nodes before linearized reading' (duration: 159.899163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:24.987875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.097284ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-30T21:06:24.988806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:24.324131Z","time spent":"664.556641ms","remote":"127.0.0.1:46814","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-30T21:06:25.065165Z","caller":"traceutil/trace.go:171","msg":"trace[901466130] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"311.676617ms","start":"2026-07-30T21:06:24.753469Z","end":"2026-07-30T21:06:25.065146Z","steps":["trace[901466130] 'read index received' (duration: 311.451981ms)","trace[901466130] 'applied index is now lower than readState.Index' (duration: 223.526µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:25.065336Z","caller":"traceutil/trace.go:171","msg":"trace[386101519] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"410.709084ms","start":"2026-07-30T21:06:24.654571Z","end":"2026-07-30T21:06:25.06528Z","steps":["trace[386101519] 'process raft request' (duration: 410.402947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:25.065405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.931564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-30T21:06:25.065488Z","caller":"traceutil/trace.go:171","msg":"trace[1437785416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"312.065477ms","start":"2026-07-30T21:06:24.753413Z","end":"2026-07-30T21:06:25.065478Z","steps":["trace[1437785416] 'agreement among raft nodes before linearized reading' (duration: 311.841891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:25.065537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:24.753391Z","time spent":"312.137588ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:06:25.065573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:24.654547Z","time spent":"410.907959ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:25.37028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.685068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:06:25.370792Z","caller":"traceutil/trace.go:171","msg":"trace[1289799786] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"380.855746ms","start":"2026-07-30T21:06:24.989845Z","end":"2026-07-30T21:06:25.370701Z","steps":["trace[1289799786] 'process raft request' (duration: 102.540383ms)","trace[1289799786] 'compare' (duration: 277.331069ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:25.370929Z","caller":"traceutil/trace.go:171","msg":"trace[1126567834] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"300.085835ms","start":"2026-07-30T21:06:25.070806Z","end":"2026-07-30T21:06:25.370892Z","steps":["trace[1126567834] 'process raft request' (duration: 299.596213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:25.371111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:25.070788Z","time spent":"300.227117ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:25.371309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:24.989825Z","time spent":"381.044131ms","remote":"127.0.0.1:46814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:25.371829Z","caller":"traceutil/trace.go:171","msg":"trace[368745075] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"165.595111ms","start":"2026-07-30T21:06:25.206208Z","end":"2026-07-30T21:06:25.371803Z","steps":["trace[368745075] 'process raft request' (duration: 164.286399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:25.372178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.543968ms","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-07-30T21:06:25.372223Z","caller":"traceutil/trace.go:171","msg":"trace[785113405] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1099; }","duration":"482.64553ms","start":"2026-07-30T21:06:24.889567Z","end":"2026-07-30T21:06:25.372212Z","steps":["trace[785113405] 'agreement among raft nodes before linearized reading' (duration: 482.558678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:25.372799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:24.889545Z","time spent":"483.231435ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:06:25.372872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.287055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T21:06:25.372926Z","caller":"traceutil/trace.go:171","msg":"trace[606840704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"612.379617ms","start":"2026-07-30T21:06:24.760534Z","end":"2026-07-30T21:06:25.372913Z","steps":["trace[606840704] 'agreement among raft nodes before linearized reading' (duration: 612.190643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:25.37297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:24.760515Z","time spent":"612.445438ms","remote":"127.0.0.1:47070","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-07-30T21:06:25.372081Z","caller":"traceutil/trace.go:171","msg":"trace[2142736107] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"306.82442ms","start":"2026-07-30T21:06:25.065237Z","end":"2026-07-30T21:06:25.372061Z","steps":["trace[2142736107] 'read index received' (duration: 27.136793ms)","trace[2142736107] 'applied index is now lower than readState.Index' (duration: 279.685257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:06:25.373258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.296184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T21:06:25.373305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.306924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T21:06:25.373919Z","caller":"traceutil/trace.go:171","msg":"trace[1640088070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1099; }","duration":"149.95637ms","start":"2026-07-30T21:06:25.223945Z","end":"2026-07-30T21:06:25.373902Z","steps":["trace[1640088070] 'agreement among raft nodes before linearized reading' (duration: 149.289174ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:25.373333Z","caller":"traceutil/trace.go:171","msg":"trace[276291873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"149.404656ms","start":"2026-07-30T21:06:25.223913Z","end":"2026-07-30T21:06:25.373318Z","steps":["trace[276291873] 'agreement among raft nodes before linearized reading' (duration: 149.224421ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:25.506613Z","caller":"traceutil/trace.go:171","msg":"trace[1600864153] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"129.519931ms","start":"2026-07-30T21:06:25.37707Z","end":"2026-07-30T21:06:25.50659Z","steps":["trace[1600864153] 'process raft request' (duration: 129.350307ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:25.506968Z","caller":"traceutil/trace.go:171","msg":"trace[2054768508] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"129.591463ms","start":"2026-07-30T21:06:25.377353Z","end":"2026-07-30T21:06:25.506945Z","steps":["trace[2054768508] 'process raft request' (duration: 129.198063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:41.95152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218641230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:06:42.452638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218641230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:06:42.526634Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.086868149s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:06:42.527009Z","caller":"traceutil/trace.go:171","msg":"trace[1052989563] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"1.076428185s","start":"2026-07-30T21:06:41.450559Z","end":"2026-07-30T21:06:42.526987Z","steps":["trace[1052989563] 'read index received' (duration: 1.076235829s)","trace[1052989563] 'applied index is now lower than readState.Index' (duration: 191.356µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:42.527364Z","caller":"traceutil/trace.go:171","msg":"trace[1266313841] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"1.087706519s","start":"2026-07-30T21:06:41.439645Z","end":"2026-07-30T21:06:42.527352Z","steps":["trace[1266313841] 'process raft request' (duration: 1.087209027s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:42.527489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:41.439613Z","time spent":"1.087786512s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:42.527773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077209963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:06:42.52781Z","caller":"traceutil/trace.go:171","msg":"trace[243510941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"1.077275165s","start":"2026-07-30T21:06:41.450526Z","end":"2026-07-30T21:06:42.527801Z","steps":["trace[243510941] 'agreement among raft nodes before linearized reading' (duration: 1.077148892s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:42.527843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:41.450512Z","time spent":"1.077325446s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:06:42.528069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022212961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:06:42.528103Z","caller":"traceutil/trace.go:171","msg":"trace[1177463893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"1.022270822s","start":"2026-07-30T21:06:41.505824Z","end":"2026-07-30T21:06:42.528095Z","steps":["trace[1177463893] 'agreement among raft nodes before linearized reading' (duration: 1.022149859s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:42.528134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:41.505813Z","time spent":"1.022314893s","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:06:42.528468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.676838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:06:42.528502Z","caller":"traceutil/trace.go:171","msg":"trace[1677799826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"563.74856ms","start":"2026-07-30T21:06:41.964745Z","end":"2026-07-30T21:06:42.528494Z","steps":["trace[1677799826] 'agreement among raft nodes before linearized reading' (duration: 563.698449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:42.528532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:41.964725Z","time spent":"563.801421ms","remote":"127.0.0.1:46772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:06:42.528761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.649648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T21:06:42.528794Z","caller":"traceutil/trace.go:171","msg":"trace[747242417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"942.72389ms","start":"2026-07-30T21:06:41.586062Z","end":"2026-07-30T21:06:42.528786Z","steps":["trace[747242417] 'agreement among raft nodes before linearized reading' (duration: 942.636908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:42.528825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:41.585997Z","time spent":"942.821982ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:06:42.528987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.228512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:06:42.529017Z","caller":"traceutil/trace.go:171","msg":"trace[1560692858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"943.281773ms","start":"2026-07-30T21:06:41.585728Z","end":"2026-07-30T21:06:42.529009Z","steps":["trace[1560692858] 'agreement among raft nodes before linearized reading' (duration: 943.204401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:42.529046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:41.585716Z","time spent":"943.324944ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:06:54.846229Z","caller":"traceutil/trace.go:171","msg":"trace[359239634] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"143.464481ms","start":"2026-07-30T21:06:54.702743Z","end":"2026-07-30T21:06:54.846208Z","steps":["trace[359239634] 'process raft request' (duration: 143.42725ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:54.846593Z","caller":"traceutil/trace.go:171","msg":"trace[1406181844] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"144.219099ms","start":"2026-07-30T21:06:54.70236Z","end":"2026-07-30T21:06:54.846579Z","steps":["trace[1406181844] 'process raft request' (duration: 143.787139ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:54.846233Z","caller":"traceutil/trace.go:171","msg":"trace[540287887] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"144.483436ms","start":"2026-07-30T21:06:54.701712Z","end":"2026-07-30T21:06:54.846195Z","steps":["trace[540287887] 'process raft request' (duration: 144.20843ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:54.847052Z","caller":"traceutil/trace.go:171","msg":"trace[1547721517] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"144.746012ms","start":"2026-07-30T21:06:54.702286Z","end":"2026-07-30T21:06:54.847032Z","steps":["trace[1547721517] 'process raft request' (duration: 143.82526ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:56.883004Z","caller":"traceutil/trace.go:171","msg":"trace[1663271999] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"124.103528ms","start":"2026-07-30T21:06:56.758877Z","end":"2026-07-30T21:06:56.88298Z","steps":["trace[1663271999] 'process raft request' (duration: 123.76118ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:56.994355Z","caller":"traceutil/trace.go:171","msg":"trace[1911927061] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"104.709216ms","start":"2026-07-30T21:06:56.889623Z","end":"2026-07-30T21:06:56.994333Z","steps":["trace[1911927061] 'process raft request' (duration: 104.550872ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:56.996397Z","caller":"traceutil/trace.go:171","msg":"trace[2060915755] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"106.757226ms","start":"2026-07-30T21:06:56.889619Z","end":"2026-07-30T21:06:56.996376Z","steps":["trace[2060915755] 'process raft request' (duration: 106.631903ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:56.996712Z","caller":"traceutil/trace.go:171","msg":"trace[556672963] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"106.413337ms","start":"2026-07-30T21:06:56.890285Z","end":"2026-07-30T21:06:56.996699Z","steps":["trace[556672963] 'process raft request' (duration: 106.053399ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:56.997011Z","caller":"traceutil/trace.go:171","msg":"trace[1103279249] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"106.306295ms","start":"2026-07-30T21:06:56.890649Z","end":"2026-07-30T21:06:56.996956Z","steps":["trace[1103279249] 'process raft request' (duration: 105.903335ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:59.04523Z","caller":"traceutil/trace.go:171","msg":"trace[1318975036] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"153.92305ms","start":"2026-07-30T21:06:58.891273Z","end":"2026-07-30T21:06:59.045196Z","steps":["trace[1318975036] 'process raft request' (duration: 112.464842ms)","trace[1318975036] 'compare' (duration: 41.288084ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:00.254411Z","caller":"traceutil/trace.go:171","msg":"trace[1278740315] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"291.059327ms","start":"2026-07-30T21:06:59.963328Z","end":"2026-07-30T21:07:00.254387Z","steps":["trace[1278740315] 'read index received' (duration: 290.816691ms)","trace[1278740315] 'applied index is now lower than readState.Index' (duration: 241.386µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:00.254469Z","caller":"traceutil/trace.go:171","msg":"trace[1739494163] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"403.670834ms","start":"2026-07-30T21:06:59.850767Z","end":"2026-07-30T21:07:00.254437Z","steps":["trace[1739494163] 'process raft request' (duration: 403.454368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:00.254553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.210692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:07:00.25461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:59.850744Z","time spent":"403.782196ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:00.254689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.662541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:07:00.254595Z","caller":"traceutil/trace.go:171","msg":"trace[388641964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"291.290253ms","start":"2026-07-30T21:06:59.963293Z","end":"2026-07-30T21:07:00.254583Z","steps":["trace[388641964] 'agreement among raft nodes before linearized reading' (duration: 291.214421ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:00.254775Z","caller":"traceutil/trace.go:171","msg":"trace[1483179261] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1282; }","duration":"286.801243ms","start":"2026-07-30T21:06:59.967954Z","end":"2026-07-30T21:07:00.254756Z","steps":["trace[1483179261] 'agreement among raft nodes before linearized reading' (duration: 286.629489ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:01.243083Z","caller":"traceutil/trace.go:171","msg":"trace[336450366] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"126.167779ms","start":"2026-07-30T21:07:01.11689Z","end":"2026-07-30T21:07:01.243057Z","steps":["trace[336450366] 'process raft request' (duration: 27.415263ms)","trace[336450366] 'compare' (duration: 98.597932ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:01.997446Z","caller":"traceutil/trace.go:171","msg":"trace[971548976] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"744.453762ms","start":"2026-07-30T21:07:01.25297Z","end":"2026-07-30T21:07:01.997424Z","steps":["trace[971548976] 'process raft request' (duration: 738.602099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:01.997556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:01.252947Z","time spent":"744.551824ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:01.997559Z","caller":"traceutil/trace.go:171","msg":"trace[1071335159] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"744.486062ms","start":"2026-07-30T21:07:01.253021Z","end":"2026-07-30T21:07:01.997507Z","steps":["trace[1071335159] 'process raft request' (duration: 744.325238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:01.997766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:01.252991Z","time spent":"744.672807ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:02.95286Z","caller":"traceutil/trace.go:171","msg":"trace[1600606275] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"219.51048ms","start":"2026-07-30T21:07:02.733332Z","end":"2026-07-30T21:07:02.952843Z","steps":["trace[1600606275] 'read index received' (duration: 219.428738ms)","trace[1600606275] 'applied index is now lower than readState.Index' (duration: 80.972µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:02.952957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.613712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:07:02.952976Z","caller":"traceutil/trace.go:171","msg":"trace[679317936] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1289; }","duration":"219.696774ms","start":"2026-07-30T21:07:02.733274Z","end":"2026-07-30T21:07:02.952971Z","steps":["trace[679317936] 'agreement among raft nodes before linearized reading' (duration: 219.633912ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:02.952987Z","caller":"traceutil/trace.go:171","msg":"trace[264508588] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"301.899545ms","start":"2026-07-30T21:07:02.651055Z","end":"2026-07-30T21:07:02.952955Z","steps":["trace[264508588] 'process raft request' (duration: 301.667929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:02.953176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:02.651037Z","time spent":"302.030577ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:04.085554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.330189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:07:04.086157Z","caller":"traceutil/trace.go:171","msg":"trace[1015095397] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"119.035653ms","start":"2026-07-30T21:07:03.96658Z","end":"2026-07-30T21:07:04.085615Z","steps":["trace[1015095397] 'read index received' (duration: 33.651µs)","trace[1015095397] 'applied index is now lower than readState.Index' (duration: 119.001172ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:04.086181Z","caller":"traceutil/trace.go:171","msg":"trace[1545085049] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"699.009746ms","start":"2026-07-30T21:07:03.386897Z","end":"2026-07-30T21:07:04.085906Z","steps":["trace[1545085049] 'process raft request' (duration: 463.263666ms)","trace[1545085049] 'compare' (duration: 235.182756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:04.086219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.646858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:07:04.086243Z","caller":"traceutil/trace.go:171","msg":"trace[1853566333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"120.299645ms","start":"2026-07-30T21:07:03.965936Z","end":"2026-07-30T21:07:04.086236Z","steps":["trace[1853566333] 'agreement among raft nodes before linearized reading' (duration: 120.259693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.086325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:03.386877Z","time spent":"699.363845ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:04.456087Z","caller":"traceutil/trace.go:171","msg":"trace[45481660] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"385.227791ms","start":"2026-07-30T21:07:04.070844Z","end":"2026-07-30T21:07:04.456072Z","steps":["trace[45481660] 'process raft request' (duration: 385.002794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.456216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.070818Z","time spent":"385.334234ms","remote":"127.0.0.1:46868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:07:04.456278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.478085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T21:07:04.456298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.553478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T21:07:04.456353Z","caller":"traceutil/trace.go:171","msg":"trace[1819539090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"455.605619ms","start":"2026-07-30T21:07:04.000731Z","end":"2026-07-30T21:07:04.456337Z","steps":["trace[1819539090] 'agreement among raft nodes before linearized reading' (duration: 455.362373ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:04.456362Z","caller":"traceutil/trace.go:171","msg":"trace[1619573663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"455.650229ms","start":"2026-07-30T21:07:04.000695Z","end":"2026-07-30T21:07:04.456346Z","steps":["trace[1619573663] 'agreement among raft nodes before linearized reading' (duration: 455.491635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.456397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.00071Z","time spent":"455.67845ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:07:04.456398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.000682Z","time spent":"455.708491ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:07:04.456037Z","caller":"traceutil/trace.go:171","msg":"trace[1296969165] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"369.838003ms","start":"2026-07-30T21:07:04.086176Z","end":"2026-07-30T21:07:04.456014Z","steps":["trace[1296969165] 'read index received' (duration: 369.696259ms)","trace[1296969165] 'applied index is now lower than readState.Index' (duration: 140.564µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:04.456891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.071224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:07:04.45694Z","caller":"traceutil/trace.go:171","msg":"trace[425168830] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1293; }","duration":"367.140545ms","start":"2026-07-30T21:07:04.089787Z","end":"2026-07-30T21:07:04.456928Z","steps":["trace[425168830] 'agreement among raft nodes before linearized reading' (duration: 367.057503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.456962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.543605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:07:04.456985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.089781Z","time spent":"367.195268ms","remote":"127.0.0.1:46946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:07:04.457011Z","caller":"traceutil/trace.go:171","msg":"trace[1249974480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"369.618247ms","start":"2026-07-30T21:07:04.087381Z","end":"2026-07-30T21:07:04.456999Z","steps":["trace[1249974480] 'agreement among raft nodes before linearized reading' (duration: 369.458143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.457051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.087339Z","time spent":"369.705639ms","remote":"127.0.0.1:46778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:07:04.457171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.485298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:07:04.457256Z","caller":"traceutil/trace.go:171","msg":"trace[990697190] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:1293; }","duration":"291.621221ms","start":"2026-07-30T21:07:04.165621Z","end":"2026-07-30T21:07:04.457243Z","steps":["trace[990697190] 'agreement among raft nodes before linearized reading' (duration: 291.221471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.783531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.458729Z","time spent":"324.797486ms","remote":"127.0.0.1:46814","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-30T21:07:04.790282Z","caller":"traceutil/trace.go:171","msg":"trace[1306608406] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"329.628945ms","start":"2026-07-30T21:07:04.460635Z","end":"2026-07-30T21:07:04.790264Z","steps":["trace[1306608406] 'process raft request' (duration: 329.594764ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:04.79041Z","caller":"traceutil/trace.go:171","msg":"trace[1921872322] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"329.865391ms","start":"2026-07-30T21:07:04.460477Z","end":"2026-07-30T21:07:04.790342Z","steps":["trace[1921872322] 'process raft request' (duration: 329.632966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:04.790492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.460618Z","time spent":"329.791579ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:04.790647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:04.460458Z","time spent":"330.087946ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:06.179262Z","caller":"traceutil/trace.go:171","msg":"trace[158100080] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"105.715207ms","start":"2026-07-30T21:07:06.073517Z","end":"2026-07-30T21:07:06.179232Z","steps":["trace[158100080] 'process raft request' (duration: 105.117702ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:13.327059Z","caller":"traceutil/trace.go:171","msg":"trace[513012791] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"342.403548ms","start":"2026-07-30T21:07:12.984615Z","end":"2026-07-30T21:07:13.327018Z","steps":["trace[513012791] 'process raft request' (duration: 247.010006ms)","trace[513012791] 'compare' (duration: 95.100385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:13.327212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:12.984587Z","time spent":"342.543692ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:14.002605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218641565,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:07:14.20865Z","caller":"traceutil/trace.go:171","msg":"trace[96339178] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"706.07457ms","start":"2026-07-30T21:07:13.502551Z","end":"2026-07-30T21:07:14.208626Z","steps":["trace[96339178] 'read index received' (duration: 609.661421ms)","trace[96339178] 'applied index is now lower than readState.Index' (duration: 96.411889ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:14.208874Z","caller":"traceutil/trace.go:171","msg":"trace[380283923] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"779.03034ms","start":"2026-07-30T21:07:13.42983Z","end":"2026-07-30T21:07:14.20886Z","steps":["trace[380283923] 'process raft request' (duration: 682.442758ms)","trace[380283923] 'compare' (duration: 95.816414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:14.208975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:13.429802Z","time spent":"779.101042ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:14.209101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.263283ms","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-07-30T21:07:14.20919Z","caller":"traceutil/trace.go:171","msg":"trace[428961873] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"651.395456ms","start":"2026-07-30T21:07:13.557771Z","end":"2026-07-30T21:07:14.209166Z","steps":["trace[428961873] 'agreement among raft nodes before linearized reading' (duration: 651.15048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:14.209245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:13.557756Z","time spent":"651.474428ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:07:14.209483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.93084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:07:14.209532Z","caller":"traceutil/trace.go:171","msg":"trace[1308670129] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1367; }","duration":"707.022992ms","start":"2026-07-30T21:07:13.502498Z","end":"2026-07-30T21:07:14.209521Z","steps":["trace[1308670129] 'agreement among raft nodes before linearized reading' (duration: 706.9337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:14.209537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.669928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T21:07:14.209564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:13.50248Z","time spent":"707.075903ms","remote":"127.0.0.1:46890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:07:14.209564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.080958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:07:14.209596Z","caller":"traceutil/trace.go:171","msg":"trace[1623623025] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1367; }","duration":"560.761761ms","start":"2026-07-30T21:07:13.648819Z","end":"2026-07-30T21:07:14.209581Z","steps":["trace[1623623025] 'agreement among raft nodes before linearized reading' (duration: 560.660438ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:14.209626Z","caller":"traceutil/trace.go:171","msg":"trace[1697632567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"245.1769ms","start":"2026-07-30T21:07:13.964435Z","end":"2026-07-30T21:07:14.209612Z","steps":["trace[1697632567] 'agreement among raft nodes before linearized reading' (duration: 244.977346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:14.209647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:13.648805Z","time spent":"560.826432ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:09:28.19177Z","caller":"traceutil/trace.go:171","msg":"trace[1740530192] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"599.918801ms","start":"2026-07-30T21:09:27.59182Z","end":"2026-07-30T21:09:28.191739Z","steps":["trace[1740530192] 'process raft request' (duration: 599.721616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:28.191941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:09:27.591802Z","time spent":"600.053405ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:09:28.250093Z","caller":"traceutil/trace.go:171","msg":"trace[1990109084] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2064; }","duration":"522.920167ms","start":"2026-07-30T21:09:27.727145Z","end":"2026-07-30T21:09:28.250065Z","steps":["trace[1990109084] 'read index received' (duration: 464.429633ms)","trace[1990109084] 'applied index is now lower than readState.Index' (duration: 58.489004ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:09:28.250224Z","caller":"traceutil/trace.go:171","msg":"trace[2049913584] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"657.272126ms","start":"2026-07-30T21:09:27.592942Z","end":"2026-07-30T21:09:28.250214Z","steps":["trace[2049913584] 'process raft request' (duration: 656.261102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:28.250284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.111311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:09:28.250377Z","caller":"traceutil/trace.go:171","msg":"trace[2053952426] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1984; }","duration":"523.216914ms","start":"2026-07-30T21:09:27.72714Z","end":"2026-07-30T21:09:28.250356Z","steps":["trace[2053952426] 'agreement among raft nodes before linearized reading' (duration: 523.077421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:28.250464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:09:27.727083Z","time spent":"523.324266ms","remote":"127.0.0.1:54276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:09:28.250472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:09:27.592933Z","time spent":"657.320347ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:09:28.250737Z","caller":"traceutil/trace.go:171","msg":"trace[855933582] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"489.527827ms","start":"2026-07-30T21:09:27.761197Z","end":"2026-07-30T21:09:28.250725Z","steps":["trace[855933582] 'process raft request' (duration: 488.716857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:28.250752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.837384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:09:28.250806Z","caller":"traceutil/trace.go:171","msg":"trace[303210520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1984; }","duration":"287.924337ms","start":"2026-07-30T21:09:27.962866Z","end":"2026-07-30T21:09:28.25079Z","steps":["trace[303210520] 'agreement among raft nodes before linearized reading' (duration: 287.839713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:28.250819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:09:27.761176Z","time spent":"489.587989ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:09:28.250984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.821377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-30T21:09:28.251013Z","caller":"traceutil/trace.go:171","msg":"trace[171003504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1984; }","duration":"269.889459ms","start":"2026-07-30T21:09:27.981112Z","end":"2026-07-30T21:09:28.251001Z","steps":["trace[171003504] 'agreement among raft nodes before linearized reading' (duration: 269.791006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:09:28.250983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.345161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:09:28.251085Z","caller":"traceutil/trace.go:171","msg":"trace[1420294173] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1984; }","duration":"225.489195ms","start":"2026-07-30T21:09:28.025567Z","end":"2026-07-30T21:09:28.251056Z","steps":["trace[1420294173] 'agreement among raft nodes before linearized reading' (duration: 225.30177ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:09:30.734191Z","caller":"traceutil/trace.go:171","msg":"trace[249039751] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"240.633932ms","start":"2026-07-30T21:09:30.493534Z","end":"2026-07-30T21:09:30.734168Z","steps":["trace[249039751] 'process raft request' (duration: 240.475048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:24.004708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218644468,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:11:24.033154Z","caller":"traceutil/trace.go:171","msg":"trace[1031533946] linearizableReadLoop","detail":"{readStateIndex:2645; appliedIndex:2644; }","duration":"528.726887ms","start":"2026-07-30T21:11:23.504401Z","end":"2026-07-30T21:11:24.033128Z","steps":["trace[1031533946] 'read index received' (duration: 528.377658ms)","trace[1031533946] 'applied index is now lower than readState.Index' (duration: 347.639µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:24.033219Z","caller":"traceutil/trace.go:171","msg":"trace[1559888018] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"600.084981ms","start":"2026-07-30T21:11:23.433104Z","end":"2026-07-30T21:11:24.033189Z","steps":["trace[1559888018] 'process raft request' (duration: 599.792514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:24.033409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.992923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-30T21:11:24.03365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:23.433082Z","time spent":"600.465331ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:11:24.033667Z","caller":"traceutil/trace.go:171","msg":"trace[310507314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2538; }","duration":"529.306271ms","start":"2026-07-30T21:11:23.504344Z","end":"2026-07-30T21:11:24.03365Z","steps":["trace[310507314] 'agreement among raft nodes before linearized reading' (duration: 528.917071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:24.03377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:23.504323Z","time spent":"529.434104ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:11:24.034195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.239543ms","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-07-30T21:11:24.034329Z","caller":"traceutil/trace.go:171","msg":"trace[759719] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2538; }","duration":"528.420618ms","start":"2026-07-30T21:11:23.505879Z","end":"2026-07-30T21:11:24.0343Z","steps":["trace[759719] 'agreement among raft nodes before linearized reading' (duration: 527.968707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:24.034402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:23.505864Z","time spent":"528.52638ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T21:11:47.233288Z","caller":"traceutil/trace.go:171","msg":"trace[880479971] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"106.411618ms","start":"2026-07-30T21:11:47.126853Z","end":"2026-07-30T21:11:47.233265Z","steps":["trace[880479971] 'process raft request' (duration: 104.173662ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:49.149117Z","caller":"traceutil/trace.go:171","msg":"trace[155050801] linearizableReadLoop","detail":"{readStateIndex:2773; appliedIndex:2772; }","duration":"374.979128ms","start":"2026-07-30T21:11:48.774116Z","end":"2026-07-30T21:11:49.149095Z","steps":["trace[155050801] 'read index received' (duration: 374.813274ms)","trace[155050801] 'applied index is now lower than readState.Index' (duration: 165.024µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:11:49.149313Z","caller":"traceutil/trace.go:171","msg":"trace[407865903] transaction","detail":"{read_only:false; response_revision:2660; number_of_response:1; }","duration":"403.041124ms","start":"2026-07-30T21:11:48.746256Z","end":"2026-07-30T21:11:49.149298Z","steps":["trace[407865903] 'process raft request' (duration: 402.715086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:49.149578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:48.74623Z","time spent":"403.212329ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:11:49.149642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.530831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"warn","ts":"2026-07-30T21:11:49.149666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.791182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:791"} {"level":"warn","ts":"2026-07-30T21:11:49.149704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.363079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T21:11:49.149726Z","caller":"traceutil/trace.go:171","msg":"trace[1666873125] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:2660; }","duration":"375.677325ms","start":"2026-07-30T21:11:48.774029Z","end":"2026-07-30T21:11:49.149706Z","steps":["trace[1666873125] 'agreement among raft nodes before linearized reading' (duration: 375.532282ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:49.149748Z","caller":"traceutil/trace.go:171","msg":"trace[1880083169] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:2660; }","duration":"372.915166ms","start":"2026-07-30T21:11:48.77681Z","end":"2026-07-30T21:11:49.149725Z","steps":["trace[1880083169] 'agreement among raft nodes before linearized reading' (duration: 372.730001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:49.149784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:48.776802Z","time spent":"372.974197ms","remote":"127.0.0.1:47164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":815,"request content":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" "} {"level":"warn","ts":"2026-07-30T21:11:49.149783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:48.774008Z","time spent":"375.762627ms","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":773,"request content":"key:\"/registry/clusterrolebindings/cilium\" "} {"level":"info","ts":"2026-07-30T21:11:49.14974Z","caller":"traceutil/trace.go:171","msg":"trace[2000415657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2660; }","duration":"205.434871ms","start":"2026-07-30T21:11:48.944292Z","end":"2026-07-30T21:11:49.149727Z","steps":["trace[2000415657] 'agreement among raft nodes before linearized reading' (duration: 205.320388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:49.149851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.506778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:11:49.149926Z","caller":"traceutil/trace.go:171","msg":"trace[741703473] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2660; }","duration":"330.610131ms","start":"2026-07-30T21:11:48.819299Z","end":"2026-07-30T21:11:49.149909Z","steps":["trace[741703473] 'agreement among raft nodes before linearized reading' (duration: 330.489038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:49.149974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:48.819286Z","time spent":"330.674012ms","remote":"127.0.0.1:46990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:11:51.594587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.631547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-07-30T21:11:51.594688Z","caller":"traceutil/trace.go:171","msg":"trace[736173247] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:2669; }","duration":"457.777091ms","start":"2026-07-30T21:11:51.136888Z","end":"2026-07-30T21:11:51.594665Z","steps":["trace[736173247] 'range keys from in-memory index tree' (duration: 457.475602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:51.594743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.136867Z","time spent":"457.861343ms","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":721,"request content":"key:\"/registry/clusterrolebindings/system:basic-user\" "} {"level":"warn","ts":"2026-07-30T21:11:51.594807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.143154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:11:51.59492Z","caller":"traceutil/trace.go:171","msg":"trace[951012430] linearizableReadLoop","detail":"{readStateIndex:2784; appliedIndex:2783; }","duration":"436.200297ms","start":"2026-07-30T21:11:51.158702Z","end":"2026-07-30T21:11:51.594903Z","steps":["trace[951012430] 'read index received' (duration: 17.757446ms)","trace[951012430] 'applied index is now lower than readState.Index' (duration: 418.441331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:11:51.595069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.357242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:698"} {"level":"warn","ts":"2026-07-30T21:11:51.595137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.863848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T21:11:51.595141Z","caller":"traceutil/trace.go:171","msg":"trace[820085676] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:2670; }","duration":"436.465015ms","start":"2026-07-30T21:11:51.158652Z","end":"2026-07-30T21:11:51.595117Z","steps":["trace[820085676] 'agreement among raft nodes before linearized reading' (duration: 436.341302ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:51.595172Z","caller":"traceutil/trace.go:171","msg":"trace[1887393902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2670; }","duration":"429.900059ms","start":"2026-07-30T21:11:51.165263Z","end":"2026-07-30T21:11:51.595163Z","steps":["trace[1887393902] 'agreement among raft nodes before linearized reading' (duration: 429.748985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:51.595191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.158634Z","time spent":"436.543196ms","remote":"127.0.0.1:47164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":722,"request content":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" "} {"level":"warn","ts":"2026-07-30T21:11:51.595207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.16522Z","time spent":"429.980242ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:11:51.595293Z","caller":"traceutil/trace.go:171","msg":"trace[279340695] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"438.768622ms","start":"2026-07-30T21:11:51.156498Z","end":"2026-07-30T21:11:51.595267Z","steps":["trace[279340695] 'process raft request' (duration: 20.034394ms)","trace[279340695] 'compare' (duration: 417.910767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:11:51.595413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.156473Z","time spent":"438.874685ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:11:51.595415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.746157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:11:51.595508Z","caller":"traceutil/trace.go:171","msg":"trace[1614975656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2670; }","duration":"350.868669ms","start":"2026-07-30T21:11:51.244623Z","end":"2026-07-30T21:11:51.595492Z","steps":["trace[1614975656] 'agreement among raft nodes before linearized reading' (duration: 350.631363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:51.595555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.24461Z","time spent":"350.932021ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T21:11:51.595708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.042297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T21:11:51.595746Z","caller":"traceutil/trace.go:171","msg":"trace[1381045436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2670; }","duration":"129.091678ms","start":"2026-07-30T21:11:51.466643Z","end":"2026-07-30T21:11:51.595735Z","steps":["trace[1381045436] 'agreement among raft nodes before linearized reading' (duration: 128.401591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.100847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778052144218644988,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:11:52.260561Z","caller":"traceutil/trace.go:171","msg":"trace[221712365] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"661.899167ms","start":"2026-07-30T21:11:51.598642Z","end":"2026-07-30T21:11:52.260541Z","steps":["trace[221712365] 'process raft request' (duration: 660.135414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.260716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.598618Z","time spent":"662.00819ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:11:52.261408Z","caller":"traceutil/trace.go:171","msg":"trace[2017686286] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"661.722834ms","start":"2026-07-30T21:11:51.599657Z","end":"2026-07-30T21:11:52.26138Z","steps":["trace[2017686286] 'process raft request' (duration: 661.534099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.261588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.599637Z","time spent":"661.855217ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:11:52.261584Z","caller":"traceutil/trace.go:171","msg":"trace[714844604] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"661.237471ms","start":"2026-07-30T21:11:51.600306Z","end":"2026-07-30T21:11:52.261543Z","steps":["trace[714844604] 'process raft request' (duration: 661.026386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.261826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.600298Z","time spent":"661.386625ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:11:52.262024Z","caller":"traceutil/trace.go:171","msg":"trace[2079073605] linearizableReadLoop","detail":"{readStateIndex:2787; appliedIndex:2784; }","duration":"662.154884ms","start":"2026-07-30T21:11:51.599842Z","end":"2026-07-30T21:11:52.261997Z","steps":["trace[2079073605] 'read index received' (duration: 658.952564ms)","trace[2079073605] 'applied index is now lower than readState.Index' (duration: 3.1991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:11:52.262205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.349169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:698"} {"level":"warn","ts":"2026-07-30T21:11:52.262216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.896174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:11:52.262244Z","caller":"traceutil/trace.go:171","msg":"trace[1787086989] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:2673; }","duration":"662.407721ms","start":"2026-07-30T21:11:51.599823Z","end":"2026-07-30T21:11:52.262231Z","steps":["trace[1787086989] 'agreement among raft nodes before linearized reading' (duration: 662.313819ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:11:52.26226Z","caller":"traceutil/trace.go:171","msg":"trace[685115796] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2673; }","duration":"643.978517ms","start":"2026-07-30T21:11:51.618269Z","end":"2026-07-30T21:11:52.262248Z","steps":["trace[685115796] 'agreement among raft nodes before linearized reading' (duration: 643.886244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.262284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.599817Z","time spent":"662.460312ms","remote":"127.0.0.1:47164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":722,"request content":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" "} {"level":"warn","ts":"2026-07-30T21:11:52.262295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.61826Z","time spent":"644.025178ms","remote":"127.0.0.1:37892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:11:52.262487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.922098ms","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":"warn","ts":"2026-07-30T21:11:52.262515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.396727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-07-30T21:11:52.26254Z","caller":"traceutil/trace.go:171","msg":"trace[193614853] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:2673; }","duration":"402.452209ms","start":"2026-07-30T21:11:51.860081Z","end":"2026-07-30T21:11:52.262533Z","steps":["trace[193614853] 'agreement among raft nodes before linearized reading' (duration: 402.392577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.262566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.860066Z","time spent":"402.494769ms","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":721,"request content":"key:\"/registry/clusterrolebindings/system:basic-user\" "} {"level":"warn","ts":"2026-07-30T21:11:52.262555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.790117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:11:52.262624Z","caller":"traceutil/trace.go:171","msg":"trace[475531014] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2673; }","duration":"640.882299ms","start":"2026-07-30T21:11:51.621724Z","end":"2026-07-30T21:11:52.262607Z","steps":["trace[475531014] 'agreement among raft nodes before linearized reading' (duration: 640.754035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.262665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.621714Z","time spent":"640.93955ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:11:52.262697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.027886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:11:52.262724Z","caller":"traceutil/trace.go:171","msg":"trace[858080367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2673; }","duration":"301.089807ms","start":"2026-07-30T21:11:51.961623Z","end":"2026-07-30T21:11:52.262713Z","steps":["trace[858080367] 'agreement among raft nodes before linearized reading' (duration: 301.050666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:11:52.262743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:11:51.961608Z","time spent":"301.131038ms","remote":"127.0.0.1:46772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:11:52.263713Z","caller":"traceutil/trace.go:171","msg":"trace[475452863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2673; }","duration":"125.17038ms","start":"2026-07-30T21:11:52.138524Z","end":"2026-07-30T21:11:52.263694Z","steps":["trace[475452863] 'agreement among raft nodes before linearized reading' (duration: 123.935769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:12:08.586889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.549611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:131 size:100959"} {"level":"info","ts":"2026-07-30T21:12:08.58695Z","caller":"traceutil/trace.go:171","msg":"trace[1976988873] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:131; response_revision:2751; }","duration":"104.632363ms","start":"2026-07-30T21:12:08.482303Z","end":"2026-07-30T21:12:08.586935Z","steps":["trace[1976988873] 'agreement among raft nodes before linearized reading' (duration: 63.188469ms)","trace[1976988873] 'range keys from in-memory index tree' (duration: 41.033733ms)"],"step_count":2}