{"level":"warn","ts":"2026-07-09T13:13:59.091302Z","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-09T13:13:59.091442Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.206: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.206:2380","--initial-cluster=instance=https://199.19.213.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.206: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-09T13:13:59.091539Z","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-09T13:13:59.091553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-07-09T13:13:59.09158Z","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-09T13:13:59.092606Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"]} {"level":"info","ts":"2026-07-09T13:13:59.092718Z","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.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.206: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-09T13:13:59.097125Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.096554ms"} {"level":"info","ts":"2026-07-09T13:13:59.1134Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b696d7a595057bfe","cluster-id":"9b00ba6f3f3b7ac2"} {"level":"info","ts":"2026-07-09T13:13:59.113601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T13:13:59.113643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 0"} {"level":"info","ts":"2026-07-09T13:13:59.113667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b696d7a595057bfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T13:13:59.11368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 1"} {"level":"info","ts":"2026-07-09T13:13:59.113746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"warn","ts":"2026-07-09T13:13:59.123983Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T13:13:59.125869Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T13:13:59.127352Z","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-09T13:13:59.130715Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b696d7a595057bfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T13:13:59.131233Z","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-09T13:13:59.131332Z","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-09T13:13:59.131353Z","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-09T13:13:59.131402Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b696d7a595057bfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T13:13:59.135176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"info","ts":"2026-07-09T13:13:59.138088Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","added-peer-id":"b696d7a595057bfe","added-peer-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-07-09T13:13:59.140481Z","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-09T13:13:59.140568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-07-09T13:13:59.14063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-07-09T13:13:59.141015Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b696d7a595057bfe","initial-advertise-peer-urls":["https://199.19.213.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T13:13:59.141088Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T13:13:59.614362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T13:13:59.614418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T13:13:59.614461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgPreVoteResp from b696d7a595057bfe at term 1"} {"level":"info","ts":"2026-07-09T13:13:59.61448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became candidate at term 2"} {"level":"info","ts":"2026-07-09T13:13:59.614497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgVoteResp from b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-07-09T13:13:59.614513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became leader at term 2"} {"level":"info","ts":"2026-07-09T13:13:59.614534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b696d7a595057bfe elected leader b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-07-09T13:13:59.63069Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b696d7a595057bfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.206:2379]}","request-path":"/0/members/b696d7a595057bfe/attributes","cluster-id":"9b00ba6f3f3b7ac2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T13:13:59.630886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:13:59.630978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:13:59.631171Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:13:59.632949Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:13:59.63313Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:13:59.633203Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:13:59.633624Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.206:2379"} {"level":"info","ts":"2026-07-09T13:13:59.634218Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T13:13:59.645564Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T13:13:59.645614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T13:14:02.643508Z","caller":"traceutil/trace.go:171","msg":"trace[168345628] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"176.118893ms","start":"2026-07-09T13:14:02.467365Z","end":"2026-07-09T13:14:02.643484Z","steps":["trace[168345628] 'process raft request' (duration: 83.993757ms)","trace[168345628] 'compare' (duration: 92.016174ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:02.835343Z","caller":"traceutil/trace.go:171","msg":"trace[1521611061] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"128.55048ms","start":"2026-07-09T13:14:02.706759Z","end":"2026-07-09T13:14:02.83531Z","steps":["trace[1521611061] 'process raft request' (duration: 64.74478ms)","trace[1521611061] 'compare' (duration: 63.627466ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:09.166248Z","caller":"traceutil/trace.go:171","msg":"trace[1111116404] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:356; }","duration":"109.824077ms","start":"2026-07-09T13:14:09.056402Z","end":"2026-07-09T13:14:09.166226Z","steps":["trace[1111116404] 'read index received' (duration: 43.741974ms)","trace[1111116404] 'applied index is now lower than readState.Index' (duration: 66.081102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:14:09.166437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.018291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-09T13:14:09.166562Z","caller":"traceutil/trace.go:171","msg":"trace[989138599] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:345; }","duration":"110.187775ms","start":"2026-07-09T13:14:09.056351Z","end":"2026-07-09T13:14:09.166539Z","steps":["trace[989138599] 'agreement among raft nodes before linearized reading' (duration: 110.020581ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:09.166663Z","caller":"traceutil/trace.go:171","msg":"trace[841386103] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"111.383442ms","start":"2026-07-09T13:14:09.055222Z","end":"2026-07-09T13:14:09.166605Z","steps":["trace[841386103] 'process raft request' (duration: 110.01867ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:09.16719Z","caller":"traceutil/trace.go:171","msg":"trace[1483773351] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"109.57003ms","start":"2026-07-09T13:14:09.057529Z","end":"2026-07-09T13:14:09.167099Z","steps":["trace[1483773351] 'process raft request' (duration: 108.641899ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:18.732824Z","caller":"traceutil/trace.go:171","msg":"trace[875867643] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"128.190852ms","start":"2026-07-09T13:14:18.604515Z","end":"2026-07-09T13:14:18.732706Z","steps":["trace[875867643] 'process raft request' (duration: 128.006126ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:23.474525Z","caller":"traceutil/trace.go:171","msg":"trace[1290257662] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"313.987148ms","start":"2026-07-09T13:14:23.160513Z","end":"2026-07-09T13:14:23.474501Z","steps":["trace[1290257662] 'read index received' (duration: 313.800383ms)","trace[1290257662] 'applied index is now lower than readState.Index' (duration: 185.505µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:14:23.474565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:23.152452Z","time spent":"322.105392ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-09T13:14:23.474714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.178154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-09T13:14:23.474758Z","caller":"traceutil/trace.go:171","msg":"trace[1268506728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:432; }","duration":"234.274175ms","start":"2026-07-09T13:14:23.240474Z","end":"2026-07-09T13:14:23.474748Z","steps":["trace[1268506728] 'agreement among raft nodes before linearized reading' (duration: 234.172173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:23.474751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.194842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T13:14:23.474845Z","caller":"traceutil/trace.go:171","msg":"trace[1540472132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:432; }","duration":"314.334676ms","start":"2026-07-09T13:14:23.160491Z","end":"2026-07-09T13:14:23.474826Z","steps":["trace[1540472132] 'agreement among raft nodes before linearized reading' (duration: 314.138702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:23.474894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:23.160481Z","time spent":"314.404388ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T13:14:33.732256Z","caller":"traceutil/trace.go:171","msg":"trace[994785316] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"171.890991ms","start":"2026-07-09T13:14:33.560344Z","end":"2026-07-09T13:14:33.732235Z","steps":["trace[994785316] 'read index received' (duration: 171.699126ms)","trace[994785316] 'applied index is now lower than readState.Index' (duration: 191.035µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:14:33.732368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.009344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:14:33.732402Z","caller":"traceutil/trace.go:171","msg":"trace[1846083543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:486; }","duration":"172.081146ms","start":"2026-07-09T13:14:33.560313Z","end":"2026-07-09T13:14:33.732394Z","steps":["trace[1846083543] 'agreement among raft nodes before linearized reading' (duration: 172.003384ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:33.732274Z","caller":"traceutil/trace.go:171","msg":"trace[1515582868] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"173.777166ms","start":"2026-07-09T13:14:33.558479Z","end":"2026-07-09T13:14:33.732257Z","steps":["trace[1515582868] 'process raft request' (duration: 173.637803ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:37.511994Z","caller":"traceutil/trace.go:171","msg":"trace[1514051236] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"120.386836ms","start":"2026-07-09T13:14:37.391576Z","end":"2026-07-09T13:14:37.511963Z","steps":["trace[1514051236] 'process raft request' (duration: 120.107829ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:37.512452Z","caller":"traceutil/trace.go:171","msg":"trace[1970225819] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"120.671613ms","start":"2026-07-09T13:14:37.391763Z","end":"2026-07-09T13:14:37.512435Z","steps":["trace[1970225819] 'process raft request' (duration: 120.069859ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:37.51255Z","caller":"traceutil/trace.go:171","msg":"trace[609266437] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"103.324747ms","start":"2026-07-09T13:14:37.409204Z","end":"2026-07-09T13:14:37.512528Z","steps":["trace[609266437] 'process raft request' (duration: 102.793375ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:37.512686Z","caller":"traceutil/trace.go:171","msg":"trace[573586847] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"107.225011ms","start":"2026-07-09T13:14:37.405447Z","end":"2026-07-09T13:14:37.512672Z","steps":["trace[573586847] 'process raft request' (duration: 106.438452ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:37.512734Z","caller":"traceutil/trace.go:171","msg":"trace[163719686] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"105.720184ms","start":"2026-07-09T13:14:37.406997Z","end":"2026-07-09T13:14:37.512717Z","steps":["trace[163719686] 'process raft request' (duration: 104.938835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:38.480986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.799485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:14:38.481254Z","caller":"traceutil/trace.go:171","msg":"trace[545156466] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"664.377818ms","start":"2026-07-09T13:14:37.816853Z","end":"2026-07-09T13:14:38.481231Z","steps":["trace[545156466] 'compare' (duration: 653.511338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:38.481341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:37.816826Z","time spent":"664.47051ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:14:38.492813Z","caller":"traceutil/trace.go:171","msg":"trace[994314905] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"639.366939ms","start":"2026-07-09T13:14:37.853422Z","end":"2026-07-09T13:14:38.492789Z","steps":["trace[994314905] 'process raft request' (duration: 639.107323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:38.493291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:37.853397Z","time spent":"639.811339ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:14:39.552892Z","caller":"traceutil/trace.go:171","msg":"trace[945300315] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"343.572347ms","start":"2026-07-09T13:14:39.209301Z","end":"2026-07-09T13:14:39.552874Z","steps":["trace[945300315] 'process raft request' (duration: 340.522084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.553013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:39.209277Z","time spent":"343.6717ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:14:39.558683Z","caller":"traceutil/trace.go:171","msg":"trace[1696142928] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"247.610607ms","start":"2026-07-09T13:14:39.311047Z","end":"2026-07-09T13:14:39.558658Z","steps":["trace[1696142928] 'read index received' (duration: 238.782335ms)","trace[1696142928] 'applied index is now lower than readState.Index' (duration: 8.827332ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:39.558897Z","caller":"traceutil/trace.go:171","msg":"trace[1594785195] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"200.352464ms","start":"2026-07-09T13:14:39.358501Z","end":"2026-07-09T13:14:39.558853Z","steps":["trace[1594785195] 'process raft request' (duration: 198.898108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.558934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.871353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.55898Z","caller":"traceutil/trace.go:171","msg":"trace[535573777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:605; }","duration":"247.948935ms","start":"2026-07-09T13:14:39.311018Z","end":"2026-07-09T13:14:39.558967Z","steps":["trace[535573777] 'agreement among raft nodes before linearized reading' (duration: 247.849003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.917964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.559084Z","caller":"traceutil/trace.go:171","msg":"trace[1762596712] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"343.909206ms","start":"2026-07-09T13:14:39.215165Z","end":"2026-07-09T13:14:39.559075Z","steps":["trace[1762596712] 'process raft request' (duration: 341.99752ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:39.559086Z","caller":"traceutil/trace.go:171","msg":"trace[2099852833] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:605; }","duration":"248.010417ms","start":"2026-07-09T13:14:39.311063Z","end":"2026-07-09T13:14:39.559073Z","steps":["trace[2099852833] 'agreement among raft nodes before linearized reading' (duration: 247.877544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:39.215127Z","time spent":"343.977797ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:14:39.559338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.567163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.559371Z","caller":"traceutil/trace.go:171","msg":"trace[1523465127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"103.611915ms","start":"2026-07-09T13:14:39.455751Z","end":"2026-07-09T13:14:39.559363Z","steps":["trace[1523465127] 'agreement among raft nodes before linearized reading' (duration: 103.560414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.082246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.559623Z","caller":"traceutil/trace.go:171","msg":"trace[1458088042] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"104.127887ms","start":"2026-07-09T13:14:39.455481Z","end":"2026-07-09T13:14:39.559609Z","steps":["trace[1458088042] 'agreement among raft nodes before linearized reading' (duration: 104.068025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.503039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.55968Z","caller":"traceutil/trace.go:171","msg":"trace[1011638660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:605; }","duration":"158.549391ms","start":"2026-07-09T13:14:39.401122Z","end":"2026-07-09T13:14:39.559672Z","steps":["trace[1011638660] 'agreement among raft nodes before linearized reading' (duration: 158.51336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.29248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.559782Z","caller":"traceutil/trace.go:171","msg":"trace[1603787104] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"104.332142ms","start":"2026-07-09T13:14:39.455445Z","end":"2026-07-09T13:14:39.559777Z","steps":["trace[1603787104] 'agreement among raft nodes before linearized reading' (duration: 104.307801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.680125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:14:39.55985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.780627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.559848Z","caller":"traceutil/trace.go:171","msg":"trace[1834062810] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:605; }","duration":"158.764037ms","start":"2026-07-09T13:14:39.401068Z","end":"2026-07-09T13:14:39.559832Z","steps":["trace[1834062810] 'agreement among raft nodes before linearized reading' (duration: 158.688585ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:39.559865Z","caller":"traceutil/trace.go:171","msg":"trace[1940079619] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:605; }","duration":"128.845149ms","start":"2026-07-09T13:14:39.431015Z","end":"2026-07-09T13:14:39.55986Z","steps":["trace[1940079619] 'agreement among raft nodes before linearized reading' (duration: 128.823439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.559952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.832773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.559982Z","caller":"traceutil/trace.go:171","msg":"trace[1049099506] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:605; }","duration":"157.869814ms","start":"2026-07-09T13:14:39.402101Z","end":"2026-07-09T13:14:39.559971Z","steps":["trace[1049099506] 'agreement among raft nodes before linearized reading' (duration: 157.818363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.560005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.215183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.560034Z","caller":"traceutil/trace.go:171","msg":"trace[1700015459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:605; }","duration":"198.259413ms","start":"2026-07-09T13:14:39.361766Z","end":"2026-07-09T13:14:39.560025Z","steps":["trace[1700015459] 'agreement among raft nodes before linearized reading' (duration: 198.211252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.560061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.791851ms","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-09T13:14:39.560079Z","caller":"traceutil/trace.go:171","msg":"trace[1942832081] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:605; }","duration":"129.810522ms","start":"2026-07-09T13:14:39.430264Z","end":"2026-07-09T13:14:39.560074Z","steps":["trace[1942832081] 'agreement among raft nodes before linearized reading' (duration: 129.779801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.560091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.850483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.560117Z","caller":"traceutil/trace.go:171","msg":"trace[1099285435] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:605; }","duration":"129.895113ms","start":"2026-07-09T13:14:39.430215Z","end":"2026-07-09T13:14:39.56011Z","steps":["trace[1099285435] 'agreement among raft nodes before linearized reading' (duration: 129.854802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.560147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.400737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.560163Z","caller":"traceutil/trace.go:171","msg":"trace[1780763181] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:605; }","duration":"198.442167ms","start":"2026-07-09T13:14:39.361715Z","end":"2026-07-09T13:14:39.560157Z","steps":["trace[1780763181] 'agreement among raft nodes before linearized reading' (duration: 198.418167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:39.56026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.934949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:39.560272Z","caller":"traceutil/trace.go:171","msg":"trace[2106873526] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:605; }","duration":"248.957109ms","start":"2026-07-09T13:14:39.311312Z","end":"2026-07-09T13:14:39.560269Z","steps":["trace[2106873526] 'agreement among raft nodes before linearized reading' (duration: 248.903658ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:45.037065Z","caller":"traceutil/trace.go:171","msg":"trace[465210607] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"281.231802ms","start":"2026-07-09T13:14:44.755781Z","end":"2026-07-09T13:14:45.037013Z","steps":["trace[465210607] 'read index received' (duration: 273.24717ms)","trace[465210607] 'applied index is now lower than readState.Index' (duration: 7.982832ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:45.037498Z","caller":"traceutil/trace.go:171","msg":"trace[1815208209] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"707.155393ms","start":"2026-07-09T13:14:44.329998Z","end":"2026-07-09T13:14:45.037153Z","steps":["trace[1815208209] 'process raft request' (duration: 700.3204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:45.037751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:44.329979Z","time spent":"707.647246ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:14:45.037305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.491359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:45.038159Z","caller":"traceutil/trace.go:171","msg":"trace[1032930777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"282.405301ms","start":"2026-07-09T13:14:44.755735Z","end":"2026-07-09T13:14:45.038141Z","steps":["trace[1032930777] 'agreement among raft nodes before linearized reading' (duration: 281.495669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:46.25502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934753838099746425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:14:46.755676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934753838099746425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:14:47.20231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.135295342s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T13:14:47.257343Z","caller":"traceutil/trace.go:171","msg":"trace[984224663] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.208117868s","start":"2026-07-09T13:14:45.049192Z","end":"2026-07-09T13:14:47.25731Z","steps":["trace[984224663] 'process raft request' (duration: 2.153421797s)","trace[984224663] 'compare' (duration: 54.256031ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:14:47.257501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.04917Z","time spent":"2.208259081s","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:14:47.257573Z","caller":"traceutil/trace.go:171","msg":"trace[1119494550] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"1.502978233s","start":"2026-07-09T13:14:45.754572Z","end":"2026-07-09T13:14:47.25755Z","steps":["trace[1119494550] 'read index received' (duration: 1.448046616s)","trace[1119494550] 'applied index is now lower than readState.Index' (duration: 54.930667ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:47.257732Z","caller":"traceutil/trace.go:171","msg":"trace[218934651] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.368078378s","start":"2026-07-09T13:14:45.88964Z","end":"2026-07-09T13:14:47.257718Z","steps":["trace[218934651] 'process raft request' (duration: 1.367841072s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:47.257792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.889616Z","time spent":"1.36814601s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:14:47.257874Z","caller":"traceutil/trace.go:171","msg":"trace[656054901] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.147170767s","start":"2026-07-09T13:14:45.110678Z","end":"2026-07-09T13:14:47.257848Z","steps":["trace[656054901] 'process raft request' (duration: 2.146484351s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:47.257919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503340552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:14:47.257969Z","caller":"traceutil/trace.go:171","msg":"trace[436645512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.503435824s","start":"2026-07-09T13:14:45.754522Z","end":"2026-07-09T13:14:47.257958Z","steps":["trace[436645512] 'agreement among raft nodes before linearized reading' (duration: 1.50330634s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:47.257981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.110659Z","time spent":"2.147267759s","remote":"127.0.0.1:41832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:14:47.257987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465254288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T13:14:47.25801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.7545Z","time spent":"1.503502216s","remote":"127.0.0.1:41596","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-09T13:14:47.258038Z","caller":"traceutil/trace.go:171","msg":"trace[233178770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"1.46533242s","start":"2026-07-09T13:14:45.792691Z","end":"2026-07-09T13:14:47.258024Z","steps":["trace[233178770] 'agreement among raft nodes before linearized reading' (duration: 1.465161586s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:47.25807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.792654Z","time spent":"1.465408682s","remote":"127.0.0.1:41916","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-09T13:14:47.258133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458315721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-09T13:14:47.258165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459672984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:14:47.258191Z","caller":"traceutil/trace.go:171","msg":"trace[1119987779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.459719236s","start":"2026-07-09T13:14:45.798463Z","end":"2026-07-09T13:14:47.258183Z","steps":["trace[1119987779] 'agreement among raft nodes before linearized reading' (duration: 1.459674695s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:47.258194Z","caller":"traceutil/trace.go:171","msg":"trace[121494448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.458385063s","start":"2026-07-09T13:14:45.799783Z","end":"2026-07-09T13:14:47.258168Z","steps":["trace[121494448] 'agreement among raft nodes before linearized reading' (duration: 1.458306132s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:14:47.258215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.798452Z","time spent":"1.459757096s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:14:47.258231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:14:45.799771Z","time spent":"1.458451945s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:14:47.258301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.246638ms","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-09T13:14:47.258342Z","caller":"traceutil/trace.go:171","msg":"trace[1991076794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"197.32174ms","start":"2026-07-09T13:14:47.061009Z","end":"2026-07-09T13:14:47.25833Z","steps":["trace[1991076794] 'agreement among raft nodes before linearized reading' (duration: 197.207988ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:52.305797Z","caller":"traceutil/trace.go:171","msg":"trace[1673042004] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"129.515245ms","start":"2026-07-09T13:14:52.176261Z","end":"2026-07-09T13:14:52.305777Z","steps":["trace[1673042004] 'process raft request' (duration: 129.405112ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:57.764276Z","caller":"traceutil/trace.go:171","msg":"trace[2027113331] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"155.281354ms","start":"2026-07-09T13:14:57.608966Z","end":"2026-07-09T13:14:57.764248Z","steps":["trace[2027113331] 'process raft request' (duration: 155.006496ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:58.693263Z","caller":"traceutil/trace.go:171","msg":"trace[1342199478] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"185.352583ms","start":"2026-07-09T13:14:58.507889Z","end":"2026-07-09T13:14:58.693241Z","steps":["trace[1342199478] 'process raft request' (duration: 185.21039ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:58.931627Z","caller":"traceutil/trace.go:171","msg":"trace[1892088756] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"220.157067ms","start":"2026-07-09T13:14:58.711419Z","end":"2026-07-09T13:14:58.931576Z","steps":["trace[1892088756] 'process raft request' (duration: 137.971787ms)","trace[1892088756] 'compare' (duration: 81.911684ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:59.092026Z","caller":"traceutil/trace.go:171","msg":"trace[263475486] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"148.04321ms","start":"2026-07-09T13:14:58.943948Z","end":"2026-07-09T13:14:59.091991Z","steps":["trace[263475486] 'process raft request' (duration: 147.950947ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:59.092493Z","caller":"traceutil/trace.go:171","msg":"trace[1388669439] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"153.106821ms","start":"2026-07-09T13:14:58.939366Z","end":"2026-07-09T13:14:59.092473Z","steps":["trace[1388669439] 'process raft request' (duration: 148.822848ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:59.725905Z","caller":"traceutil/trace.go:171","msg":"trace[1860626778] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"155.944609ms","start":"2026-07-09T13:14:59.569934Z","end":"2026-07-09T13:14:59.725878Z","steps":["trace[1860626778] 'process raft request' (duration: 99.575518ms)","trace[1860626778] 'compare' (duration: 56.183326ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:14:59.726234Z","caller":"traceutil/trace.go:171","msg":"trace[1668635155] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"155.121398ms","start":"2026-07-09T13:14:59.57109Z","end":"2026-07-09T13:14:59.726212Z","steps":["trace[1668635155] 'process raft request' (duration: 154.74699ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:59.726252Z","caller":"traceutil/trace.go:171","msg":"trace[1608484283] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"154.930624ms","start":"2026-07-09T13:14:59.57129Z","end":"2026-07-09T13:14:59.72622Z","steps":["trace[1608484283] 'process raft request' (duration: 154.79728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:00.905227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.325996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:00.905307Z","caller":"traceutil/trace.go:171","msg":"trace[932204660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"148.497171ms","start":"2026-07-09T13:15:00.756792Z","end":"2026-07-09T13:15:00.905289Z","steps":["trace[932204660] 'range keys from in-memory index tree' (duration: 148.129462ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.204623Z","caller":"traceutil/trace.go:171","msg":"trace[1934549109] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"109.197668ms","start":"2026-07-09T13:15:01.095402Z","end":"2026-07-09T13:15:01.204599Z","steps":["trace[1934549109] 'read index received' (duration: 108.958162ms)","trace[1934549109] 'applied index is now lower than readState.Index' (duration: 238.616µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:01.204679Z","caller":"traceutil/trace.go:171","msg":"trace[2078394030] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"195.066457ms","start":"2026-07-09T13:15:01.009569Z","end":"2026-07-09T13:15:01.204635Z","steps":["trace[2078394030] 'process raft request' (duration: 194.867643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:01.204938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.524656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6xvp5\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-07-09T13:15:01.204984Z","caller":"traceutil/trace.go:171","msg":"trace[448190495] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6xvp5; range_end:; response_count:1; response_revision:784; }","duration":"109.634968ms","start":"2026-07-09T13:15:01.095336Z","end":"2026-07-09T13:15:01.204971Z","steps":["trace[448190495] 'agreement among raft nodes before linearized reading' (duration: 109.353132ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.352975Z","caller":"traceutil/trace.go:171","msg":"trace[1132082573] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"136.700738ms","start":"2026-07-09T13:15:01.216251Z","end":"2026-07-09T13:15:01.352951Z","steps":["trace[1132082573] 'process raft request' (duration: 136.552874ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.524803Z","caller":"traceutil/trace.go:171","msg":"trace[1169977874] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"164.255038ms","start":"2026-07-09T13:15:01.360515Z","end":"2026-07-09T13:15:01.52477Z","steps":["trace[1169977874] 'process raft request' (duration: 111.277138ms)","trace[1169977874] 'compare' (duration: 52.738885ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:01.525155Z","caller":"traceutil/trace.go:171","msg":"trace[579139292] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"161.04683ms","start":"2026-07-09T13:15:01.364063Z","end":"2026-07-09T13:15:01.525109Z","steps":["trace[579139292] 'process raft request' (duration: 160.659491ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.770295Z","caller":"traceutil/trace.go:171","msg":"trace[298409867] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"116.692938ms","start":"2026-07-09T13:15:01.653575Z","end":"2026-07-09T13:15:01.770268Z","steps":["trace[298409867] 'read index received' (duration: 116.194705ms)","trace[298409867] 'applied index is now lower than readState.Index' (duration: 497.303µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:01.770437Z","caller":"traceutil/trace.go:171","msg":"trace[1692984563] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"237.111935ms","start":"2026-07-09T13:15:01.533313Z","end":"2026-07-09T13:15:01.770425Z","steps":["trace[1692984563] 'process raft request' (duration: 236.88057ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.770589Z","caller":"traceutil/trace.go:171","msg":"trace[1358667678] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"238.637723ms","start":"2026-07-09T13:15:01.531927Z","end":"2026-07-09T13:15:01.770565Z","steps":["trace[1358667678] 'process raft request' (duration: 237.899484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:01.770914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.327803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:15:01.771013Z","caller":"traceutil/trace.go:171","msg":"trace[1976128229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"117.474977ms","start":"2026-07-09T13:15:01.653523Z","end":"2026-07-09T13:15:01.770998Z","steps":["trace[1976128229] 'agreement among raft nodes before linearized reading' (duration: 117.262812ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.936769Z","caller":"traceutil/trace.go:171","msg":"trace[574315242] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"160.831355ms","start":"2026-07-09T13:15:01.775907Z","end":"2026-07-09T13:15:01.936738Z","steps":["trace[574315242] 'process raft request' (duration: 159.400871ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.936792Z","caller":"traceutil/trace.go:171","msg":"trace[2014145146] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"156.786689ms","start":"2026-07-09T13:15:01.779982Z","end":"2026-07-09T13:15:01.936769Z","steps":["trace[2014145146] 'process raft request' (duration: 156.726368ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.936849Z","caller":"traceutil/trace.go:171","msg":"trace[1736480517] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"159.991156ms","start":"2026-07-09T13:15:01.776815Z","end":"2026-07-09T13:15:01.936806Z","steps":["trace[1736480517] 'process raft request' (duration: 159.841692ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:01.937011Z","caller":"traceutil/trace.go:171","msg":"trace[1723358388] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"160.683143ms","start":"2026-07-09T13:15:01.776315Z","end":"2026-07-09T13:15:01.936998Z","steps":["trace[1723358388] 'process raft request' (duration: 160.296763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:03.427818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.384493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:03.427958Z","caller":"traceutil/trace.go:171","msg":"trace[493761201] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"206.880761ms","start":"2026-07-09T13:15:03.22106Z","end":"2026-07-09T13:15:03.427941Z","steps":["trace[493761201] 'read index received' (duration: 97.420226ms)","trace[493761201] 'applied index is now lower than readState.Index' (duration: 109.459335ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:03.428064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.008553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-09T13:15:03.428027Z","caller":"traceutil/trace.go:171","msg":"trace[93849352] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"212.375352ms","start":"2026-07-09T13:15:03.215602Z","end":"2026-07-09T13:15:03.427977Z","steps":["trace[93849352] 'process raft request' (duration: 102.757893ms)","trace[93849352] 'compare' (duration: 109.23779ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:03.428096Z","caller":"traceutil/trace.go:171","msg":"trace[806064001] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:799; }","duration":"207.072605ms","start":"2026-07-09T13:15:03.221012Z","end":"2026-07-09T13:15:03.428085Z","steps":["trace[806064001] 'agreement among raft nodes before linearized reading' (duration: 206.976953ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:05.993641Z","caller":"traceutil/trace.go:171","msg":"trace[1610903634] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"112.869386ms","start":"2026-07-09T13:15:05.880738Z","end":"2026-07-09T13:15:05.993607Z","steps":["trace[1610903634] 'process raft request' (duration: 112.717892ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:06.167504Z","caller":"traceutil/trace.go:171","msg":"trace[499260131] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"163.611652ms","start":"2026-07-09T13:15:06.003866Z","end":"2026-07-09T13:15:06.167478Z","steps":["trace[499260131] 'process raft request' (duration: 94.658079ms)","trace[499260131] 'compare' (duration: 68.756138ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:06.167876Z","caller":"traceutil/trace.go:171","msg":"trace[760736331] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"163.736025ms","start":"2026-07-09T13:15:06.004114Z","end":"2026-07-09T13:15:06.16785Z","steps":["trace[760736331] 'process raft request' (duration: 163.636602ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:06.167856Z","caller":"traceutil/trace.go:171","msg":"trace[1292517622] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"163.991481ms","start":"2026-07-09T13:15:06.003852Z","end":"2026-07-09T13:15:06.167843Z","steps":["trace[1292517622] 'process raft request' (duration: 163.582572ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:07.783722Z","caller":"traceutil/trace.go:171","msg":"trace[1725349523] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"268.939057ms","start":"2026-07-09T13:15:07.514764Z","end":"2026-07-09T13:15:07.783703Z","steps":["trace[1725349523] 'process raft request' (duration: 268.798934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:08.073637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.463593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:08.07381Z","caller":"traceutil/trace.go:171","msg":"trace[419788605] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"254.061081ms","start":"2026-07-09T13:15:07.819711Z","end":"2026-07-09T13:15:08.073772Z","steps":["trace[419788605] 'process raft request' (duration: 41.392343ms)","trace[419788605] 'compare' (duration: 212.359951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:08.724072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.955274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:08.72418Z","caller":"traceutil/trace.go:171","msg":"trace[1480818615] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"552.381673ms","start":"2026-07-09T13:15:08.171786Z","end":"2026-07-09T13:15:08.724168Z","steps":["trace[1480818615] 'read index received' (duration: 410.267446ms)","trace[1480818615] 'applied index is now lower than readState.Index' (duration: 142.113527ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:08.724227Z","caller":"traceutil/trace.go:171","msg":"trace[1426578715] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"646.392447ms","start":"2026-07-09T13:15:08.077782Z","end":"2026-07-09T13:15:08.724174Z","steps":["trace[1426578715] 'process raft request' (duration: 504.260979ms)","trace[1426578715] 'compare' (duration: 141.876162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:08.724346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:08.077759Z","time spent":"646.526501ms","remote":"127.0.0.1:41916","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-09T13:15:08.72444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.658659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:15:08.724471Z","caller":"traceutil/trace.go:171","msg":"trace[639759673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"552.731041ms","start":"2026-07-09T13:15:08.171731Z","end":"2026-07-09T13:15:08.724462Z","steps":["trace[639759673] 'agreement among raft nodes before linearized reading' (duration: 552.501595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:08.724495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:08.17171Z","time spent":"552.779563ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:15:08.856641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.467855ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T13:15:08.856829Z","caller":"traceutil/trace.go:171","msg":"trace[2080776241] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"132.611619ms","start":"2026-07-09T13:15:08.7242Z","end":"2026-07-09T13:15:08.856811Z","steps":["trace[2080776241] 'read index received' (duration: 51.261µs)","trace[2080776241] 'applied index is now lower than readState.Index' (duration: 132.557898ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:08.856862Z","caller":"traceutil/trace.go:171","msg":"trace[865329634] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"128.0837ms","start":"2026-07-09T13:15:08.728765Z","end":"2026-07-09T13:15:08.856849Z","steps":["trace[865329634] 'process raft request' (duration: 127.980027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:08.856992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.501491ms","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-09T13:15:08.857018Z","caller":"traceutil/trace.go:171","msg":"trace[1749418122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"684.565251ms","start":"2026-07-09T13:15:08.172447Z","end":"2026-07-09T13:15:08.857012Z","steps":["trace[1749418122] 'agreement among raft nodes before linearized reading' (duration: 684.449339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:08.857038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:08.172435Z","time spent":"684.597563ms","remote":"127.0.0.1:41916","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-09T13:15:08.857126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.881799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:15:08.857147Z","caller":"traceutil/trace.go:171","msg":"trace[1025768029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"684.93228ms","start":"2026-07-09T13:15:08.172209Z","end":"2026-07-09T13:15:08.857142Z","steps":["trace[1025768029] 'agreement among raft nodes before linearized reading' (duration: 684.879949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:08.857163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:08.172187Z","time spent":"684.972431ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:15:08.857225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.49103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:08.857246Z","caller":"traceutil/trace.go:171","msg":"trace[1506827550] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:813; }","duration":"123.565792ms","start":"2026-07-09T13:15:08.733674Z","end":"2026-07-09T13:15:08.85724Z","steps":["trace[1506827550] 'agreement among raft nodes before linearized reading' (duration: 123.533821ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:09.037911Z","caller":"traceutil/trace.go:171","msg":"trace[296538843] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"175.876147ms","start":"2026-07-09T13:15:08.862Z","end":"2026-07-09T13:15:09.037877Z","steps":["trace[296538843] 'process raft request' (duration: 100.4885ms)","trace[296538843] 'compare' (duration: 75.229453ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:11.101605Z","caller":"traceutil/trace.go:171","msg":"trace[711830738] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"166.073692ms","start":"2026-07-09T13:15:10.935502Z","end":"2026-07-09T13:15:11.101576Z","steps":["trace[711830738] 'process raft request' (duration: 66.200617ms)","trace[711830738] 'compare' (duration: 99.746281ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:12.93186Z","caller":"traceutil/trace.go:171","msg":"trace[297518421] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"168.477639ms","start":"2026-07-09T13:15:12.763359Z","end":"2026-07-09T13:15:12.931837Z","steps":["trace[297518421] 'process raft request' (duration: 168.349616ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:12.9815Z","caller":"traceutil/trace.go:171","msg":"trace[1212929336] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"165.750715ms","start":"2026-07-09T13:15:12.815722Z","end":"2026-07-09T13:15:12.981472Z","steps":["trace[1212929336] 'process raft request' (duration: 165.56065ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:16.596275Z","caller":"traceutil/trace.go:171","msg":"trace[1615061129] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"107.163679ms","start":"2026-07-09T13:15:16.489083Z","end":"2026-07-09T13:15:16.596247Z","steps":["trace[1615061129] 'process raft request' (duration: 107.013645ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:16.703966Z","caller":"traceutil/trace.go:171","msg":"trace[168368558] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"100.954521ms","start":"2026-07-09T13:15:16.602989Z","end":"2026-07-09T13:15:16.703944Z","steps":["trace[168368558] 'process raft request' (duration: 99.764033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:17.823902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934753838099746887,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:15:18.188736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058783944s","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-09T13:15:18.188858Z","caller":"traceutil/trace.go:171","msg":"trace[6060959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:844; }","duration":"1.058943828s","start":"2026-07-09T13:15:17.129891Z","end":"2026-07-09T13:15:18.188835Z","steps":["trace[6060959] 'range keys from in-memory index tree' (duration: 1.058566639s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.188911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:17.129871Z","time spent":"1.05902605s","remote":"127.0.0.1:41916","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-09T13:15:18.324454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934753838099746887,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:15:18.400021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.237688603s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T13:15:18.400454Z","caller":"traceutil/trace.go:171","msg":"trace[106625081] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"1.238259777s","start":"2026-07-09T13:15:17.162166Z","end":"2026-07-09T13:15:18.400426Z","steps":["trace[106625081] 'process raft request' (duration: 1.238057572s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.400793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:17.162135Z","time spent":"1.23838509s","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:15:18.404893Z","caller":"traceutil/trace.go:171","msg":"trace[864133162] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"211.271436ms","start":"2026-07-09T13:15:18.193601Z","end":"2026-07-09T13:15:18.404872Z","steps":["trace[864133162] 'process raft request' (duration: 210.862015ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:18.430748Z","caller":"traceutil/trace.go:171","msg":"trace[570671653] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:878; }","duration":"1.107481632s","start":"2026-07-09T13:15:17.323248Z","end":"2026-07-09T13:15:18.43073Z","steps":["trace[570671653] 'read index received' (duration: 1.077043572s)","trace[570671653] 'applied index is now lower than readState.Index' (duration: 30.43701ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:18.430789Z","caller":"traceutil/trace.go:171","msg":"trace[1085206186] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"167.768362ms","start":"2026-07-09T13:15:18.262999Z","end":"2026-07-09T13:15:18.430767Z","steps":["trace[1085206186] 'process raft request' (duration: 167.587728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.430928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107676977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:15:18.430955Z","caller":"traceutil/trace.go:171","msg":"trace[741532119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"1.107750219s","start":"2026-07-09T13:15:17.323199Z","end":"2026-07-09T13:15:18.430949Z","steps":["trace[741532119] 'agreement among raft nodes before linearized reading' (duration: 1.107637076s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.430976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:17.323133Z","time spent":"1.10783743s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:15:18.431072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.675393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T13:15:18.431094Z","caller":"traceutil/trace.go:171","msg":"trace[965983811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"417.754116ms","start":"2026-07-09T13:15:18.013335Z","end":"2026-07-09T13:15:18.431089Z","steps":["trace[965983811] 'agreement among raft nodes before linearized reading' (duration: 417.699555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.431111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:18.013304Z","time spent":"417.803126ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T13:15:18.431086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.369934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:18.431269Z","caller":"traceutil/trace.go:171","msg":"trace[1935637335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"673.593038ms","start":"2026-07-09T13:15:17.757652Z","end":"2026-07-09T13:15:18.431245Z","steps":["trace[1935637335] 'agreement among raft nodes before linearized reading' (duration: 673.367273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.431332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:17.757629Z","time spent":"673.688521ms","remote":"127.0.0.1:41596","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-09T13:15:18.431268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.356665ms","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-09T13:15:18.431389Z","caller":"traceutil/trace.go:171","msg":"trace[395106231] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:847; }","duration":"608.513609ms","start":"2026-07-09T13:15:17.822863Z","end":"2026-07-09T13:15:18.431377Z","steps":["trace[395106231] 'agreement among raft nodes before linearized reading' (duration: 608.177181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:18.431447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:17.822843Z","time spent":"608.592251ms","remote":"127.0.0.1:42114","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":"info","ts":"2026-07-09T13:15:20.541401Z","caller":"traceutil/trace.go:171","msg":"trace[1467757970] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"118.142362ms","start":"2026-07-09T13:15:20.423236Z","end":"2026-07-09T13:15:20.541378Z","steps":["trace[1467757970] 'read index received' (duration: 117.955207ms)","trace[1467757970] 'applied index is now lower than readState.Index' (duration: 186.315µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:20.541445Z","caller":"traceutil/trace.go:171","msg":"trace[1140047688] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"128.44537ms","start":"2026-07-09T13:15:20.412975Z","end":"2026-07-09T13:15:20.541421Z","steps":["trace[1140047688] 'process raft request' (duration: 128.263535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:20.5416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.337427ms","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-09T13:15:20.541694Z","caller":"traceutil/trace.go:171","msg":"trace[1126140306] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:854; }","duration":"118.500491ms","start":"2026-07-09T13:15:20.423177Z","end":"2026-07-09T13:15:20.541678Z","steps":["trace[1126140306] 'agreement among raft nodes before linearized reading' (duration: 118.326267ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:20.710224Z","caller":"traceutil/trace.go:171","msg":"trace[81017103] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"163.471669ms","start":"2026-07-09T13:15:20.546735Z","end":"2026-07-09T13:15:20.710206Z","steps":["trace[81017103] 'process raft request' (duration: 163.422998ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:20.710249Z","caller":"traceutil/trace.go:171","msg":"trace[1432332677] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"164.844092ms","start":"2026-07-09T13:15:20.545377Z","end":"2026-07-09T13:15:20.710222Z","steps":["trace[1432332677] 'process raft request' (duration: 159.918934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:21.019958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.100179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T13:15:21.020328Z","caller":"traceutil/trace.go:171","msg":"trace[1333670823] transaction","detail":"{read_only:false; number_of_response:1; response_revision:857; }","duration":"235.586168ms","start":"2026-07-09T13:15:20.784713Z","end":"2026-07-09T13:15:21.020299Z","steps":["trace[1333670823] 'process raft request' (duration: 52.015307ms)","trace[1333670823] 'compare' (duration: 183.018648ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:21.317649Z","caller":"traceutil/trace.go:171","msg":"trace[1189478274] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"219.075253ms","start":"2026-07-09T13:15:21.098546Z","end":"2026-07-09T13:15:21.317621Z","steps":["trace[1189478274] 'read index received' (duration: 151.668347ms)","trace[1189478274] 'applied index is now lower than readState.Index' (duration: 67.405636ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:21.31772Z","caller":"traceutil/trace.go:171","msg":"trace[1578301706] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"293.772613ms","start":"2026-07-09T13:15:21.023919Z","end":"2026-07-09T13:15:21.317692Z","steps":["trace[1578301706] 'process raft request' (duration: 226.379458ms)","trace[1578301706] 'compare' (duration: 67.194531ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:21.317825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.257157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:15:21.317866Z","caller":"traceutil/trace.go:171","msg":"trace[316543610] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:858; }","duration":"219.348898ms","start":"2026-07-09T13:15:21.098507Z","end":"2026-07-09T13:15:21.317856Z","steps":["trace[316543610] 'agreement among raft nodes before linearized reading' (duration: 219.256906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:21.31802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.677952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-09T13:15:21.318101Z","caller":"traceutil/trace.go:171","msg":"trace[1511604095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:858; }","duration":"165.840846ms","start":"2026-07-09T13:15:21.152242Z","end":"2026-07-09T13:15:21.318082Z","steps":["trace[1511604095] 'agreement among raft nodes before linearized reading' (duration: 165.61405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:21.318036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.840708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vgnxr\" ","response":"range_response_count:1 size:4229"} {"level":"info","ts":"2026-07-09T13:15:21.318191Z","caller":"traceutil/trace.go:171","msg":"trace[153073997] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vgnxr; range_end:; response_count:1; response_revision:858; }","duration":"154.030963ms","start":"2026-07-09T13:15:21.164118Z","end":"2026-07-09T13:15:21.318149Z","steps":["trace[153073997] 'agreement among raft nodes before linearized reading' (duration: 153.772856ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:21.604117Z","caller":"traceutil/trace.go:171","msg":"trace[2053091965] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"282.189795ms","start":"2026-07-09T13:15:21.321899Z","end":"2026-07-09T13:15:21.604088Z","steps":["trace[2053091965] 'process raft request' (duration: 282.007861ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:21.775759Z","caller":"traceutil/trace.go:171","msg":"trace[287086789] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"450.322147ms","start":"2026-07-09T13:15:21.32541Z","end":"2026-07-09T13:15:21.775732Z","steps":["trace[287086789] 'process raft request' (duration: 450.03452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:21.775989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:21.325388Z","time spent":"450.520371ms","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:15:22.937632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.643997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:22.937709Z","caller":"traceutil/trace.go:171","msg":"trace[988039356] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"219.302839ms","start":"2026-07-09T13:15:22.71839Z","end":"2026-07-09T13:15:22.937693Z","steps":["trace[988039356] 'process raft request' (duration: 117.543089ms)","trace[988039356] 'compare' (duration: 101.511754ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:22.964887Z","caller":"traceutil/trace.go:171","msg":"trace[614176044] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"180.366204ms","start":"2026-07-09T13:15:22.784487Z","end":"2026-07-09T13:15:22.964854Z","steps":["trace[614176044] 'process raft request' (duration: 180.252021ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:23.151108Z","caller":"traceutil/trace.go:171","msg":"trace[416799252] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"122.464239ms","start":"2026-07-09T13:15:23.028617Z","end":"2026-07-09T13:15:23.151081Z","steps":["trace[416799252] 'process raft request' (duration: 72.924158ms)","trace[416799252] 'compare' (duration: 49.430549ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:23.585461Z","caller":"traceutil/trace.go:171","msg":"trace[724760225] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"103.142677ms","start":"2026-07-09T13:15:23.482293Z","end":"2026-07-09T13:15:23.585435Z","steps":["trace[724760225] 'process raft request' (duration: 103.090436ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:23.58573Z","caller":"traceutil/trace.go:171","msg":"trace[436714926] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"104.190191ms","start":"2026-07-09T13:15:23.481416Z","end":"2026-07-09T13:15:23.585607Z","steps":["trace[436714926] 'process raft request' (duration: 103.559617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:23.900384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.076121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:23.900473Z","caller":"traceutil/trace.go:171","msg":"trace[1876399741] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"216.54919ms","start":"2026-07-09T13:15:23.683908Z","end":"2026-07-09T13:15:23.900457Z","steps":["trace[1876399741] 'process raft request' (duration: 76.328435ms)","trace[1876399741] 'compare' (duration: 139.976269ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:23.957878Z","caller":"traceutil/trace.go:171","msg":"trace[1558299888] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"273.919467ms","start":"2026-07-09T13:15:23.683928Z","end":"2026-07-09T13:15:23.957847Z","steps":["trace[1558299888] 'process raft request' (duration: 273.673301ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:23.957949Z","caller":"traceutil/trace.go:171","msg":"trace[1425189834] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"273.980768ms","start":"2026-07-09T13:15:23.683942Z","end":"2026-07-09T13:15:23.957923Z","steps":["trace[1425189834] 'process raft request' (duration: 273.772034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:23.958109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.567096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:23.958178Z","caller":"traceutil/trace.go:171","msg":"trace[55046979] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:887; }","duration":"269.667478ms","start":"2026-07-09T13:15:23.688493Z","end":"2026-07-09T13:15:23.958161Z","steps":["trace[55046979] 'agreement among raft nodes before linearized reading' (duration: 269.518704ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:23.957792Z","caller":"traceutil/trace.go:171","msg":"trace[1616727048] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:920; }","duration":"269.257169ms","start":"2026-07-09T13:15:23.688511Z","end":"2026-07-09T13:15:23.957768Z","steps":["trace[1616727048] 'read index received' (duration: 71.683648ms)","trace[1616727048] 'applied index is now lower than readState.Index' (duration: 197.572381ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:23.958681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.398762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:23.95872Z","caller":"traceutil/trace.go:171","msg":"trace[1752695982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"202.483584ms","start":"2026-07-09T13:15:23.756228Z","end":"2026-07-09T13:15:23.958711Z","steps":["trace[1752695982] 'agreement among raft nodes before linearized reading' (duration: 202.424533ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.140529Z","caller":"traceutil/trace.go:171","msg":"trace[681020196] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"180.164088ms","start":"2026-07-09T13:15:23.960341Z","end":"2026-07-09T13:15:24.140505Z","steps":["trace[681020196] 'process raft request' (duration: 179.979054ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.140585Z","caller":"traceutil/trace.go:171","msg":"trace[459372297] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"174.683261ms","start":"2026-07-09T13:15:23.965857Z","end":"2026-07-09T13:15:24.14054Z","steps":["trace[459372297] 'read index received' (duration: 174.61825ms)","trace[459372297] 'applied index is now lower than readState.Index' (duration: 61.391µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:24.140865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.969548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-07-09T13:15:24.14099Z","caller":"traceutil/trace.go:171","msg":"trace[2007526520] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:888; }","duration":"175.123401ms","start":"2026-07-09T13:15:23.965836Z","end":"2026-07-09T13:15:24.140959Z","steps":["trace[2007526520] 'agreement among raft nodes before linearized reading' (duration: 174.783163ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.311924Z","caller":"traceutil/trace.go:171","msg":"trace[1917314586] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"171.214482ms","start":"2026-07-09T13:15:24.140683Z","end":"2026-07-09T13:15:24.311898Z","steps":["trace[1917314586] 'read index received' (duration: 158.728753ms)","trace[1917314586] 'applied index is now lower than readState.Index' (duration: 12.484659ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:24.31202Z","caller":"traceutil/trace.go:171","msg":"trace[824664283] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"333.220239ms","start":"2026-07-09T13:15:23.978762Z","end":"2026-07-09T13:15:24.311982Z","steps":["trace[824664283] 'process raft request' (duration: 321.204211ms)","trace[824664283] 'compare' (duration: 11.027976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:24.312168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.073165ms","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-09T13:15:24.312211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:23.978738Z","time spent":"333.379083ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036294,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:15:24.312241Z","caller":"traceutil/trace.go:171","msg":"trace[335964740] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:889; }","duration":"250.211698ms","start":"2026-07-09T13:15:24.06199Z","end":"2026-07-09T13:15:24.312202Z","steps":["trace[335964740] 'agreement among raft nodes before linearized reading' (duration: 250.002864ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.366234Z","caller":"traceutil/trace.go:171","msg":"trace[662110617] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"215.898085ms","start":"2026-07-09T13:15:24.150319Z","end":"2026-07-09T13:15:24.366217Z","steps":["trace[662110617] 'process raft request' (duration: 215.871464ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.366314Z","caller":"traceutil/trace.go:171","msg":"trace[1068834916] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"220.814629ms","start":"2026-07-09T13:15:24.145463Z","end":"2026-07-09T13:15:24.366277Z","steps":["trace[1068834916] 'process raft request' (duration: 220.583344ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.366369Z","caller":"traceutil/trace.go:171","msg":"trace[1002051478] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"218.808313ms","start":"2026-07-09T13:15:24.147538Z","end":"2026-07-09T13:15:24.366346Z","steps":["trace[1002051478] 'process raft request' (duration: 218.628549ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.366321Z","caller":"traceutil/trace.go:171","msg":"trace[1899588999] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"219.11457ms","start":"2026-07-09T13:15:24.147168Z","end":"2026-07-09T13:15:24.366283Z","steps":["trace[1899588999] 'process raft request' (duration: 218.961037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:24.539222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.46603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:24.539435Z","caller":"traceutil/trace.go:171","msg":"trace[685458965] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"167.105776ms","start":"2026-07-09T13:15:24.372304Z","end":"2026-07-09T13:15:24.539409Z","steps":["trace[685458965] 'process raft request' (duration: 45.35657ms)","trace[685458965] 'compare' (duration: 121.338896ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:24.539535Z","caller":"traceutil/trace.go:171","msg":"trace[1953540719] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:929; }","duration":"114.088229ms","start":"2026-07-09T13:15:24.425427Z","end":"2026-07-09T13:15:24.539515Z","steps":["trace[1953540719] 'read index received' (duration: 113.208159ms)","trace[1953540719] 'applied index is now lower than readState.Index' (duration: 878.9µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:24.539664Z","caller":"traceutil/trace.go:171","msg":"trace[544038594] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"160.386971ms","start":"2026-07-09T13:15:24.379243Z","end":"2026-07-09T13:15:24.53963Z","steps":["trace[544038594] 'process raft request' (duration: 160.209157ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:24.539761Z","caller":"traceutil/trace.go:171","msg":"trace[1824343185] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"165.290454ms","start":"2026-07-09T13:15:24.374449Z","end":"2026-07-09T13:15:24.539739Z","steps":["trace[1824343185] 'process raft request' (duration: 164.893424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:24.540858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.41332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-07-09T13:15:24.540925Z","caller":"traceutil/trace.go:171","msg":"trace[1033594055] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:896; }","duration":"115.521573ms","start":"2026-07-09T13:15:24.425387Z","end":"2026-07-09T13:15:24.540908Z","steps":["trace[1033594055] 'agreement among raft nodes before linearized reading' (duration: 114.193262ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:25.111549Z","caller":"traceutil/trace.go:171","msg":"trace[733857134] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"169.613334ms","start":"2026-07-09T13:15:24.941907Z","end":"2026-07-09T13:15:25.11152Z","steps":["trace[733857134] 'read index received' (duration: 139.898127ms)","trace[733857134] 'applied index is now lower than readState.Index' (duration: 29.713997ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:25.111801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.88081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:15:25.111835Z","caller":"traceutil/trace.go:171","msg":"trace[142852356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"169.966302ms","start":"2026-07-09T13:15:24.941859Z","end":"2026-07-09T13:15:25.111825Z","steps":["trace[142852356] 'agreement among raft nodes before linearized reading' (duration: 169.758718ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:25.111985Z","caller":"traceutil/trace.go:171","msg":"trace[1992435295] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"220.549932ms","start":"2026-07-09T13:15:24.891398Z","end":"2026-07-09T13:15:25.111948Z","steps":["trace[1992435295] 'process raft request' (duration: 190.461366ms)","trace[1992435295] 'compare' (duration: 29.36324ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:27.42064Z","caller":"traceutil/trace.go:171","msg":"trace[729314871] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"158.401565ms","start":"2026-07-09T13:15:27.262177Z","end":"2026-07-09T13:15:27.420579Z","steps":["trace[729314871] 'process raft request' (duration: 103.396922ms)","trace[729314871] 'compare' (duration: 54.87777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:27.668376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.68394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:27.66854Z","caller":"traceutil/trace.go:171","msg":"trace[753997330] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"153.270356ms","start":"2026-07-09T13:15:27.515242Z","end":"2026-07-09T13:15:27.668512Z","steps":["trace[753997330] 'process raft request' (duration: 52.381762ms)","trace[753997330] 'compare' (duration: 100.584037ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:27.926491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.229871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:27.926679Z","caller":"traceutil/trace.go:171","msg":"trace[1414304919] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"251.327614ms","start":"2026-07-09T13:15:27.675325Z","end":"2026-07-09T13:15:27.926653Z","steps":["trace[1414304919] 'process raft request' (duration: 114.773295ms)","trace[1414304919] 'compare' (duration: 135.589626ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:27.926607Z","caller":"traceutil/trace.go:171","msg":"trace[780609023] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"169.056632ms","start":"2026-07-09T13:15:27.757533Z","end":"2026-07-09T13:15:27.92659Z","steps":["trace[780609023] 'read index received' (duration: 32.473792ms)","trace[780609023] 'applied index is now lower than readState.Index' (duration: 136.58155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:27.927072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.337778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:27.927212Z","caller":"traceutil/trace.go:171","msg":"trace[1187139864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"169.647335ms","start":"2026-07-09T13:15:27.757481Z","end":"2026-07-09T13:15:27.927128Z","steps":["trace[1187139864] 'agreement among raft nodes before linearized reading' (duration: 169.289127ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:28.126179Z","caller":"traceutil/trace.go:171","msg":"trace[2101311263] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"194.793778ms","start":"2026-07-09T13:15:27.931355Z","end":"2026-07-09T13:15:28.126149Z","steps":["trace[2101311263] 'process raft request' (duration: 135.020454ms)","trace[2101311263] 'compare' (duration: 59.664681ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:28.129173Z","caller":"traceutil/trace.go:171","msg":"trace[1782660691] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:956; }","duration":"180.903015ms","start":"2026-07-09T13:15:27.948248Z","end":"2026-07-09T13:15:28.129151Z","steps":["trace[1782660691] 'read index received' (duration: 118.137152ms)","trace[1782660691] 'applied index is now lower than readState.Index' (duration: 62.764893ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:28.129448Z","caller":"traceutil/trace.go:171","msg":"trace[84901428] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"194.994802ms","start":"2026-07-09T13:15:27.934425Z","end":"2026-07-09T13:15:28.12942Z","steps":["trace[84901428] 'process raft request' (duration: 194.673675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:28.129592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.325875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:15:28.129636Z","caller":"traceutil/trace.go:171","msg":"trace[1753445879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"181.404937ms","start":"2026-07-09T13:15:27.948219Z","end":"2026-07-09T13:15:28.129624Z","steps":["trace[1753445879] 'agreement among raft nodes before linearized reading' (duration: 181.254504ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:28.12973Z","caller":"traceutil/trace.go:171","msg":"trace[1610534130] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"197.884129ms","start":"2026-07-09T13:15:27.931832Z","end":"2026-07-09T13:15:28.129716Z","steps":["trace[1610534130] 'process raft request' (duration: 197.135372ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:29.35656Z","caller":"traceutil/trace.go:171","msg":"trace[515987588] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"121.82785ms","start":"2026-07-09T13:15:29.234696Z","end":"2026-07-09T13:15:29.356524Z","steps":["trace[515987588] 'process raft request' (duration: 35.181255ms)","trace[515987588] 'compare' (duration: 86.496521ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:29.35674Z","caller":"traceutil/trace.go:171","msg":"trace[1827268752] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"120.211461ms","start":"2026-07-09T13:15:29.236512Z","end":"2026-07-09T13:15:29.356723Z","steps":["trace[1827268752] 'process raft request' (duration: 120.081938ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:29.357163Z","caller":"traceutil/trace.go:171","msg":"trace[2128886334] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"114.051429ms","start":"2026-07-09T13:15:29.243092Z","end":"2026-07-09T13:15:29.357143Z","steps":["trace[2128886334] 'process raft request' (duration: 113.579268ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:41.857902Z","caller":"traceutil/trace.go:171","msg":"trace[479780895] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"122.126216ms","start":"2026-07-09T13:15:41.735751Z","end":"2026-07-09T13:15:41.857877Z","steps":["trace[479780895] 'process raft request' (duration: 121.756426ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:50.01654Z","caller":"traceutil/trace.go:171","msg":"trace[796036217] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"124.330556ms","start":"2026-07-09T13:15:49.892188Z","end":"2026-07-09T13:15:50.016519Z","steps":["trace[796036217] 'process raft request' (duration: 124.216653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:56.116214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.401377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:56.116298Z","caller":"traceutil/trace.go:171","msg":"trace[504169078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"360.52135ms","start":"2026-07-09T13:15:55.755756Z","end":"2026-07-09T13:15:56.116277Z","steps":["trace[504169078] 'range keys from in-memory index tree' (duration: 360.327446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:56.116385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:55.75574Z","time spent":"360.634713ms","remote":"127.0.0.1:41596","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-09T13:15:56.686445Z","caller":"traceutil/trace.go:171","msg":"trace[700095140] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"565.085074ms","start":"2026-07-09T13:15:56.121308Z","end":"2026-07-09T13:15:56.686393Z","steps":["trace[700095140] 'process raft request' (duration: 564.860549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:56.686618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.90803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:15:56.686701Z","caller":"traceutil/trace.go:171","msg":"trace[394602286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"243.049253ms","start":"2026-07-09T13:15:56.443639Z","end":"2026-07-09T13:15:56.686689Z","steps":["trace[394602286] 'agreement among raft nodes before linearized reading' (duration: 242.821798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:56.686792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:56.121296Z","time spent":"565.264578ms","remote":"127.0.0.1:41916","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-09T13:15:56.686341Z","caller":"traceutil/trace.go:171","msg":"trace[70132600] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"242.630004ms","start":"2026-07-09T13:15:56.443686Z","end":"2026-07-09T13:15:56.686316Z","steps":["trace[70132600] 'read index received' (duration: 242.478801ms)","trace[70132600] 'applied index is now lower than readState.Index' (duration: 150.133µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:57.370771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.564816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:57.370862Z","caller":"traceutil/trace.go:171","msg":"trace[209825048] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"614.221779ms","start":"2026-07-09T13:15:56.756622Z","end":"2026-07-09T13:15:57.370844Z","steps":["trace[209825048] 'read index received' (duration: 494.458529ms)","trace[209825048] 'applied index is now lower than readState.Index' (duration: 119.76127ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:57.370925Z","caller":"traceutil/trace.go:171","msg":"trace[635865617] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"680.33088ms","start":"2026-07-09T13:15:56.690583Z","end":"2026-07-09T13:15:57.370914Z","steps":["trace[635865617] 'process raft request' (duration: 560.534538ms)","trace[635865617] 'compare' (duration: 119.347292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:57.371045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.427525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:57.371104Z","caller":"traceutil/trace.go:171","msg":"trace[762075602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"614.573008ms","start":"2026-07-09T13:15:56.756516Z","end":"2026-07-09T13:15:57.371089Z","steps":["trace[762075602] 'agreement among raft nodes before linearized reading' (duration: 614.480396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:57.371145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:56.756489Z","time spent":"614.647679ms","remote":"127.0.0.1:41596","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-09T13:15:57.371205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:56.690559Z","time spent":"680.395961ms","remote":"127.0.0.1:41916","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-09T13:15:57.604183Z","caller":"traceutil/trace.go:171","msg":"trace[405217248] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"233.278397ms","start":"2026-07-09T13:15:57.370886Z","end":"2026-07-09T13:15:57.604164Z","steps":["trace[405217248] 'read index received' (duration: 233.135964ms)","trace[405217248] 'applied index is now lower than readState.Index' (duration: 141.673µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:57.604362Z","caller":"traceutil/trace.go:171","msg":"trace[1275746411] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"535.218013ms","start":"2026-07-09T13:15:57.069134Z","end":"2026-07-09T13:15:57.604352Z","steps":["trace[1275746411] 'process raft request' (duration: 534.936536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:57.604456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:57.069114Z","time spent":"535.277815ms","remote":"127.0.0.1:41916","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-09T13:15:57.604723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.9837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T13:15:57.604784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.699772ms","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-09T13:15:57.604788Z","caller":"traceutil/trace.go:171","msg":"trace[631893888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1069; }","duration":"638.077092ms","start":"2026-07-09T13:15:56.966694Z","end":"2026-07-09T13:15:57.604771Z","steps":["trace[631893888] 'agreement among raft nodes before linearized reading' (duration: 637.897838ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:57.604815Z","caller":"traceutil/trace.go:171","msg":"trace[1066527740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"477.767654ms","start":"2026-07-09T13:15:57.127038Z","end":"2026-07-09T13:15:57.604806Z","steps":["trace[1066527740] 'agreement among raft nodes before linearized reading' (duration: 477.669321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:57.604876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:56.966678Z","time spent":"638.185795ms","remote":"127.0.0.1:41916","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-09T13:15:57.604888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:15:57.127Z","time spent":"477.880046ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T13:15:57.604888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.779056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:15:57.604975Z","caller":"traceutil/trace.go:171","msg":"trace[219969522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"232.903508ms","start":"2026-07-09T13:15:57.37205Z","end":"2026-07-09T13:15:57.604954Z","steps":["trace[219969522] 'agreement among raft nodes before linearized reading' (duration: 232.723074ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:18.015175Z","caller":"traceutil/trace.go:171","msg":"trace[315564024] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"117.656423ms","start":"2026-07-09T13:16:17.897482Z","end":"2026-07-09T13:16:18.015138Z","steps":["trace[315564024] 'process raft request' (duration: 117.514718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:21.458084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934753838099747567,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T13:16:21.82763Z","caller":"traceutil/trace.go:171","msg":"trace[1773726834] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1193; }","duration":"869.857684ms","start":"2026-07-09T13:16:20.957742Z","end":"2026-07-09T13:16:21.8276Z","steps":["trace[1773726834] 'read index received' (duration: 869.724521ms)","trace[1773726834] 'applied index is now lower than readState.Index' (duration: 131.793µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:16:21.827789Z","caller":"traceutil/trace.go:171","msg":"trace[1752225953] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"990.559606ms","start":"2026-07-09T13:16:20.837211Z","end":"2026-07-09T13:16:21.82777Z","steps":["trace[1752225953] 'process raft request' (duration: 990.203718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:21.827996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.237594ms","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-09T13:16:21.828091Z","caller":"traceutil/trace.go:171","msg":"trace[1507776627] 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:1146; }","duration":"870.568351ms","start":"2026-07-09T13:16:20.957506Z","end":"2026-07-09T13:16:21.828074Z","steps":["trace[1507776627] 'agreement among raft nodes before linearized reading' (duration: 870.426838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:21.828155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:20.957482Z","time spent":"870.650623ms","remote":"127.0.0.1:40468","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-09T13:16:21.828615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:20.837194Z","time spent":"990.63996ms","remote":"127.0.0.1:41916","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-09T13:16:21.991382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.586838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:16:21.991487Z","caller":"traceutil/trace.go:171","msg":"trace[1997802632] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"163.756329ms","start":"2026-07-09T13:16:21.827713Z","end":"2026-07-09T13:16:21.99147Z","steps":["trace[1997802632] 'read index received' (duration: 33.916285ms)","trace[1997802632] 'applied index is now lower than readState.Index' (duration: 129.838884ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:16:21.99167Z","caller":"traceutil/trace.go:171","msg":"trace[494444441] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"254.924668ms","start":"2026-07-09T13:16:21.736714Z","end":"2026-07-09T13:16:21.991639Z","steps":["trace[494444441] 'process raft request' (duration: 125.003872ms)","trace[494444441] 'compare' (duration: 129.393504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:16:21.991708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.782901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:16:21.991745Z","caller":"traceutil/trace.go:171","msg":"trace[1662860513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1147; }","duration":"454.855243ms","start":"2026-07-09T13:16:21.53688Z","end":"2026-07-09T13:16:21.991735Z","steps":["trace[1662860513] 'agreement among raft nodes before linearized reading' (duration: 454.690969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:21.991761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.620272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:16:21.991818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:21.536862Z","time spent":"454.945136ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:16:21.991838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.719434ms","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-09T13:16:21.991952Z","caller":"traceutil/trace.go:171","msg":"trace[1990545921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1147; }","duration":"194.902109ms","start":"2026-07-09T13:16:21.797036Z","end":"2026-07-09T13:16:21.991938Z","steps":["trace[1990545921] 'agreement among raft nodes before linearized reading' (duration: 194.601502ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:21.991824Z","caller":"traceutil/trace.go:171","msg":"trace[1374022724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1147; }","duration":"194.715535ms","start":"2026-07-09T13:16:21.797093Z","end":"2026-07-09T13:16:21.991809Z","steps":["trace[1374022724] 'agreement among raft nodes before linearized reading' (duration: 194.51866ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:22.189828Z","caller":"traceutil/trace.go:171","msg":"trace[636289231] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"192.801421ms","start":"2026-07-09T13:16:21.99697Z","end":"2026-07-09T13:16:22.189771Z","steps":["trace[636289231] 'process raft request' (duration: 192.575306ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:22.189671Z","caller":"traceutil/trace.go:171","msg":"trace[144460645] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"192.870723ms","start":"2026-07-09T13:16:21.996755Z","end":"2026-07-09T13:16:22.189626Z","steps":["trace[144460645] 'process raft request' (duration: 187.754374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:23.69241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.251042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:16:23.692496Z","caller":"traceutil/trace.go:171","msg":"trace[835847309] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"348.915602ms","start":"2026-07-09T13:16:23.343564Z","end":"2026-07-09T13:16:23.692479Z","steps":["trace[835847309] 'process raft request' (duration: 59.542587ms)","trace[835847309] 'compare' (duration: 289.123589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:16:23.692537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:23.34354Z","time spent":"348.976914ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:16:23.945919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.472023ms","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-09T13:16:23.94601Z","caller":"traceutil/trace.go:171","msg":"trace[594389346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1152; }","duration":"112.616336ms","start":"2026-07-09T13:16:23.833371Z","end":"2026-07-09T13:16:23.945987Z","steps":["trace[594389346] 'range keys from in-memory index tree' (duration: 112.287918ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:24.23548Z","caller":"traceutil/trace.go:171","msg":"trace[692982957] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"106.381782ms","start":"2026-07-09T13:16:24.12907Z","end":"2026-07-09T13:16:24.235452Z","steps":["trace[692982957] 'process raft request' (duration: 106.156206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:25.047314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.783821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:16:25.04738Z","caller":"traceutil/trace.go:171","msg":"trace[2141714558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"291.895723ms","start":"2026-07-09T13:16:24.755469Z","end":"2026-07-09T13:16:25.047365Z","steps":["trace[2141714558] 'range keys from in-memory index tree' (duration: 291.654157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:25.047566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.331724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:16:25.047658Z","caller":"traceutil/trace.go:171","msg":"trace[1403985496] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1159; }","duration":"283.54913ms","start":"2026-07-09T13:16:24.764087Z","end":"2026-07-09T13:16:25.047636Z","steps":["trace[1403985496] 'count revisions from in-memory index tree' (duration: 282.888635ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:26.28757Z","caller":"traceutil/trace.go:171","msg":"trace[615795897] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"252.521572ms","start":"2026-07-09T13:16:26.035027Z","end":"2026-07-09T13:16:26.287549Z","steps":["trace[615795897] 'process raft request' (duration: 252.196355ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:26.403193Z","caller":"traceutil/trace.go:171","msg":"trace[2125114181] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"111.555901ms","start":"2026-07-09T13:16:26.29158Z","end":"2026-07-09T13:16:26.403136Z","steps":["trace[2125114181] 'process raft request' (duration: 48.075672ms)","trace[2125114181] 'compare' (duration: 63.140541ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:16:42.208973Z","caller":"traceutil/trace.go:171","msg":"trace[879528540] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"101.703965ms","start":"2026-07-09T13:16:42.107249Z","end":"2026-07-09T13:16:42.208953Z","steps":["trace[879528540] 'process raft request' (duration: 101.582692ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:42.72255Z","caller":"traceutil/trace.go:171","msg":"trace[104701697] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"273.193938ms","start":"2026-07-09T13:16:42.449331Z","end":"2026-07-09T13:16:42.722525Z","steps":["trace[104701697] 'read index received' (duration: 259.523111ms)","trace[104701697] 'applied index is now lower than readState.Index' (duration: 13.669417ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:16:42.722641Z","caller":"traceutil/trace.go:171","msg":"trace[963296125] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"305.855595ms","start":"2026-07-09T13:16:42.416741Z","end":"2026-07-09T13:16:42.722597Z","steps":["trace[963296125] 'process raft request' (duration: 292.086186ms)","trace[963296125] 'compare' (duration: 13.228306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:16:42.722754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.411853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T13:16:42.722788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.993991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:16:42.722845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.521596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:16:42.722841Z","caller":"traceutil/trace.go:171","msg":"trace[550856403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"273.542796ms","start":"2026-07-09T13:16:42.449275Z","end":"2026-07-09T13:16:42.722818Z","steps":["trace[550856403] 'agreement among raft nodes before linearized reading' (duration: 273.323951ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:42.722849Z","caller":"traceutil/trace.go:171","msg":"trace[887395675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"190.083812ms","start":"2026-07-09T13:16:42.532751Z","end":"2026-07-09T13:16:42.722835Z","steps":["trace[887395675] 'agreement among raft nodes before linearized reading' (duration: 189.904988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:42.722849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:42.416717Z","time spent":"306.020448ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:16:42.722896Z","caller":"traceutil/trace.go:171","msg":"trace[1264435524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"191.580757ms","start":"2026-07-09T13:16:42.531305Z","end":"2026-07-09T13:16:42.722886Z","steps":["trace[1264435524] 'agreement among raft nodes before linearized reading' (duration: 191.494606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:16:42.722768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.460435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:16:42.723005Z","caller":"traceutil/trace.go:171","msg":"trace[1391251358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"191.726941ms","start":"2026-07-09T13:16:42.53125Z","end":"2026-07-09T13:16:42.722977Z","steps":["trace[1391251358] 'agreement among raft nodes before linearized reading' (duration: 191.406583ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:55.239642Z","caller":"traceutil/trace.go:171","msg":"trace[1379758399] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"310.753998ms","start":"2026-07-09T13:16:54.928869Z","end":"2026-07-09T13:16:55.239623Z","steps":["trace[1379758399] 'process raft request' (duration: 310.686126ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:16:55.239681Z","caller":"traceutil/trace.go:171","msg":"trace[2006044290] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"311.126848ms","start":"2026-07-09T13:16:54.928519Z","end":"2026-07-09T13:16:55.239646Z","steps":["trace[2006044290] 'process raft request' (duration: 244.378102ms)","trace[2006044290] 'compare' (duration: 66.432048ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:16:55.239766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:54.92886Z","time spent":"310.8513ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:16:55.239866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:54.928498Z","time spent":"311.25676ms","remote":"127.0.0.1:41916","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-09T13:16:59.337186Z","caller":"traceutil/trace.go:171","msg":"trace[1853313627] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"175.385693ms","start":"2026-07-09T13:16:59.161781Z","end":"2026-07-09T13:16:59.337167Z","steps":["trace[1853313627] 'process raft request' (duration: 149.667837ms)","trace[1853313627] 'compare' (duration: 25.552372ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:00.046039Z","caller":"traceutil/trace.go:171","msg":"trace[1897649562] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"431.980696ms","start":"2026-07-09T13:16:59.614034Z","end":"2026-07-09T13:17:00.046015Z","steps":["trace[1897649562] 'read index received' (duration: 431.762971ms)","trace[1897649562] 'applied index is now lower than readState.Index' (duration: 214.255µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:00.046144Z","caller":"traceutil/trace.go:171","msg":"trace[697797615] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"540.055259ms","start":"2026-07-09T13:16:59.506067Z","end":"2026-07-09T13:17:00.046122Z","steps":["trace[697797615] 'process raft request' (duration: 539.803374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:00.046233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.67831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:17:00.046254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:59.50604Z","time spent":"540.149882ms","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:17:00.046306Z","caller":"traceutil/trace.go:171","msg":"trace[1140456942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"289.777551ms","start":"2026-07-09T13:16:59.756511Z","end":"2026-07-09T13:17:00.046289Z","steps":["trace[1140456942] 'agreement among raft nodes before linearized reading' (duration: 289.669209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:00.046232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.135619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:00.046392Z","caller":"traceutil/trace.go:171","msg":"trace[154006027] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1338; }","duration":"432.394775ms","start":"2026-07-09T13:16:59.613984Z","end":"2026-07-09T13:17:00.046379Z","steps":["trace[154006027] 'agreement among raft nodes before linearized reading' (duration: 432.14965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:00.046432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:16:59.613964Z","time spent":"432.458487ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:17:00.307891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.495798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:00.30798Z","caller":"traceutil/trace.go:171","msg":"trace[1803294481] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"253.998393ms","start":"2026-07-09T13:17:00.053967Z","end":"2026-07-09T13:17:00.307966Z","steps":["trace[1803294481] 'process raft request' (duration: 93.366202ms)","trace[1803294481] 'compare' (duration: 160.295504ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:00.341628Z","caller":"traceutil/trace.go:171","msg":"trace[31967776] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"287.617743ms","start":"2026-07-09T13:17:00.053989Z","end":"2026-07-09T13:17:00.341606Z","steps":["trace[31967776] 'process raft request' (duration: 287.445239ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:00.341748Z","caller":"traceutil/trace.go:171","msg":"trace[2136837242] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"285.822891ms","start":"2026-07-09T13:17:00.055895Z","end":"2026-07-09T13:17:00.341718Z","steps":["trace[2136837242] 'process raft request' (duration: 285.657527ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:00.960489Z","caller":"traceutil/trace.go:171","msg":"trace[1227066365] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"111.699168ms","start":"2026-07-09T13:17:00.848761Z","end":"2026-07-09T13:17:00.96046Z","steps":["trace[1227066365] 'process raft request' (duration: 111.533684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:01.829006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.109292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:01.829382Z","caller":"traceutil/trace.go:171","msg":"trace[2060752072] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"665.706181ms","start":"2026-07-09T13:17:01.163634Z","end":"2026-07-09T13:17:01.82934Z","steps":["trace[2060752072] 'process raft request' (duration: 270.105538ms)","trace[2060752072] 'compare' (duration: 394.711912ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:01.829516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.163598Z","time spent":"665.839773ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:17:01.829552Z","caller":"traceutil/trace.go:171","msg":"trace[255608105] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"609.020707ms","start":"2026-07-09T13:17:01.220065Z","end":"2026-07-09T13:17:01.829086Z","steps":["trace[255608105] 'read index received' (duration: 213.67118ms)","trace[255608105] 'applied index is now lower than readState.Index' (duration: 395.347567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:01.829592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.504828ms","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-09T13:17:01.829655Z","caller":"traceutil/trace.go:171","msg":"trace[1346139084] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1349; }","duration":"609.600691ms","start":"2026-07-09T13:17:01.220043Z","end":"2026-07-09T13:17:01.829643Z","steps":["trace[1346139084] 'agreement among raft nodes before linearized reading' (duration: 609.451728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:01.829681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.220029Z","time spent":"609.646142ms","remote":"127.0.0.1:41760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:17:01.844494Z","caller":"traceutil/trace.go:171","msg":"trace[1514506091] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"622.11132ms","start":"2026-07-09T13:17:01.222356Z","end":"2026-07-09T13:17:01.844467Z","steps":["trace[1514506091] 'process raft request' (duration: 621.978857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:01.844609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.400127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-07-09T13:17:01.84461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.712625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-09T13:17:01.844631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.222342Z","time spent":"622.220553ms","remote":"127.0.0.1:41710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:17:01.844645Z","caller":"traceutil/trace.go:171","msg":"trace[905696188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"480.471679ms","start":"2026-07-09T13:17:01.364163Z","end":"2026-07-09T13:17:01.844635Z","steps":["trace[905696188] 'agreement among raft nodes before linearized reading' (duration: 480.379347ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:01.844663Z","caller":"traceutil/trace.go:171","msg":"trace[626993031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"455.796477ms","start":"2026-07-09T13:17:01.388853Z","end":"2026-07-09T13:17:01.844649Z","steps":["trace[626993031] 'agreement among raft nodes before linearized reading' (duration: 455.634784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:01.844672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.36414Z","time spent":"480.52624ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:17:01.8447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.388841Z","time spent":"455.849448ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:17:02.632891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.371333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:02.633076Z","caller":"traceutil/trace.go:171","msg":"trace[1073536997] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"785.22823ms","start":"2026-07-09T13:17:01.847835Z","end":"2026-07-09T13:17:02.633064Z","steps":["trace[1073536997] 'process raft request' (duration: 785.201899ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:02.633094Z","caller":"traceutil/trace.go:171","msg":"trace[843165107] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"785.388643ms","start":"2026-07-09T13:17:01.847689Z","end":"2026-07-09T13:17:02.633078Z","steps":["trace[843165107] 'process raft request' (duration: 785.26928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:02.633138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.847818Z","time spent":"785.286771ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:02.633166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.847681Z","time spent":"785.450264ms","remote":"127.0.0.1:41710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:17:02.633271Z","caller":"traceutil/trace.go:171","msg":"trace[1966208926] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"799.838818ms","start":"2026-07-09T13:17:01.833275Z","end":"2026-07-09T13:17:02.633114Z","steps":["trace[1966208926] 'process raft request' (duration: 371.202319ms)","trace[1966208926] 'compare' (duration: 427.880541ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:02.633356Z","caller":"traceutil/trace.go:171","msg":"trace[1819683962] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"785.511736ms","start":"2026-07-09T13:17:01.847836Z","end":"2026-07-09T13:17:02.633348Z","steps":["trace[1819683962] 'process raft request' (duration: 785.171008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:02.633395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.847822Z","time spent":"785.553427ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:02.633474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.833241Z","time spent":"800.124344ms","remote":"127.0.0.1:41710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:17:02.633304Z","caller":"traceutil/trace.go:171","msg":"trace[130242347] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"788.848843ms","start":"2026-07-09T13:17:01.844448Z","end":"2026-07-09T13:17:02.633297Z","steps":["trace[130242347] 'read index received' (duration: 360.135223ms)","trace[130242347] 'applied index is now lower than readState.Index' (duration: 428.71269ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:02.633394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.83821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-07-09T13:17:02.632947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.754979ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:17:02.633707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.869069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:02.633732Z","caller":"traceutil/trace.go:171","msg":"trace[1705912747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"787.915281ms","start":"2026-07-09T13:17:01.845811Z","end":"2026-07-09T13:17:02.633727Z","steps":["trace[1705912747] 'agreement among raft nodes before linearized reading' (duration: 787.87235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:02.633754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.845801Z","time spent":"787.946631ms","remote":"127.0.0.1:41592","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-09T13:17:02.633796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.425858ms","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-09T13:17:02.63383Z","caller":"traceutil/trace.go:171","msg":"trace[1459240592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"224.49342ms","start":"2026-07-09T13:17:02.409328Z","end":"2026-07-09T13:17:02.633821Z","steps":["trace[1459240592] 'agreement among raft nodes before linearized reading' (duration: 224.405838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:02.633872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.540242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:02.6339Z","caller":"traceutil/trace.go:171","msg":"trace[1406846121] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1354; }","duration":"733.602663ms","start":"2026-07-09T13:17:01.90029Z","end":"2026-07-09T13:17:02.633893Z","steps":["trace[1406846121] 'agreement among raft nodes before linearized reading' (duration: 733.559892ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:02.633699Z","caller":"traceutil/trace.go:171","msg":"trace[544371651] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1350; }","duration":"546.392987ms","start":"2026-07-09T13:17:02.087295Z","end":"2026-07-09T13:17:02.633688Z","steps":["trace[544371651] 'range keys from in-memory index tree' (duration: 544.740917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:02.633925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.900274Z","time spent":"733.645514ms","remote":"127.0.0.1:33044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:17:02.633663Z","caller":"traceutil/trace.go:171","msg":"trace[937942098] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1354; }","duration":"801.138817ms","start":"2026-07-09T13:17:01.832515Z","end":"2026-07-09T13:17:02.633654Z","steps":["trace[937942098] 'agreement among raft nodes before linearized reading' (duration: 800.803189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:02.634185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:01.832499Z","time spent":"801.67768ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T13:17:03.249601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.301561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:03.249732Z","caller":"traceutil/trace.go:171","msg":"trace[327165771] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"611.398663ms","start":"2026-07-09T13:17:02.638308Z","end":"2026-07-09T13:17:03.249707Z","steps":["trace[327165771] 'process raft request' (duration: 103.918257ms)","trace[327165771] 'compare' (duration: 507.159038ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:03.249818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:02.638282Z","time spent":"611.501484ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:17:03.256542Z","caller":"traceutil/trace.go:171","msg":"trace[296097391] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"317.815172ms","start":"2026-07-09T13:17:02.938675Z","end":"2026-07-09T13:17:03.256491Z","steps":["trace[296097391] 'read index received' (duration: 317.660448ms)","trace[296097391] 'applied index is now lower than readState.Index' (duration: 153.844µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:03.256689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.004846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:03.256732Z","caller":"traceutil/trace.go:171","msg":"trace[994946340] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1356; }","duration":"318.104308ms","start":"2026-07-09T13:17:02.938618Z","end":"2026-07-09T13:17:03.256722Z","steps":["trace[994946340] 'agreement among raft nodes before linearized reading' (duration: 318.016576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:03.256806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:02.938595Z","time spent":"318.200821ms","remote":"127.0.0.1:42160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:17:03.25659Z","caller":"traceutil/trace.go:171","msg":"trace[940501362] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"617.975934ms","start":"2026-07-09T13:17:02.638583Z","end":"2026-07-09T13:17:03.256559Z","steps":["trace[940501362] 'process raft request' (duration: 617.78408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:03.256995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:02.638574Z","time spent":"618.347712ms","remote":"127.0.0.1:41916","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-09T13:17:03.275845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.003056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.206\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-09T13:17:03.275857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.882356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:17:03.275851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.474081ms","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-09T13:17:03.275898Z","caller":"traceutil/trace.go:171","msg":"trace[1825778464] range","detail":"{range_begin:/registry/masterleases/199.19.213.206; range_end:; response_count:1; response_revision:1357; }","duration":"116.102069ms","start":"2026-07-09T13:17:03.159785Z","end":"2026-07-09T13:17:03.275887Z","steps":["trace[1825778464] 'agreement among raft nodes before linearized reading' (duration: 115.945775ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:03.275909Z","caller":"traceutil/trace.go:171","msg":"trace[1711250537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"248.957727ms","start":"2026-07-09T13:17:03.026938Z","end":"2026-07-09T13:17:03.275896Z","steps":["trace[1711250537] 'agreement among raft nodes before linearized reading' (duration: 248.821314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:03.275873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.202286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:03.276007Z","caller":"traceutil/trace.go:171","msg":"trace[543639408] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1357; }","duration":"250.377889ms","start":"2026-07-09T13:17:03.025616Z","end":"2026-07-09T13:17:03.275994Z","steps":["trace[543639408] 'agreement among raft nodes before linearized reading' (duration: 250.181245ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:03.27596Z","caller":"traceutil/trace.go:171","msg":"trace[433766405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"272.592504ms","start":"2026-07-09T13:17:03.003313Z","end":"2026-07-09T13:17:03.275906Z","steps":["trace[433766405] 'agreement among raft nodes before linearized reading' (duration: 272.386799ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:03.276188Z","caller":"traceutil/trace.go:171","msg":"trace[740067380] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"309.731994ms","start":"2026-07-09T13:17:02.966444Z","end":"2026-07-09T13:17:03.276176Z","steps":["trace[740067380] 'process raft request' (duration: 309.148191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:03.276277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:02.966417Z","time spent":"309.806306ms","remote":"127.0.0.1:41916","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-09T13:17:04.803861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308525211s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:04.804164Z","caller":"traceutil/trace.go:171","msg":"trace[665768437] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"1.523557792s","start":"2026-07-09T13:17:03.280583Z","end":"2026-07-09T13:17:04.804141Z","steps":["trace[665768437] 'process raft request' (duration: 1.523379177s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:04.804299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:03.28056Z","time spent":"1.523685034s","remote":"127.0.0.1:41916","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-09T13:17:04.804352Z","caller":"traceutil/trace.go:171","msg":"trace[1310686893] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1416; }","duration":"1.047614427s","start":"2026-07-09T13:17:03.756718Z","end":"2026-07-09T13:17:04.804332Z","steps":["trace[1310686893] 'read index received' (duration: 125.381585ms)","trace[1310686893] 'applied index is now lower than readState.Index' (duration: 922.231452ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:04.804427Z","caller":"traceutil/trace.go:171","msg":"trace[168530054] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.524676958s","start":"2026-07-09T13:17:03.279722Z","end":"2026-07-09T13:17:04.804399Z","steps":["trace[168530054] 'process raft request' (duration: 215.535863ms)","trace[168530054] 'compare' (duration: 1.308267154s)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:04.804546Z","caller":"traceutil/trace.go:171","msg":"trace[735274153] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.477775501s","start":"2026-07-09T13:17:03.326753Z","end":"2026-07-09T13:17:04.804529Z","steps":["trace[735274153] 'process raft request' (duration: 1.477326791s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:04.804558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:03.279704Z","time spent":"1.52477921s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:04.804677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:03.326735Z","time spent":"1.477850404s","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:04.804717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047992555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:17:04.804768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.134052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T13:17:04.804768Z","caller":"traceutil/trace.go:171","msg":"trace[1911593731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"1.048068177s","start":"2026-07-09T13:17:03.756683Z","end":"2026-07-09T13:17:04.804751Z","steps":["trace[1911593731] 'agreement among raft nodes before linearized reading' (duration: 1.047991496s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:04.804759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.274772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:04.804794Z","caller":"traceutil/trace.go:171","msg":"trace[225572856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"167.196713ms","start":"2026-07-09T13:17:04.637591Z","end":"2026-07-09T13:17:04.804788Z","steps":["trace[225572856] 'agreement among raft nodes before linearized reading' (duration: 167.097461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:04.804763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.13521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:17:04.804813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:03.756669Z","time spent":"1.048132889s","remote":"127.0.0.1:41596","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-09T13:17:04.804829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.931714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:17:04.804835Z","caller":"traceutil/trace.go:171","msg":"trace[931351324] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1360; }","duration":"577.401415ms","start":"2026-07-09T13:17:04.22741Z","end":"2026-07-09T13:17:04.804811Z","steps":["trace[931351324] 'agreement among raft nodes before linearized reading' (duration: 577.282362ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:04.804848Z","caller":"traceutil/trace.go:171","msg":"trace[740698203] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1360; }","duration":"195.265453ms","start":"2026-07-09T13:17:04.609568Z","end":"2026-07-09T13:17:04.804834Z","steps":["trace[740698203] 'agreement among raft nodes before linearized reading' (duration: 195.14152ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:04.804879Z","caller":"traceutil/trace.go:171","msg":"trace[167609870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1360; }","duration":"165.998045ms","start":"2026-07-09T13:17:04.638867Z","end":"2026-07-09T13:17:04.804865Z","steps":["trace[167609870] 'agreement among raft nodes before linearized reading' (duration: 165.832301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:04.804895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:04.227389Z","time spent":"577.489806ms","remote":"127.0.0.1:41786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:17:05.329561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.555339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:05.329686Z","caller":"traceutil/trace.go:171","msg":"trace[573774956] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"519.285319ms","start":"2026-07-09T13:17:04.810365Z","end":"2026-07-09T13:17:05.329651Z","steps":["trace[573774956] 'process raft request' (duration: 181.519785ms)","trace[573774956] 'compare' (duration: 337.433576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:05.329762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:04.810343Z","time spent":"519.37858ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:05.776161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.73727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:05.776291Z","caller":"traceutil/trace.go:171","msg":"trace[1175313000] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1419; }","duration":"965.205869ms","start":"2026-07-09T13:17:04.811066Z","end":"2026-07-09T13:17:05.776271Z","steps":["trace[1175313000] 'read index received' (duration: 180.765328ms)","trace[1175313000] 'applied index is now lower than readState.Index' (duration: 784.438621ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:05.776378Z","caller":"traceutil/trace.go:171","msg":"trace[1245692316] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"965.921695ms","start":"2026-07-09T13:17:04.810403Z","end":"2026-07-09T13:17:05.776325Z","steps":["trace[1245692316] 'process raft request' (duration: 685.881508ms)","trace[1245692316] 'compare' (duration: 279.501625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:05.776499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:04.810384Z","time spent":"966.060958ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:05.776714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.609188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T13:17:05.777545Z","caller":"traceutil/trace.go:171","msg":"trace[752887368] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1362; }","duration":"966.491477ms","start":"2026-07-09T13:17:04.811037Z","end":"2026-07-09T13:17:05.777529Z","steps":["trace[752887368] 'agreement among raft nodes before linearized reading' (duration: 965.546706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:05.777614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:04.811028Z","time spent":"966.57554ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-07-09T13:17:05.782056Z","caller":"traceutil/trace.go:171","msg":"trace[1697128145] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"501.115117ms","start":"2026-07-09T13:17:05.28093Z","end":"2026-07-09T13:17:05.782045Z","steps":["trace[1697128145] 'process raft request' (duration: 501.033676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:05.782297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:05.280907Z","time spent":"501.341743ms","remote":"127.0.0.1:41916","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-09T13:17:05.828451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.28483ms","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-09T13:17:05.828517Z","caller":"traceutil/trace.go:171","msg":"trace[21714167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1364; }","duration":"567.387622ms","start":"2026-07-09T13:17:05.261115Z","end":"2026-07-09T13:17:05.828502Z","steps":["trace[21714167] 'agreement among raft nodes before linearized reading' (duration: 567.191108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:05.828548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:05.261101Z","time spent":"567.441554ms","remote":"127.0.0.1:41916","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-09T13:17:05.828563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015644486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:05.828676Z","caller":"traceutil/trace.go:171","msg":"trace[1271276512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"1.015784599s","start":"2026-07-09T13:17:04.812852Z","end":"2026-07-09T13:17:05.828636Z","steps":["trace[1271276512] 'agreement among raft nodes before linearized reading' (duration: 1.015650836s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:05.828738Z","caller":"traceutil/trace.go:171","msg":"trace[1952940658] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"262.822487ms","start":"2026-07-09T13:17:05.565907Z","end":"2026-07-09T13:17:05.828729Z","steps":["trace[1952940658] 'process raft request' (duration: 262.208823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:05.828744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:04.812838Z","time spent":"1.015891561s","remote":"127.0.0.1:41596","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-09T13:17:05.82887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.906433ms","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-09T13:17:05.828897Z","caller":"traceutil/trace.go:171","msg":"trace[960853533] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1364; }","duration":"219.937284ms","start":"2026-07-09T13:17:05.608953Z","end":"2026-07-09T13:17:05.828891Z","steps":["trace[960853533] 'agreement among raft nodes before linearized reading' (duration: 219.867872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:05.829036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.281413ms","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-09T13:17:05.829063Z","caller":"traceutil/trace.go:171","msg":"trace[1650883061] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1364; }","duration":"220.346165ms","start":"2026-07-09T13:17:05.60871Z","end":"2026-07-09T13:17:05.829056Z","steps":["trace[1650883061] 'agreement among raft nodes before linearized reading' (duration: 220.295384ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:06.147519Z","caller":"traceutil/trace.go:171","msg":"trace[1279710905] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"310.668316ms","start":"2026-07-09T13:17:05.836824Z","end":"2026-07-09T13:17:06.147493Z","steps":["trace[1279710905] 'read index received' (duration: 267.130988ms)","trace[1279710905] 'applied index is now lower than readState.Index' (duration: 43.535958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:06.147633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.787249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:06.147664Z","caller":"traceutil/trace.go:171","msg":"trace[82582897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"310.846521ms","start":"2026-07-09T13:17:05.836807Z","end":"2026-07-09T13:17:06.147654Z","steps":["trace[82582897] 'agreement among raft nodes before linearized reading' (duration: 310.773889ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:06.147621Z","caller":"traceutil/trace.go:171","msg":"trace[116890456] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"314.857693ms","start":"2026-07-09T13:17:05.832685Z","end":"2026-07-09T13:17:06.147543Z","steps":["trace[116890456] 'process raft request' (duration: 271.327636ms)","trace[116890456] 'compare' (duration: 43.288762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:06.147694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:05.836801Z","time spent":"310.886852ms","remote":"127.0.0.1:41596","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-09T13:17:06.147906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:05.832672Z","time spent":"315.042147ms","remote":"127.0.0.1:41916","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-09T13:17:12.378814Z","caller":"traceutil/trace.go:171","msg":"trace[596740534] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"152.38534ms","start":"2026-07-09T13:17:12.226374Z","end":"2026-07-09T13:17:12.378759Z","steps":["trace[596740534] 'read index received' (duration: 152.087162ms)","trace[596740534] 'applied index is now lower than readState.Index' (duration: 296.928µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:12.378863Z","caller":"traceutil/trace.go:171","msg":"trace[1633483598] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"193.617296ms","start":"2026-07-09T13:17:12.185197Z","end":"2026-07-09T13:17:12.378814Z","steps":["trace[1633483598] 'process raft request' (duration: 193.324758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:12.378994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.579195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:17:12.379066Z","caller":"traceutil/trace.go:171","msg":"trace[1182491493] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1419; }","duration":"152.730558ms","start":"2026-07-09T13:17:12.226319Z","end":"2026-07-09T13:17:12.37905Z","steps":["trace[1182491493] 'agreement among raft nodes before linearized reading' (duration: 152.590115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:13.029901Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934753838099748164,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:17:13.488704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.98789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:13.488868Z","caller":"traceutil/trace.go:171","msg":"trace[240708885] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"959.631319ms","start":"2026-07-09T13:17:12.529213Z","end":"2026-07-09T13:17:13.488844Z","steps":["trace[240708885] 'read index received' (duration: 806.543093ms)","trace[240708885] 'applied index is now lower than readState.Index' (duration: 153.085226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:13.489072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.838074ms","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:532"} {"level":"info","ts":"2026-07-09T13:17:13.489138Z","caller":"traceutil/trace.go:171","msg":"trace[1394833150] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1420; }","duration":"959.953997ms","start":"2026-07-09T13:17:12.529166Z","end":"2026-07-09T13:17:13.48912Z","steps":["trace[1394833150] 'agreement among raft nodes before linearized reading' (duration: 959.761442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:13.489177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:12.529143Z","time spent":"960.025578ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-09T13:17:13.488873Z","caller":"traceutil/trace.go:171","msg":"trace[126818626] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"1.034109604s","start":"2026-07-09T13:17:12.454729Z","end":"2026-07-09T13:17:13.488838Z","steps":["trace[126818626] 'process raft request' (duration: 881.10302ms)","trace[126818626] 'compare' (duration: 151.814256ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:13.489376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.477839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.206\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-09T13:17:13.489404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.997923ms","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-09T13:17:13.489421Z","caller":"traceutil/trace.go:171","msg":"trace[1066771223] range","detail":"{range_begin:/registry/masterleases/199.19.213.206; range_end:; response_count:1; response_revision:1420; }","duration":"328.585701ms","start":"2026-07-09T13:17:13.160823Z","end":"2026-07-09T13:17:13.489408Z","steps":["trace[1066771223] 'agreement among raft nodes before linearized reading' (duration: 328.410787ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:13.489471Z","caller":"traceutil/trace.go:171","msg":"trace[1886567626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1420; }","duration":"652.088545ms","start":"2026-07-09T13:17:12.837366Z","end":"2026-07-09T13:17:13.489454Z","steps":["trace[1886567626] 'agreement among raft nodes before linearized reading' (duration: 651.889711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:13.48949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:13.160804Z","time spent":"328.677073ms","remote":"127.0.0.1:41650","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.206\" "} {"level":"warn","ts":"2026-07-09T13:17:13.489493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:12.454716Z","time spent":"1.034635446s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:17:13.489519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:12.837354Z","time spent":"652.151457ms","remote":"127.0.0.1:41916","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-09T13:17:13.489611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.321103ms","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-09T13:17:13.48969Z","caller":"traceutil/trace.go:171","msg":"trace[653787882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"656.476256ms","start":"2026-07-09T13:17:12.8332Z","end":"2026-07-09T13:17:13.489676Z","steps":["trace[653787882] 'agreement among raft nodes before linearized reading' (duration: 655.970325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:13.489784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:17:12.83318Z","time spent":"656.591339ms","remote":"127.0.0.1:41916","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-09T13:17:13.489879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.727316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-07-09T13:17:13.489699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.433402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:17:13.48991Z","caller":"traceutil/trace.go:171","msg":"trace[927695795] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1420; }","duration":"221.785298ms","start":"2026-07-09T13:17:13.268116Z","end":"2026-07-09T13:17:13.489901Z","steps":["trace[927695795] 'agreement among raft nodes before linearized reading' (duration: 221.683585ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:13.489969Z","caller":"traceutil/trace.go:171","msg":"trace[884011467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1420; }","duration":"127.706438ms","start":"2026-07-09T13:17:13.362217Z","end":"2026-07-09T13:17:13.489923Z","steps":["trace[884011467] 'agreement among raft nodes before linearized reading' (duration: 127.405291ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:25.699949Z","caller":"traceutil/trace.go:171","msg":"trace[740986569] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"115.210128ms","start":"2026-07-09T13:17:25.584716Z","end":"2026-07-09T13:17:25.699926Z","steps":["trace[740986569] 'process raft request' (duration: 115.049134ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:29.072897Z","caller":"traceutil/trace.go:171","msg":"trace[1893711080] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"100.56788ms","start":"2026-07-09T13:17:28.972304Z","end":"2026-07-09T13:17:29.072871Z","steps":["trace[1893711080] 'process raft request' (duration: 100.416407ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:40.12572Z","caller":"traceutil/trace.go:171","msg":"trace[1473501140] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"137.831865ms","start":"2026-07-09T13:17:39.98786Z","end":"2026-07-09T13:17:40.125692Z","steps":["trace[1473501140] 'process raft request' (duration: 131.889847ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:40.126117Z","caller":"traceutil/trace.go:171","msg":"trace[840972309] linearizableReadLoop","detail":"{readStateIndex:1613; appliedIndex:1611; }","duration":"104.645323ms","start":"2026-07-09T13:17:40.021458Z","end":"2026-07-09T13:17:40.126103Z","steps":["trace[840972309] 'read index received' (duration: 98.302946ms)","trace[840972309] 'applied index is now lower than readState.Index' (duration: 6.341437ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:40.126324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.853528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T13:17:40.126365Z","caller":"traceutil/trace.go:171","msg":"trace[1021027471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1547; }","duration":"104.954491ms","start":"2026-07-09T13:17:40.0214Z","end":"2026-07-09T13:17:40.126355Z","steps":["trace[1021027471] 'agreement among raft nodes before linearized reading' (duration: 104.773016ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:40.126378Z","caller":"traceutil/trace.go:171","msg":"trace[552844594] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"135.135963ms","start":"2026-07-09T13:17:39.991224Z","end":"2026-07-09T13:17:40.12636Z","steps":["trace[552844594] 'process raft request' (duration: 134.814944ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:56.813936Z","caller":"traceutil/trace.go:171","msg":"trace[662542866] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"266.422492ms","start":"2026-07-09T13:18:56.547477Z","end":"2026-07-09T13:18:56.813899Z","steps":["trace[662542866] 'process raft request' (duration: 265.97388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:18:58.636155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.516208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T13:18:58.63644Z","caller":"traceutil/trace.go:171","msg":"trace[465588106] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"161.879846ms","start":"2026-07-09T13:18:58.47454Z","end":"2026-07-09T13:18:58.636419Z","steps":["trace[465588106] 'process raft request' (duration: 161.722553ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:58.799069Z","caller":"traceutil/trace.go:171","msg":"trace[1196750174] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1983; }","duration":"250.908951ms","start":"2026-07-09T13:18:58.54814Z","end":"2026-07-09T13:18:58.799049Z","steps":["trace[1196750174] 'read index received' (duration: 26.841562ms)","trace[1196750174] 'applied index is now lower than readState.Index' (duration: 224.066419ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:18:58.799184Z","caller":"traceutil/trace.go:171","msg":"trace[1985257576] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"291.686987ms","start":"2026-07-09T13:18:58.507486Z","end":"2026-07-09T13:18:58.799173Z","steps":["trace[1985257576] 'process raft request' (duration: 240.870428ms)","trace[1985257576] 'compare' (duration: 50.610124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:18:58.799524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.372141ms","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:532"} {"level":"info","ts":"2026-07-09T13:18:58.799556Z","caller":"traceutil/trace.go:171","msg":"trace[1516126548] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1904; }","duration":"251.451853ms","start":"2026-07-09T13:18:58.548096Z","end":"2026-07-09T13:18:58.799548Z","steps":["trace[1516126548] 'agreement among raft nodes before linearized reading' (duration: 251.333121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:04.584973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.168677ms","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":"warn","ts":"2026-07-09T13:19:04.584991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.92419ms","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-09T13:19:04.585065Z","caller":"traceutil/trace.go:171","msg":"trace[1945217684] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1931; }","duration":"176.314ms","start":"2026-07-09T13:19:04.408733Z","end":"2026-07-09T13:19:04.585047Z","steps":["trace[1945217684] 'count revisions from in-memory index tree' (duration: 176.078175ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:04.585078Z","caller":"traceutil/trace.go:171","msg":"trace[1644165265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1931; }","duration":"229.106655ms","start":"2026-07-09T13:19:04.355951Z","end":"2026-07-09T13:19:04.585058Z","steps":["trace[1644165265] 'range keys from in-memory index tree' (duration: 228.687034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:04.585105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.11599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T13:19:04.58521Z","caller":"traceutil/trace.go:171","msg":"trace[1892311976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1931; }","duration":"149.258503ms","start":"2026-07-09T13:19:04.435928Z","end":"2026-07-09T13:19:04.585186Z","steps":["trace[1892311976] 'range keys from in-memory index tree' (duration: 148.938666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:19.11991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.665863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:19:19.120118Z","caller":"traceutil/trace.go:171","msg":"trace[153893026] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"234.499521ms","start":"2026-07-09T13:19:18.885601Z","end":"2026-07-09T13:19:19.1201Z","steps":["trace[153893026] 'process raft request' (duration: 234.416169ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:19.120101Z","caller":"traceutil/trace.go:171","msg":"trace[968023862] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"432.419442ms","start":"2026-07-09T13:19:18.687657Z","end":"2026-07-09T13:19:19.120076Z","steps":["trace[968023862] 'process raft request' (duration: 263.518383ms)","trace[968023862] 'compare' (duration: 168.475589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:19:19.120221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:19:18.687638Z","time spent":"432.520535ms","remote":"127.0.0.1:41916","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-09T13:19:19.120312Z","caller":"traceutil/trace.go:171","msg":"trace[41671820] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"362.454969ms","start":"2026-07-09T13:19:18.757846Z","end":"2026-07-09T13:19:19.120301Z","steps":["trace[41671820] 'read index received' (duration: 193.338915ms)","trace[41671820] 'applied index is now lower than readState.Index' (duration: 169.114964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:19:19.120466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.624822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:19:19.120503Z","caller":"traceutil/trace.go:171","msg":"trace[568459851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1996; }","duration":"362.695184ms","start":"2026-07-09T13:19:18.757798Z","end":"2026-07-09T13:19:19.120493Z","steps":["trace[568459851] 'agreement among raft nodes before linearized reading' (duration: 362.642703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:19.120523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:19:18.757779Z","time spent":"362.740125ms","remote":"127.0.0.1:41596","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-09T13:19:19.120567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.377817ms","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-09T13:19:19.120595Z","caller":"traceutil/trace.go:171","msg":"trace[1322344967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1996; }","duration":"312.438898ms","start":"2026-07-09T13:19:18.808146Z","end":"2026-07-09T13:19:19.120585Z","steps":["trace[1322344967] 'agreement among raft nodes before linearized reading' (duration: 312.343936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:19.120615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:19:18.808128Z","time spent":"312.481749ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T13:19:19.120722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.2859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:19:19.120722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.881835ms","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-09T13:19:19.120797Z","caller":"traceutil/trace.go:171","msg":"trace[2051147875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1996; }","duration":"170.994558ms","start":"2026-07-09T13:19:18.949783Z","end":"2026-07-09T13:19:19.120777Z","steps":["trace[2051147875] 'agreement among raft nodes before linearized reading' (duration: 170.734092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:19.120812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.938427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-09T13:19:19.120815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.844329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-09T13:19:19.120834Z","caller":"traceutil/trace.go:171","msg":"trace[189684895] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1996; }","duration":"280.986839ms","start":"2026-07-09T13:19:18.83984Z","end":"2026-07-09T13:19:19.120827Z","steps":["trace[189684895] 'agreement among raft nodes before linearized reading' (duration: 280.926317ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:19.120852Z","caller":"traceutil/trace.go:171","msg":"trace[1030921375] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1996; }","duration":"278.89928ms","start":"2026-07-09T13:19:18.841944Z","end":"2026-07-09T13:19:19.120843Z","steps":["trace[1030921375] 'agreement among raft nodes before linearized reading' (duration: 278.767107ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:19.120753Z","caller":"traceutil/trace.go:171","msg":"trace[1373680742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1996; }","duration":"201.391653ms","start":"2026-07-09T13:19:18.919352Z","end":"2026-07-09T13:19:19.120744Z","steps":["trace[1373680742] 'agreement among raft nodes before linearized reading' (duration: 201.349722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:19.12094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.847602ms","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:532"} {"level":"info","ts":"2026-07-09T13:19:19.120967Z","caller":"traceutil/trace.go:171","msg":"trace[1321943878] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1996; }","duration":"207.901443ms","start":"2026-07-09T13:19:18.91306Z","end":"2026-07-09T13:19:19.120961Z","steps":["trace[1321943878] 'agreement among raft nodes before linearized reading' (duration: 207.840781ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:19.401596Z","caller":"traceutil/trace.go:171","msg":"trace[1712575288] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"274.960929ms","start":"2026-07-09T13:19:19.126602Z","end":"2026-07-09T13:19:19.401563Z","steps":["trace[1712575288] 'process raft request' (duration: 183.483217ms)","trace[1712575288] 'compare' (duration: 91.045922ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:19:19.401736Z","caller":"traceutil/trace.go:171","msg":"trace[194699333] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"275.023832ms","start":"2026-07-09T13:19:19.126676Z","end":"2026-07-09T13:19:19.4017Z","steps":["trace[194699333] 'process raft request' (duration: 274.750835ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:19.401741Z","caller":"traceutil/trace.go:171","msg":"trace[851693152] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2083; }","duration":"127.265143ms","start":"2026-07-09T13:19:19.274431Z","end":"2026-07-09T13:19:19.401696Z","steps":["trace[851693152] 'read index received' (duration: 35.564825ms)","trace[851693152] 'applied index is now lower than readState.Index' (duration: 91.698848ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:19:19.401804Z","caller":"traceutil/trace.go:171","msg":"trace[885420374] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"275.171124ms","start":"2026-07-09T13:19:19.12661Z","end":"2026-07-09T13:19:19.401781Z","steps":["trace[885420374] 'process raft request' (duration: 274.748625ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:19.401798Z","caller":"traceutil/trace.go:171","msg":"trace[1728749854] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"275.069482ms","start":"2026-07-09T13:19:19.126699Z","end":"2026-07-09T13:19:19.401769Z","steps":["trace[1728749854] 'process raft request' (duration: 274.878848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:19.401941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.488349ms","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-09T13:19:19.401992Z","caller":"traceutil/trace.go:171","msg":"trace[1450037859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2001; }","duration":"127.58931ms","start":"2026-07-09T13:19:19.27439Z","end":"2026-07-09T13:19:19.401979Z","steps":["trace[1450037859] 'agreement among raft nodes before linearized reading' (duration: 127.430376ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:59.611942Z","caller":"traceutil/trace.go:171","msg":"trace[1760839731] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"128.585578ms","start":"2026-07-09T13:19:59.483332Z","end":"2026-07-09T13:19:59.611918Z","steps":["trace[1760839731] 'process raft request' (duration: 126.845027ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:59.612077Z","caller":"traceutil/trace.go:171","msg":"trace[648016391] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"124.917839ms","start":"2026-07-09T13:19:59.487131Z","end":"2026-07-09T13:19:59.612049Z","steps":["trace[648016391] 'process raft request' (duration: 124.471289ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:59.869058Z","caller":"traceutil/trace.go:171","msg":"trace[716818795] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2279; }","duration":"111.935977ms","start":"2026-07-09T13:19:59.757096Z","end":"2026-07-09T13:19:59.869032Z","steps":["trace[716818795] 'read index received' (duration: 73.498261ms)","trace[716818795] 'applied index is now lower than readState.Index' (duration: 38.436586ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:19:59.869153Z","caller":"traceutil/trace.go:171","msg":"trace[1275681830] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"206.620578ms","start":"2026-07-09T13:19:59.662464Z","end":"2026-07-09T13:19:59.869085Z","steps":["trace[1275681830] 'process raft request' (duration: 168.139421ms)","trace[1275681830] 'compare' (duration: 38.256182ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:19:59.869186Z","caller":"traceutil/trace.go:171","msg":"trace[1469026893] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"206.235579ms","start":"2026-07-09T13:19:59.662907Z","end":"2026-07-09T13:19:59.869142Z","steps":["trace[1469026893] 'process raft request' (duration: 206.071685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:19:59.869192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.0864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:19:59.869416Z","caller":"traceutil/trace.go:171","msg":"trace[766892178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2187; }","duration":"112.356227ms","start":"2026-07-09T13:19:59.757043Z","end":"2026-07-09T13:19:59.869399Z","steps":["trace[766892178] 'agreement among raft nodes before linearized reading' (duration: 112.100901ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:21:06.421267Z","caller":"traceutil/trace.go:171","msg":"trace[17310431] linearizableReadLoop","detail":"{readStateIndex:2621; appliedIndex:2620; }","duration":"124.132394ms","start":"2026-07-09T13:21:06.29711Z","end":"2026-07-09T13:21:06.421242Z","steps":["trace[17310431] 'read index received' (duration: 123.925029ms)","trace[17310431] 'applied index is now lower than readState.Index' (duration: 206.515µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:21:06.421339Z","caller":"traceutil/trace.go:171","msg":"trace[120937386] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"141.778826ms","start":"2026-07-09T13:21:06.279519Z","end":"2026-07-09T13:21:06.421298Z","steps":["trace[120937386] 'process raft request' (duration: 141.588082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:21:06.421462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.333188ms","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:532"} {"level":"info","ts":"2026-07-09T13:21:06.421505Z","caller":"traceutil/trace.go:171","msg":"trace[1317032934] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2513; }","duration":"124.41846ms","start":"2026-07-09T13:21:06.297075Z","end":"2026-07-09T13:21:06.421494Z","steps":["trace[1317032934] 'agreement among raft nodes before linearized reading' (duration: 124.269976ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:21:12.101996Z","caller":"traceutil/trace.go:171","msg":"trace[1360377573] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"164.245863ms","start":"2026-07-09T13:21:11.937718Z","end":"2026-07-09T13:21:12.101964Z","steps":["trace[1360377573] 'process raft request' (duration: 164.015038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:21:12.303654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.99379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:21:12.303886Z","caller":"traceutil/trace.go:171","msg":"trace[2008234505] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"198.490624ms","start":"2026-07-09T13:21:12.105365Z","end":"2026-07-09T13:21:12.303856Z","steps":["trace[2008234505] 'process raft request' (duration: 95.151466ms)","trace[2008234505] 'compare' (duration: 102.726724ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:21:26.278875Z","caller":"traceutil/trace.go:171","msg":"trace[735663807] transaction","detail":"{read_only:false; response_revision:2615; number_of_response:1; }","duration":"111.471157ms","start":"2026-07-09T13:21:26.167374Z","end":"2026-07-09T13:21:26.278845Z","steps":["trace[735663807] 'process raft request' (duration: 111.217242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:21:41.491397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.216012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-09T13:21:41.49149Z","caller":"traceutil/trace.go:171","msg":"trace[2026925323] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:2684; }","duration":"117.361915ms","start":"2026-07-09T13:21:41.374107Z","end":"2026-07-09T13:21:41.491468Z","steps":["trace[2026925323] 'range keys from in-memory index tree' (duration: 117.037198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:21:41.491619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.520304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-09T13:21:41.491661Z","caller":"traceutil/trace.go:171","msg":"trace[679800194] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:2684; }","duration":"142.590605ms","start":"2026-07-09T13:21:41.349059Z","end":"2026-07-09T13:21:41.491649Z","steps":["trace[679800194] 'range keys from in-memory index tree' (duration: 142.408552ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:21:50.064444Z","caller":"traceutil/trace.go:171","msg":"trace[329582112] linearizableReadLoop","detail":"{readStateIndex:2842; appliedIndex:2841; }","duration":"121.075894ms","start":"2026-07-09T13:21:49.943336Z","end":"2026-07-09T13:21:50.064412Z","steps":["trace[329582112] 'read index received' (duration: 102.894079ms)","trace[329582112] 'applied index is now lower than readState.Index' (duration: 18.179455ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:21:50.064506Z","caller":"traceutil/trace.go:171","msg":"trace[1854750075] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"176.356456ms","start":"2026-07-09T13:21:49.88812Z","end":"2026-07-09T13:21:50.064476Z","steps":["trace[1854750075] 'process raft request' (duration: 158.10797ms)","trace[1854750075] 'compare' (duration: 17.856977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:21:50.064726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.37247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cxq7k\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-07-09T13:21:50.064789Z","caller":"traceutil/trace.go:171","msg":"trace[1984148409] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cxq7k; range_end:; response_count:1; response_revision:2724; }","duration":"121.473032ms","start":"2026-07-09T13:21:49.9433Z","end":"2026-07-09T13:21:50.064773Z","steps":["trace[1984148409] 'agreement among raft nodes before linearized reading' (duration: 121.280898ms)"],"step_count":1}