{"level":"warn","ts":"2026-08-15T02:41:53.911397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:41:53.911479Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.221: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.221:2380","--initial-cluster=instance=https://199.19.213.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.221: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-08-15T02:41:53.911541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:41:53.911551Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.221:2380"]} {"level":"info","ts":"2026-08-15T02:41:53.911567Z","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-08-15T02:41:53.913777Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.221:2379"]} {"level":"info","ts":"2026-08-15T02:41:53.913863Z","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.221:2380"],"listen-peer-urls":["https://199.19.213.221:2380"],"advertise-client-urls":["https://199.19.213.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.221: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-08-15T02:41:53.918446Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.395738ms"} {"level":"info","ts":"2026-08-15T02:41:53.930075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b506daf99e68981a","cluster-id":"d2c45690f9793857"} {"level":"info","ts":"2026-08-15T02:41:53.930198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T02:41:53.930234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became follower at term 0"} {"level":"info","ts":"2026-08-15T02:41:53.930257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b506daf99e68981a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T02:41:53.930281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became follower at term 1"} {"level":"info","ts":"2026-08-15T02:41:53.930357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a switched to configuration voters=(13044354136364587034)"} {"level":"warn","ts":"2026-08-15T02:41:53.946419Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T02:41:53.948056Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T02:41:53.951321Z","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-08-15T02:41:53.954534Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b506daf99e68981a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T02:41:53.955424Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b506daf99e68981a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T02:41:53.955529Z","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-08-15T02:41:53.955557Z","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-08-15T02:41:53.955563Z","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-08-15T02:41:53.956001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a switched to configuration voters=(13044354136364587034)"} {"level":"info","ts":"2026-08-15T02:41:53.957862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d2c45690f9793857","local-member-id":"b506daf99e68981a","added-peer-id":"b506daf99e68981a","added-peer-peer-urls":["https://199.19.213.221:2380"]} {"level":"info","ts":"2026-08-15T02:41:53.966059Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.221:2380"} {"level":"info","ts":"2026-08-15T02:41:53.966094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.221:2380"} {"level":"info","ts":"2026-08-15T02:41:53.965938Z","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-08-15T02:41:53.966976Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b506daf99e68981a","initial-advertise-peer-urls":["https://199.19.213.221:2380"],"listen-peer-urls":["https://199.19.213.221:2380"],"advertise-client-urls":["https://199.19.213.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T02:41:53.967021Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T02:41:54.230703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T02:41:54.23081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T02:41:54.230844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a received MsgPreVoteResp from b506daf99e68981a at term 1"} {"level":"info","ts":"2026-08-15T02:41:54.230868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became candidate at term 2"} {"level":"info","ts":"2026-08-15T02:41:54.230881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a received MsgVoteResp from b506daf99e68981a at term 2"} {"level":"info","ts":"2026-08-15T02:41:54.230901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became leader at term 2"} {"level":"info","ts":"2026-08-15T02:41:54.230923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b506daf99e68981a elected leader b506daf99e68981a at term 2"} {"level":"info","ts":"2026-08-15T02:41:54.232211Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b506daf99e68981a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.221:2379]}","request-path":"/0/members/b506daf99e68981a/attributes","cluster-id":"d2c45690f9793857","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T02:41:54.232259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:41:54.232398Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:41:54.232459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:41:54.232676Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T02:41:54.23271Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-15T02:41:54.233404Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d2c45690f9793857","local-member-id":"b506daf99e68981a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:41:54.233524Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:41:54.233582Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:41:54.236852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T02:41:54.236907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.221:2379"} {"level":"info","ts":"2026-08-15T02:42:31.640923Z","caller":"traceutil/trace.go:171","msg":"trace[1734296502] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"384.269195ms","start":"2026-08-15T02:42:31.256616Z","end":"2026-08-15T02:42:31.640885Z","steps":["trace[1734296502] 'process raft request' (duration: 384.120171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.641508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.256594Z","time spent":"384.462461ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.875397Z","caller":"traceutil/trace.go:171","msg":"trace[2102190606] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"615.11571ms","start":"2026-08-15T02:42:31.260258Z","end":"2026-08-15T02:42:31.875374Z","steps":["trace[2102190606] 'process raft request' (duration: 614.812322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.875589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.260247Z","time spent":"615.282124ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.875883Z","caller":"traceutil/trace.go:171","msg":"trace[197017437] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"603.355006ms","start":"2026-08-15T02:42:31.272488Z","end":"2026-08-15T02:42:31.875843Z","steps":["trace[197017437] 'process raft request' (duration: 602.872382ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.875917Z","caller":"traceutil/trace.go:171","msg":"trace[89320120] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"593.593323ms","start":"2026-08-15T02:42:31.282284Z","end":"2026-08-15T02:42:31.875877Z","steps":["trace[89320120] 'process raft request' (duration: 593.230294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.87605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.272481Z","time spent":"603.485789ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.876085Z","caller":"traceutil/trace.go:171","msg":"trace[598260885] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"602.960974ms","start":"2026-08-15T02:42:31.273048Z","end":"2026-08-15T02:42:31.876009Z","steps":["trace[598260885] 'process raft request' (duration: 602.367788ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.8762Z","caller":"traceutil/trace.go:171","msg":"trace[2046064603] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"591.182248ms","start":"2026-08-15T02:42:31.285004Z","end":"2026-08-15T02:42:31.876186Z","steps":["trace[2046064603] 'process raft request' (duration: 590.611883ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.876221Z","caller":"traceutil/trace.go:171","msg":"trace[2055942249] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"608.752439ms","start":"2026-08-15T02:42:31.267454Z","end":"2026-08-15T02:42:31.876206Z","steps":["trace[2055942249] 'process raft request' (duration: 607.729162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.876286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.284996Z","time spent":"591.267752ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:42:31.876293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.267437Z","time spent":"608.828562ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:42:31.876308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.27304Z","time spent":"603.15632ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.876359Z","caller":"traceutil/trace.go:171","msg":"trace[14969084] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"606.694534ms","start":"2026-08-15T02:42:31.269611Z","end":"2026-08-15T02:42:31.876306Z","steps":["trace[14969084] 'process raft request' (duration: 605.638366ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.87639Z","caller":"traceutil/trace.go:171","msg":"trace[505873444] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"604.238869ms","start":"2026-08-15T02:42:31.272139Z","end":"2026-08-15T02:42:31.876377Z","steps":["trace[505873444] 'process raft request' (duration: 603.16244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.876129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.282264Z","time spent":"593.757957ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:42:31.876443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.272121Z","time spent":"604.29319ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:42:31.876467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.269604Z","time spent":"606.800557ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.876577Z","caller":"traceutil/trace.go:171","msg":"trace[1698520243] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"565.003817ms","start":"2026-08-15T02:42:31.311561Z","end":"2026-08-15T02:42:31.876564Z","steps":["trace[1698520243] 'process raft request' (duration: 564.143124ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.875802Z","caller":"traceutil/trace.go:171","msg":"trace[1858669997] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"618.891942ms","start":"2026-08-15T02:42:31.25683Z","end":"2026-08-15T02:42:31.875722Z","steps":["trace[1858669997] 'process raft request' (duration: 613.814915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.876712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.311545Z","time spent":"565.064429ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:42:31.876815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.256795Z","time spent":"619.852546ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.87711Z","caller":"traceutil/trace.go:171","msg":"trace[382750002] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:543; }","duration":"616.766585ms","start":"2026-08-15T02:42:31.260323Z","end":"2026-08-15T02:42:31.877089Z","steps":["trace[382750002] 'read index received' (duration: 380.454544ms)","trace[382750002] 'applied index is now lower than readState.Index' (duration: 236.310711ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:31.877152Z","caller":"traceutil/trace.go:171","msg":"trace[1017960378] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"423.080985ms","start":"2026-08-15T02:42:31.454045Z","end":"2026-08-15T02:42:31.877126Z","steps":["trace[1017960378] 'process raft request' (duration: 422.87926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.87728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.940709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:42:31.877276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.45401Z","time spent":"423.223179ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:42:31.877351Z","caller":"traceutil/trace.go:171","msg":"trace[1820519821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"617.062792ms","start":"2026-08-15T02:42:31.260277Z","end":"2026-08-15T02:42:31.877339Z","steps":["trace[1820519821] 'agreement among raft nodes before linearized reading' (duration: 616.920047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.877384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.260256Z","time spent":"617.121164ms","remote":"127.0.0.1:56470","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-08-15T02:42:31.878271Z","caller":"traceutil/trace.go:171","msg":"trace[1263217742] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"230.110615ms","start":"2026-08-15T02:42:31.648145Z","end":"2026-08-15T02:42:31.878255Z","steps":["trace[1263217742] 'process raft request' (duration: 229.855958ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.878555Z","caller":"traceutil/trace.go:171","msg":"trace[1746319142] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"230.2939ms","start":"2026-08-15T02:42:31.64825Z","end":"2026-08-15T02:42:31.878544Z","steps":["trace[1746319142] 'process raft request' (duration: 229.920079ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.878775Z","caller":"traceutil/trace.go:171","msg":"trace[863433606] transaction","detail":"{read_only:false; number_of_response:1; response_revision:538; }","duration":"230.395713ms","start":"2026-08-15T02:42:31.648353Z","end":"2026-08-15T02:42:31.878749Z","steps":["trace[863433606] 'process raft request' (duration: 229.940172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.878781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.750967ms","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-08-15T02:42:31.878998Z","caller":"traceutil/trace.go:171","msg":"trace[1588444338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"566.127958ms","start":"2026-08-15T02:42:31.312851Z","end":"2026-08-15T02:42:31.878978Z","steps":["trace[1588444338] 'agreement among raft nodes before linearized reading' (duration: 565.505361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:31.87913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:31.312823Z","time spent":"566.25545ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-15T02:42:37.094684Z","caller":"traceutil/trace.go:171","msg":"trace[1879341108] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"163.221993ms","start":"2026-08-15T02:42:36.931432Z","end":"2026-08-15T02:42:37.094654Z","steps":["trace[1879341108] 'process raft request' (duration: 163.064199ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:38.344747Z","caller":"traceutil/trace.go:171","msg":"trace[1349120815] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"128.164104ms","start":"2026-08-15T02:42:38.216546Z","end":"2026-08-15T02:42:38.34471Z","steps":["trace[1349120815] 'read index received' (duration: 126.785318ms)","trace[1349120815] 'applied index is now lower than readState.Index' (duration: 1.377396ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:38.344937Z","caller":"traceutil/trace.go:171","msg":"trace[702513141] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"208.27463ms","start":"2026-08-15T02:42:38.136648Z","end":"2026-08-15T02:42:38.344922Z","steps":["trace[702513141] 'process raft request' (duration: 207.117659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:38.347489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.920328ms","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-08-15T02:42:38.3476Z","caller":"traceutil/trace.go:171","msg":"trace[102797345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"131.089132ms","start":"2026-08-15T02:42:38.216489Z","end":"2026-08-15T02:42:38.347578Z","steps":["trace[102797345] 'agreement among raft nodes before linearized reading' (duration: 129.096749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:38.729692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.356871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:42:38.72982Z","caller":"traceutil/trace.go:171","msg":"trace[1866297945] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"373.466546ms","start":"2026-08-15T02:42:38.356333Z","end":"2026-08-15T02:42:38.7298Z","steps":["trace[1866297945] 'process raft request' (duration: 145.643752ms)","trace[1866297945] 'compare' (duration: 227.062223ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:38.730127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:38.356316Z","time spent":"373.720213ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:42:38.751477Z","caller":"traceutil/trace.go:171","msg":"trace[1767848868] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"344.446909ms","start":"2026-08-15T02:42:38.407002Z","end":"2026-08-15T02:42:38.751449Z","steps":["trace[1767848868] 'process raft request' (duration: 344.396278ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:38.751515Z","caller":"traceutil/trace.go:171","msg":"trace[1909963759] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"392.056363ms","start":"2026-08-15T02:42:38.359415Z","end":"2026-08-15T02:42:38.751471Z","steps":["trace[1909963759] 'process raft request' (duration: 391.840237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:38.751619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:38.406973Z","time spent":"344.579511ms","remote":"127.0.0.1:57028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:42:38.751728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:38.359384Z","time spent":"392.222978ms","remote":"127.0.0.1:56772","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-08-15T02:42:40.042053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.864938ms","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-08-15T02:42:40.04214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.894749ms","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-08-15T02:42:40.0422Z","caller":"traceutil/trace.go:171","msg":"trace[2006341615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"943.98788ms","start":"2026-08-15T02:42:39.098191Z","end":"2026-08-15T02:42:40.042179Z","steps":["trace[2006341615] 'range keys from in-memory index tree' (duration: 943.682053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:40.042267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:39.098172Z","time spent":"944.077544ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-15T02:42:40.042688Z","caller":"traceutil/trace.go:171","msg":"trace[455316575] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:636; }","duration":"336.028342ms","start":"2026-08-15T02:42:39.706105Z","end":"2026-08-15T02:42:40.042133Z","steps":["trace[455316575] 'range keys from in-memory index tree' (duration: 335.849777ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:40.044329Z","caller":"traceutil/trace.go:171","msg":"trace[1687087522] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"856.157508ms","start":"2026-08-15T02:42:39.188146Z","end":"2026-08-15T02:42:40.044304Z","steps":["trace[1687087522] 'process raft request' (duration: 848.643757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:40.04456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:39.188121Z","time spent":"856.329472ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:42:40.044946Z","caller":"traceutil/trace.go:171","msg":"trace[1885851660] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"109.987146ms","start":"2026-08-15T02:42:39.934937Z","end":"2026-08-15T02:42:40.044924Z","steps":["trace[1885851660] 'read index received' (duration: 101.692094ms)","trace[1885851660] 'applied index is now lower than readState.Index' (duration: 8.294232ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:40.045151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.202603ms","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-08-15T02:42:40.04518Z","caller":"traceutil/trace.go:171","msg":"trace[571219960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"110.290065ms","start":"2026-08-15T02:42:39.934882Z","end":"2026-08-15T02:42:40.045173Z","steps":["trace[571219960] 'agreement among raft nodes before linearized reading' (duration: 110.123741ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:40.045229Z","caller":"traceutil/trace.go:171","msg":"trace[1341380471] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"523.877035ms","start":"2026-08-15T02:42:39.521323Z","end":"2026-08-15T02:42:40.0452Z","steps":["trace[1341380471] 'process raft request' (duration: 522.829638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:40.045395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:39.521292Z","time spent":"524.015498ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:42:41.502609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.542298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-15T02:42:41.502701Z","caller":"traceutil/trace.go:171","msg":"trace[230661357] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:659; }","duration":"177.65688ms","start":"2026-08-15T02:42:41.325023Z","end":"2026-08-15T02:42:41.50268Z","steps":["trace[230661357] 'range keys from in-memory index tree' (duration: 177.402452ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:54.503479Z","caller":"traceutil/trace.go:171","msg":"trace[687810856] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"383.483766ms","start":"2026-08-15T02:42:54.119962Z","end":"2026-08-15T02:42:54.503446Z","steps":["trace[687810856] 'process raft request' (duration: 383.288792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:54.503738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:54.119933Z","time spent":"383.664342ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:42:54.510368Z","caller":"traceutil/trace.go:171","msg":"trace[2133660710] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"339.188273ms","start":"2026-08-15T02:42:54.171148Z","end":"2026-08-15T02:42:54.510336Z","steps":["trace[2133660710] 'read index received' (duration: 332.041989ms)","trace[2133660710] 'applied index is now lower than readState.Index' (duration: 7.145124ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:54.510512Z","caller":"traceutil/trace.go:171","msg":"trace[1262784392] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"389.844069ms","start":"2026-08-15T02:42:54.120615Z","end":"2026-08-15T02:42:54.510459Z","steps":["trace[1262784392] 'process raft request' (duration: 389.589682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:54.510617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.456401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k979f\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-15T02:42:54.510679Z","caller":"traceutil/trace.go:171","msg":"trace[944188009] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k979f; range_end:; response_count:1; response_revision:795; }","duration":"339.569754ms","start":"2026-08-15T02:42:54.171081Z","end":"2026-08-15T02:42:54.51065Z","steps":["trace[944188009] 'agreement among raft nodes before linearized reading' (duration: 339.374698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:54.510724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:54.171055Z","time spent":"339.658367ms","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k979f\" "} {"level":"warn","ts":"2026-08-15T02:42:54.510814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:54.120586Z","time spent":"390.048285ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:43:18.126237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.498349ms","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-08-15T02:43:18.12632Z","caller":"traceutil/trace.go:171","msg":"trace[1218864898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"400.653603ms","start":"2026-08-15T02:43:17.725648Z","end":"2026-08-15T02:43:18.126301Z","steps":["trace[1218864898] 'range keys from in-memory index tree' (duration: 399.869872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:18.126357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:43:17.725627Z","time spent":"400.723045ms","remote":"127.0.0.1:56772","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":"info","ts":"2026-08-15T02:43:18.301058Z","caller":"traceutil/trace.go:171","msg":"trace[651357989] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"170.681116ms","start":"2026-08-15T02:43:18.130339Z","end":"2026-08-15T02:43:18.301021Z","steps":["trace[651357989] 'process raft request' (duration: 170.231884ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:43:19.375061Z","caller":"traceutil/trace.go:171","msg":"trace[605209826] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"414.257392ms","start":"2026-08-15T02:43:18.960771Z","end":"2026-08-15T02:43:19.375029Z","steps":["trace[605209826] 'read index received' (duration: 413.909683ms)","trace[605209826] 'applied index is now lower than readState.Index' (duration: 346.539µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:43:19.375365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.56345ms","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-08-15T02:43:19.375411Z","caller":"traceutil/trace.go:171","msg":"trace[942165353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"414.684355ms","start":"2026-08-15T02:43:18.960714Z","end":"2026-08-15T02:43:19.375399Z","steps":["trace[942165353] 'agreement among raft nodes before linearized reading' (duration: 414.443938ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:43:19.375394Z","caller":"traceutil/trace.go:171","msg":"trace[2134163922] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"432.140588ms","start":"2026-08-15T02:43:18.943207Z","end":"2026-08-15T02:43:19.375347Z","steps":["trace[2134163922] 'process raft request' (duration: 431.638305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:19.375459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:43:18.96069Z","time spent":"414.760086ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-15T02:43:19.375654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:43:18.943167Z","time spent":"432.336995ms","remote":"127.0.0.1:56772","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-08-15T02:43:43.720823Z","caller":"traceutil/trace.go:171","msg":"trace[2048313816] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"148.118734ms","start":"2026-08-15T02:43:43.572681Z","end":"2026-08-15T02:43:43.720799Z","steps":["trace[2048313816] 'read index received' (duration: 147.98975ms)","trace[2048313816] 'applied index is now lower than readState.Index' (duration: 127.704µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:43:43.720916Z","caller":"traceutil/trace.go:171","msg":"trace[1901853392] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"154.725064ms","start":"2026-08-15T02:43:43.566137Z","end":"2026-08-15T02:43:43.720862Z","steps":["trace[1901853392] 'process raft request' (duration: 154.525068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:43.721065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.36644ms","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-08-15T02:43:43.721115Z","caller":"traceutil/trace.go:171","msg":"trace[1644985006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"148.483114ms","start":"2026-08-15T02:43:43.572618Z","end":"2026-08-15T02:43:43.721101Z","steps":["trace[1644985006] 'agreement among raft nodes before linearized reading' (duration: 148.317119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:43.721263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.294131ms","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-08-15T02:43:43.721386Z","caller":"traceutil/trace.go:171","msg":"trace[676366153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"140.452576ms","start":"2026-08-15T02:43:43.580907Z","end":"2026-08-15T02:43:43.721359Z","steps":["trace[676366153] 'agreement among raft nodes before linearized reading' (duration: 140.144037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:43.997886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.459464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:43:43.998099Z","caller":"traceutil/trace.go:171","msg":"trace[1516241488] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"271.869785ms","start":"2026-08-15T02:43:43.726153Z","end":"2026-08-15T02:43:43.998023Z","steps":["trace[1516241488] 'process raft request' (duration: 81.142984ms)","trace[1516241488] 'compare' (duration: 189.810447ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:43:44.134858Z","caller":"traceutil/trace.go:171","msg":"trace[1671247919] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"408.656192ms","start":"2026-08-15T02:43:43.726164Z","end":"2026-08-15T02:43:44.13482Z","steps":["trace[1671247919] 'process raft request' (duration: 405.177246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:44.135069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:43:43.726124Z","time spent":"408.830655ms","remote":"127.0.0.1:56772","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-08-15T02:43:44.954463Z","caller":"traceutil/trace.go:171","msg":"trace[2117608249] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"121.837779ms","start":"2026-08-15T02:43:44.832592Z","end":"2026-08-15T02:43:44.95443Z","steps":["trace[2117608249] 'read index received' (duration: 121.558751ms)","trace[2117608249] 'applied index is now lower than readState.Index' (duration: 277.998µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:43:44.95458Z","caller":"traceutil/trace.go:171","msg":"trace[2020647003] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"123.431853ms","start":"2026-08-15T02:43:44.831112Z","end":"2026-08-15T02:43:44.954544Z","steps":["trace[2020647003] 'process raft request' (duration: 123.117404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:43:44.954838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.211999ms","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-08-15T02:43:44.954968Z","caller":"traceutil/trace.go:171","msg":"trace[33814802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"122.383975ms","start":"2026-08-15T02:43:44.832554Z","end":"2026-08-15T02:43:44.954938Z","steps":["trace[33814802] 'agreement among raft nodes before linearized reading' (duration: 122.034495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:08.017573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.271997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:08.017688Z","caller":"traceutil/trace.go:171","msg":"trace[1822792719] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1193; }","duration":"394.474562ms","start":"2026-08-15T02:44:07.623191Z","end":"2026-08-15T02:44:08.017666Z","steps":["trace[1822792719] 'count revisions from in-memory index tree' (duration: 394.183775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:08.017745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:07.623169Z","time spent":"394.562556ms","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:08.018128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.066116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:08.018247Z","caller":"traceutil/trace.go:171","msg":"trace[498047675] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"196.296569ms","start":"2026-08-15T02:44:07.821929Z","end":"2026-08-15T02:44:08.018226Z","steps":["trace[498047675] 'process raft request' (duration: 63.022307ms)","trace[498047675] 'compare' (duration: 132.874851ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:08.256158Z","caller":"traceutil/trace.go:171","msg":"trace[2125251585] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"102.790328ms","start":"2026-08-15T02:44:08.153337Z","end":"2026-08-15T02:44:08.256127Z","steps":["trace[2125251585] 'read index received' (duration: 95.958092ms)","trace[2125251585] 'applied index is now lower than readState.Index' (duration: 6.830986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:08.256439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.075485ms","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-08-15T02:44:08.256487Z","caller":"traceutil/trace.go:171","msg":"trace[1197673884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"103.185369ms","start":"2026-08-15T02:44:08.153285Z","end":"2026-08-15T02:44:08.25647Z","steps":["trace[1197673884] 'agreement among raft nodes before linearized reading' (duration: 102.978703ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:08.256762Z","caller":"traceutil/trace.go:171","msg":"trace[744628413] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"197.318879ms","start":"2026-08-15T02:44:08.059402Z","end":"2026-08-15T02:44:08.256721Z","steps":["trace[744628413] 'process raft request' (duration: 189.825774ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:15.280716Z","caller":"traceutil/trace.go:171","msg":"trace[932949416] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"100.80214ms","start":"2026-08-15T02:44:15.179875Z","end":"2026-08-15T02:44:15.280677Z","steps":["trace[932949416] 'process raft request' (duration: 84.574285ms)","trace[932949416] 'compare' (duration: 16.05232ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:16.806883Z","caller":"traceutil/trace.go:171","msg":"trace[2004640088] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"125.992213ms","start":"2026-08-15T02:44:16.680864Z","end":"2026-08-15T02:44:16.806856Z","steps":["trace[2004640088] 'process raft request' (duration: 125.795868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:17.365148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.016839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:17.365262Z","caller":"traceutil/trace.go:171","msg":"trace[393910115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"107.185804ms","start":"2026-08-15T02:44:17.258046Z","end":"2026-08-15T02:44:17.365232Z","steps":["trace[393910115] 'range keys from in-memory index tree' (duration: 106.919977ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:17.49696Z","caller":"traceutil/trace.go:171","msg":"trace[1602155945] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"127.308061ms","start":"2026-08-15T02:44:17.369607Z","end":"2026-08-15T02:44:17.496915Z","steps":["trace[1602155945] 'process raft request' (duration: 98.507904ms)","trace[1602155945] 'compare' (duration: 28.484557ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:18.611486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.879015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:18.611613Z","caller":"traceutil/trace.go:171","msg":"trace[1541590959] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"231.8584ms","start":"2026-08-15T02:44:18.379738Z","end":"2026-08-15T02:44:18.611596Z","steps":["trace[1541590959] 'process raft request' (duration: 63.780149ms)","trace[1541590959] 'compare' (duration: 167.754531ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:18.65407Z","caller":"traceutil/trace.go:171","msg":"trace[42086240] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"273.629867ms","start":"2026-08-15T02:44:18.380419Z","end":"2026-08-15T02:44:18.654049Z","steps":["trace[42086240] 'process raft request' (duration: 273.499543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:19.756259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581483,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-15T02:44:20.019122Z","caller":"traceutil/trace.go:171","msg":"trace[918688428] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"763.627529ms","start":"2026-08-15T02:44:19.255468Z","end":"2026-08-15T02:44:20.019095Z","steps":["trace[918688428] 'read index received' (duration: 763.400723ms)","trace[918688428] 'applied index is now lower than readState.Index' (duration: 225.756µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:20.019204Z","caller":"traceutil/trace.go:171","msg":"trace[1032676480] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"777.395824ms","start":"2026-08-15T02:44:19.241769Z","end":"2026-08-15T02:44:20.019165Z","steps":["trace[1032676480] 'process raft request' (duration: 777.169378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:20.01936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.449556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-15T02:44:20.019406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:19.24175Z","time spent":"777.545359ms","remote":"127.0.0.1:56772","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-08-15T02:44:20.019425Z","caller":"traceutil/trace.go:171","msg":"trace[1355815743] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1232; }","duration":"550.58612ms","start":"2026-08-15T02:44:19.468818Z","end":"2026-08-15T02:44:20.019404Z","steps":["trace[1355815743] 'agreement among raft nodes before linearized reading' (duration: 550.453216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:20.019429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.82163ms","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-08-15T02:44:20.019469Z","caller":"traceutil/trace.go:171","msg":"trace[143629225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"517.920533ms","start":"2026-08-15T02:44:19.501538Z","end":"2026-08-15T02:44:20.019458Z","steps":["trace[143629225] 'agreement among raft nodes before linearized reading' (duration: 517.756738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:20.019476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:19.468791Z","time spent":"550.674432ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:20.019501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:19.501513Z","time spent":"517.980765ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-15T02:44:20.019656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.187676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:20.019718Z","caller":"traceutil/trace.go:171","msg":"trace[1933478843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"764.260568ms","start":"2026-08-15T02:44:19.255448Z","end":"2026-08-15T02:44:20.019708Z","steps":["trace[1933478843] 'agreement among raft nodes before linearized reading' (duration: 763.790175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:20.019745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:19.25544Z","time spent":"764.298828ms","remote":"127.0.0.1:56470","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-08-15T02:44:20.759236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:21.129215Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105214885s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-15T02:44:21.522683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.124314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:21.522779Z","caller":"traceutil/trace.go:171","msg":"trace[1086580979] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.498853655s","start":"2026-08-15T02:44:20.02391Z","end":"2026-08-15T02:44:21.522764Z","steps":["trace[1086580979] 'process raft request' (duration: 1.105558416s)","trace[1086580979] 'compare' (duration: 392.841056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:21.522864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.023897Z","time spent":"1.498903676s","remote":"127.0.0.1:56772","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-08-15T02:44:21.585905Z","caller":"traceutil/trace.go:171","msg":"trace[1994843957] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1283; }","duration":"1.326751219s","start":"2026-08-15T02:44:20.259139Z","end":"2026-08-15T02:44:21.58589Z","steps":["trace[1994843957] 'read index received' (duration: 870.423463ms)","trace[1994843957] 'applied index is now lower than readState.Index' (duration: 456.326846ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:21.586154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326997046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:21.5861Z","caller":"traceutil/trace.go:171","msg":"trace[960994188] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.413888647s","start":"2026-08-15T02:44:20.17215Z","end":"2026-08-15T02:44:21.586039Z","steps":["trace[960994188] 'process raft request' (duration: 1.41363602s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:21.58618Z","caller":"traceutil/trace.go:171","msg":"trace[291504833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"1.327084359s","start":"2026-08-15T02:44:20.259088Z","end":"2026-08-15T02:44:21.586172Z","steps":["trace[291504833] 'agreement among raft nodes before linearized reading' (duration: 1.327024557s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.586202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.259063Z","time spent":"1.327134599s","remote":"127.0.0.1:56470","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-08-15T02:44:21.586351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.172118Z","time spent":"1.414094255s","remote":"127.0.0.1:56772","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-08-15T02:44:21.586628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.843549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-15T02:44:21.586645Z","caller":"traceutil/trace.go:171","msg":"trace[481523448] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1234; }","duration":"345.89581ms","start":"2026-08-15T02:44:21.240745Z","end":"2026-08-15T02:44:21.58664Z","steps":["trace[481523448] 'agreement among raft nodes before linearized reading' (duration: 345.794997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.586474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.155912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-15T02:44:21.586798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.891149ms","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-08-15T02:44:21.586755Z","caller":"traceutil/trace.go:171","msg":"trace[2049164723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"970.431859ms","start":"2026-08-15T02:44:20.616272Z","end":"2026-08-15T02:44:21.586704Z","steps":["trace[2049164723] 'agreement among raft nodes before linearized reading' (duration: 970.137432ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:21.586818Z","caller":"traceutil/trace.go:171","msg":"trace[1316545249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"928.95619ms","start":"2026-08-15T02:44:20.657857Z","end":"2026-08-15T02:44:21.586813Z","steps":["trace[1316545249] 'agreement among raft nodes before linearized reading' (duration: 928.900869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.586833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.657829Z","time spent":"929.000503ms","remote":"127.0.0.1:56772","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-08-15T02:44:21.586933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.535028ms","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-08-15T02:44:21.586924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.616253Z","time spent":"970.592036ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:44:21.586952Z","caller":"traceutil/trace.go:171","msg":"trace[388084360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"702.60685ms","start":"2026-08-15T02:44:20.884338Z","end":"2026-08-15T02:44:21.586945Z","steps":["trace[388084360] 'agreement among raft nodes before linearized reading' (duration: 702.336902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.586667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:21.240724Z","time spent":"345.93877ms","remote":"127.0.0.1:57044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":128,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:21.587169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.884306Z","time spent":"702.857657ms","remote":"127.0.0.1:56772","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-08-15T02:44:21.587771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.293891ms","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-08-15T02:44:21.587861Z","caller":"traceutil/trace.go:171","msg":"trace[840805938] 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:1234; }","duration":"951.454036ms","start":"2026-08-15T02:44:20.636385Z","end":"2026-08-15T02:44:21.587839Z","steps":["trace[840805938] 'agreement among raft nodes before linearized reading' (duration: 950.322144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.587909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:20.636359Z","time spent":"951.537849ms","remote":"127.0.0.1:54452","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-08-15T02:44:23.614277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462562083s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:23.614452Z","caller":"traceutil/trace.go:171","msg":"trace[1251859803] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"2.022282396s","start":"2026-08-15T02:44:21.592144Z","end":"2026-08-15T02:44:23.614426Z","steps":["trace[1251859803] 'process raft request' (duration: 559.462814ms)","trace[1251859803] 'compare' (duration: 1.462189363s)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:23.614549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:21.592123Z","time spent":"2.022373969s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:24.118748Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.745964362s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-15T02:44:24.223036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.91004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:24.223535Z","caller":"traceutil/trace.go:171","msg":"trace[1018334911] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1285; }","duration":"2.199342364s","start":"2026-08-15T02:44:22.024171Z","end":"2026-08-15T02:44:24.223513Z","steps":["trace[1018334911] 'read index received' (duration: 127.479495ms)","trace[1018334911] 'applied index is now lower than readState.Index' (duration: 2.071861859s)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:24.224024Z","caller":"traceutil/trace.go:171","msg":"trace[633966999] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"2.233492623s","start":"2026-08-15T02:44:21.990505Z","end":"2026-08-15T02:44:24.223997Z","steps":["trace[633966999] 'process raft request' (duration: 2.232961078s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:24.224053Z","caller":"traceutil/trace.go:171","msg":"trace[1498568180] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"2.631491827s","start":"2026-08-15T02:44:21.592513Z","end":"2026-08-15T02:44:24.224005Z","steps":["trace[1498568180] 'process raft request' (duration: 2.526470165s)","trace[1498568180] 'compare' (duration: 103.315703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:24.224163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:21.990473Z","time spent":"2.233621366s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:24.224244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.200084265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-15T02:44:24.224022Z","caller":"traceutil/trace.go:171","msg":"trace[1607693547] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"2.631171548s","start":"2026-08-15T02:44:21.592819Z","end":"2026-08-15T02:44:24.223991Z","steps":["trace[1607693547] 'process raft request' (duration: 2.630371665s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:24.224293Z","caller":"traceutil/trace.go:171","msg":"trace[1049954649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"2.200170157s","start":"2026-08-15T02:44:22.024109Z","end":"2026-08-15T02:44:24.224279Z","steps":["trace[1049954649] 'agreement among raft nodes before linearized reading' (duration: 2.199982352s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:24.224305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:21.592502Z","time spent":"2.631673832s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:24.224334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:22.024087Z","time spent":"2.200239179s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-15T02:44:24.224469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:21.592796Z","time spent":"2.631538718s","remote":"127.0.0.1:56772","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-08-15T02:44:24.967338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.49311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-15T02:44:24.967725Z","caller":"traceutil/trace.go:171","msg":"trace[1781053148] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"739.322943ms","start":"2026-08-15T02:44:24.22838Z","end":"2026-08-15T02:44:24.967703Z","steps":["trace[1781053148] 'process raft request' (duration: 739.26357ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:24.96783Z","caller":"traceutil/trace.go:171","msg":"trace[1032932846] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"1.373478719s","start":"2026-08-15T02:44:23.594314Z","end":"2026-08-15T02:44:24.967793Z","steps":["trace[1032932846] 'process raft request' (duration: 1.37316595s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:24.96787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:24.228358Z","time spent":"739.424255ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:24.967973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.594289Z","time spent":"1.373607562s","remote":"127.0.0.1:56772","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-08-15T02:44:24.96818Z","caller":"traceutil/trace.go:171","msg":"trace[1080814193] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"744.582064ms","start":"2026-08-15T02:44:24.223585Z","end":"2026-08-15T02:44:24.968167Z","steps":["trace[1080814193] 'read index received' (duration: 362.266789ms)","trace[1080814193] 'applied index is now lower than readState.Index' (duration: 382.314144ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:24.968277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760421297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:24.968323Z","caller":"traceutil/trace.go:171","msg":"trace[757378189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1240; }","duration":"1.760512718s","start":"2026-08-15T02:44:23.207798Z","end":"2026-08-15T02:44:24.968311Z","steps":["trace[757378189] 'agreement among raft nodes before linearized reading' (duration: 1.760418495s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:24.968375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.207779Z","time spent":"1.760582591s","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:24.969429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441451539s","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-08-15T02:44:24.969459Z","caller":"traceutil/trace.go:171","msg":"trace[2096091995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"1.44151527s","start":"2026-08-15T02:44:23.527936Z","end":"2026-08-15T02:44:24.969452Z","steps":["trace[2096091995] 'agreement among raft nodes before linearized reading' (duration: 1.441397047s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:24.969477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.527917Z","time spent":"1.441556901s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-15T02:44:24.969485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117437266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:44:24.969534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711840804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:24.969549Z","caller":"traceutil/trace.go:171","msg":"trace[48971101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"1.711885134s","start":"2026-08-15T02:44:23.257658Z","end":"2026-08-15T02:44:24.969543Z","steps":["trace[48971101] 'agreement among raft nodes before linearized reading' (duration: 1.711859404s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:24.969541Z","caller":"traceutil/trace.go:171","msg":"trace[1089136830] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1240; }","duration":"1.11755727s","start":"2026-08-15T02:44:23.851967Z","end":"2026-08-15T02:44:24.969524Z","steps":["trace[1089136830] 'agreement among raft nodes before linearized reading' (duration: 1.117439436s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:24.969565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.257639Z","time spent":"1.711922185s","remote":"127.0.0.1:56470","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-08-15T02:44:24.969588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.851944Z","time spent":"1.117630242s","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-08-15T02:44:25.787507Z","caller":"traceutil/trace.go:171","msg":"trace[1702280147] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"162.794499ms","start":"2026-08-15T02:44:25.624677Z","end":"2026-08-15T02:44:25.787471Z","steps":["trace[1702280147] 'process raft request' (duration: 162.609443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:26.679688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.04439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:26.679808Z","caller":"traceutil/trace.go:171","msg":"trace[452081739] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"422.713053ms","start":"2026-08-15T02:44:26.257076Z","end":"2026-08-15T02:44:26.679789Z","steps":["trace[452081739] 'read index received' (duration: 31.837533ms)","trace[452081739] 'applied index is now lower than readState.Index' (duration: 390.873919ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:26.679885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.833456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:26.67991Z","caller":"traceutil/trace.go:171","msg":"trace[1541711015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"422.901968ms","start":"2026-08-15T02:44:26.256998Z","end":"2026-08-15T02:44:26.6799Z","steps":["trace[1541711015] 'agreement among raft nodes before linearized reading' (duration: 422.839906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:26.679946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:26.256963Z","time spent":"422.97628ms","remote":"127.0.0.1:56470","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-08-15T02:44:26.68016Z","caller":"traceutil/trace.go:171","msg":"trace[1325560477] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"447.190074ms","start":"2026-08-15T02:44:26.232922Z","end":"2026-08-15T02:44:26.680112Z","steps":["trace[1325560477] 'process raft request' (duration: 150.553567ms)","trace[1325560477] 'compare' (duration: 295.789682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:26.680363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:26.232884Z","time spent":"447.38071ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:27.150947Z","caller":"traceutil/trace.go:171","msg":"trace[1998558490] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"136.411082ms","start":"2026-08-15T02:44:27.0145Z","end":"2026-08-15T02:44:27.150911Z","steps":["trace[1998558490] 'read index received' (duration: 41.803568ms)","trace[1998558490] 'applied index is now lower than readState.Index' (duration: 94.606244ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:27.151169Z","caller":"traceutil/trace.go:171","msg":"trace[704443582] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"172.181128ms","start":"2026-08-15T02:44:26.97894Z","end":"2026-08-15T02:44:27.151122Z","steps":["trace[704443582] 'process raft request' (duration: 153.713088ms)","trace[704443582] 'compare' (duration: 18.084059ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:27.151224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.708771ms","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-08-15T02:44:27.151327Z","caller":"traceutil/trace.go:171","msg":"trace[405217841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"136.878766ms","start":"2026-08-15T02:44:27.014432Z","end":"2026-08-15T02:44:27.151311Z","steps":["trace[405217841] 'agreement among raft nodes before linearized reading' (duration: 136.604868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.190148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.660444ms","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-08-15T02:44:28.189966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.459115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:28.190326Z","caller":"traceutil/trace.go:171","msg":"trace[1886733733] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1247; }","duration":"520.876748ms","start":"2026-08-15T02:44:27.669409Z","end":"2026-08-15T02:44:28.190285Z","steps":["trace[1886733733] 'count revisions from in-memory index tree' (duration: 520.353913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.190489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:27.669386Z","time spent":"521.054432ms","remote":"127.0.0.1:56560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:28.190489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.154003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:44:28.190506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.01613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:44:28.190238Z","caller":"traceutil/trace.go:171","msg":"trace[1664751571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"397.782757ms","start":"2026-08-15T02:44:27.792438Z","end":"2026-08-15T02:44:28.190221Z","steps":["trace[1664751571] 'range keys from in-memory index tree' (duration: 397.389407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.19061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:27.792352Z","time spent":"398.250362ms","remote":"127.0.0.1:56772","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":"info","ts":"2026-08-15T02:44:28.1906Z","caller":"traceutil/trace.go:171","msg":"trace[734284282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"932.305426ms","start":"2026-08-15T02:44:27.258255Z","end":"2026-08-15T02:44:28.190561Z","steps":["trace[734284282] 'range keys from in-memory index tree' (duration: 932.058019ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:28.190599Z","caller":"traceutil/trace.go:171","msg":"trace[2096447574] range","detail":"{range_begin:/registry/masterleases/199.19.213.221; range_end:; response_count:1; response_revision:1247; }","duration":"841.267266ms","start":"2026-08-15T02:44:27.34931Z","end":"2026-08-15T02:44:28.190577Z","steps":["trace[2096447574] 'range keys from in-memory index tree' (duration: 840.70729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.190593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.279243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-15T02:44:28.190651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:27.258233Z","time spent":"932.40772ms","remote":"127.0.0.1:56470","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-08-15T02:44:28.19066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:27.349287Z","time spent":"841.362478ms","remote":"127.0.0.1:56506","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.221\" "} {"level":"info","ts":"2026-08-15T02:44:28.190703Z","caller":"traceutil/trace.go:171","msg":"trace[693311863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1247; }","duration":"564.410505ms","start":"2026-08-15T02:44:27.626241Z","end":"2026-08-15T02:44:28.190652Z","steps":["trace[693311863] 'count revisions from in-memory index tree' (duration: 564.176359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.190763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:27.626217Z","time spent":"564.527279ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:28.69327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:29.194016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:29.695364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:30.193633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000402398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:30.193791Z","caller":"traceutil/trace.go:171","msg":"trace[2112556942] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000617295s","start":"2026-08-15T02:44:28.193143Z","end":"2026-08-15T02:44:30.19376Z","steps":["trace[2112556942] 'agreement among raft nodes before linearized reading' (duration: 2.000433939s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:30.193873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.19312Z","time spent":"2.000735968s","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/15 02:44:30 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:30.195976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:30.696356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:31.19701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:31.697808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:32.19864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:32.203744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000513621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-15T02:44:32.203853Z","caller":"traceutil/trace.go:171","msg":"trace[547120106] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000699967s","start":"2026-08-15T02:44:30.203129Z","end":"2026-08-15T02:44:32.203829Z","steps":["trace[547120106] 'agreement among raft nodes before linearized reading' (duration: 2.000552882s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:32.20393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:30.203093Z","time spent":"2.00081093s","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:44:32.69922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:32.960404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.986856105s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:44:32.96109Z","caller":"traceutil/trace.go:171","msg":"trace[2129221150] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"4.987659078s","start":"2026-08-15T02:44:27.9734Z","end":"2026-08-15T02:44:32.961059Z","steps":["trace[2129221150] 'process raft request' (duration: 4.987483963s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:32.961268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:27.973376Z","time spent":"4.987805982s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:44:33.193945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.195801Z","time spent":"4.998136918s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-15T02:44:33.293242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998876749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:33.293351Z","caller":"traceutil/trace.go:171","msg":"trace[1027278480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999079355s","start":"2026-08-15T02:44:28.294245Z","end":"2026-08-15T02:44:33.293325Z","steps":["trace[1027278480] 'agreement among raft nodes before linearized reading' (duration: 4.998927791s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:33.293407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.294219Z","time spent":"4.999176508s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/15 02:44:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:33.545966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999944175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:33.546071Z","caller":"traceutil/trace.go:171","msg":"trace[287851264] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00012771s","start":"2026-08-15T02:44:31.54592Z","end":"2026-08-15T02:44:33.546047Z","steps":["trace[287851264] 'agreement among raft nodes before linearized reading' (duration: 1.999989646s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:33.546123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:31.545891Z","time spent":"2.000221583s","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/15 02:44:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:34.060702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.062272Z","time spent":"4.998419646s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/15 02:44:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:34.15835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999279641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:34.158452Z","caller":"traceutil/trace.go:171","msg":"trace[306960017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999455386s","start":"2026-08-15T02:44:29.158974Z","end":"2026-08-15T02:44:34.15843Z","steps":["trace[306960017] 'agreement among raft nodes before linearized reading' (duration: 4.999325962s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:34.158498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.158947Z","time spent":"4.99954288s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/15 02:44:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:34.203866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999886213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-15T02:44:34.203982Z","caller":"traceutil/trace.go:171","msg":"trace[1378343961] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000067108s","start":"2026-08-15T02:44:32.203891Z","end":"2026-08-15T02:44:34.203958Z","steps":["trace[1378343961] 'agreement among raft nodes before linearized reading' (duration: 1.999914754s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:34.204053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:32.203869Z","time spent":"2.000173482s","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:44:34.483219Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.522503513s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:44:34.484672Z","caller":"traceutil/trace.go:171","msg":"trace[1334224729] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"5.768308026s","start":"2026-08-15T02:44:28.716323Z","end":"2026-08-15T02:44:34.484631Z","steps":["trace[1334224729] 'process raft request' (duration: 5.767912624s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:34.48493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.716293Z","time spent":"5.768489451s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:34.485119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.193086Z","time spent":"6.292021274s","remote":"127.0.0.1:56506","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-15T02:44:34.485487Z","caller":"traceutil/trace.go:171","msg":"trace[905128854] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"1.949757795s","start":"2026-08-15T02:44:32.535463Z","end":"2026-08-15T02:44:34.485221Z","steps":["trace[905128854] 'process raft request' (duration: 1.948957563s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:34.485575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:32.535439Z","time spent":"1.950100795s","remote":"127.0.0.1:56650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5963,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:35.500616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01607266s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-15T02:44:35.500871Z","caller":"traceutil/trace.go:171","msg":"trace[1104637991] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1300; }","duration":"7.307660381s","start":"2026-08-15T02:44:28.193188Z","end":"2026-08-15T02:44:35.500849Z","steps":["trace[1104637991] 'read index received' (duration: 4.768537223s)","trace[1104637991] 'applied index is now lower than readState.Index' (duration: 2.539122058s)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:35.501464Z","caller":"traceutil/trace.go:171","msg":"trace[881959036] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"2.53325163s","start":"2026-08-15T02:44:32.968182Z","end":"2026-08-15T02:44:35.501434Z","steps":["trace[881959036] 'process raft request' (duration: 2.53255624s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:35.501612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:32.968152Z","time spent":"2.533383392s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:44:36.207226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.687078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-08-15T02:44:36.207488Z","caller":"traceutil/trace.go:171","msg":"trace[424637416] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1306; }","duration":"706.563032ms","start":"2026-08-15T02:44:35.500909Z","end":"2026-08-15T02:44:36.207472Z","steps":["trace[424637416] 'read index received' (duration: 331.59µs)","trace[424637416] 'applied index is now lower than readState.Index' (duration: 706.230442ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:36.207603Z","caller":"traceutil/trace.go:171","msg":"trace[1530256101] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1254; }","duration":"1.718763751s","start":"2026-08-15T02:44:34.488786Z","end":"2026-08-15T02:44:36.20755Z","steps":["trace[1530256101] 'process raft request' (duration: 1.0126335s)","trace[1530256101] 'compare' (duration: 705.630756ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:36.207676Z","caller":"traceutil/trace.go:171","msg":"trace[2132575888] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"1.680670269s","start":"2026-08-15T02:44:34.526995Z","end":"2026-08-15T02:44:36.207666Z","steps":["trace[2132575888] 'process raft request' (duration: 1.68033259s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.207731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:34.52697Z","time spent":"1.680732831s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:36.207817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:34.488757Z","time spent":"1.718944227s","remote":"127.0.0.1:56506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":41,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:36.207994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.446356ms","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-08-15T02:44:36.208018Z","caller":"traceutil/trace.go:171","msg":"trace[1012841942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"912.503069ms","start":"2026-08-15T02:44:35.295506Z","end":"2026-08-15T02:44:36.20801Z","steps":["trace[1012841942] 'agreement among raft nodes before linearized reading' (duration: 912.391016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.20804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:35.295487Z","time spent":"912.54849ms","remote":"127.0.0.1:56772","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-08-15T02:44:36.208179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.523704427s","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-08-15T02:44:36.208198Z","caller":"traceutil/trace.go:171","msg":"trace[907626734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"7.52383429s","start":"2026-08-15T02:44:28.684358Z","end":"2026-08-15T02:44:36.208192Z","steps":["trace[907626734] 'agreement among raft nodes before linearized reading' (duration: 7.523766948s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.208226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.68433Z","time spent":"7.523891533s","remote":"127.0.0.1:56772","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-08-15T02:44:36.208235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.604927056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:36.208283Z","caller":"traceutil/trace.go:171","msg":"trace[374820020] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1255; }","duration":"7.604974798s","start":"2026-08-15T02:44:28.603293Z","end":"2026-08-15T02:44:36.208267Z","steps":["trace[374820020] 'agreement among raft nodes before linearized reading' (duration: 7.604891215s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.208329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:28.603224Z","time spent":"7.605095921s","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:36.20831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.995077677s","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-08-15T02:44:36.209173Z","caller":"traceutil/trace.go:171","msg":"trace[737210152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"6.995962732s","start":"2026-08-15T02:44:29.213155Z","end":"2026-08-15T02:44:36.209118Z","steps":["trace[737210152] 'agreement among raft nodes before linearized reading' (duration: 6.994946953s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.208464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.145928422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-08-15T02:44:36.209369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.213132Z","time spent":"6.996164888s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-15T02:44:36.209418Z","caller":"traceutil/trace.go:171","msg":"trace[1150740826] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1255; }","duration":"2.14690407s","start":"2026-08-15T02:44:34.062479Z","end":"2026-08-15T02:44:36.209384Z","steps":["trace[1150740826] 'agreement among raft nodes before linearized reading' (duration: 2.145891141s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.209623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:34.062458Z","time spent":"2.147114815s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-08-15T02:44:36.208531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997939418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:36.20981Z","caller":"traceutil/trace.go:171","msg":"trace[4819424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"1.999241145s","start":"2026-08-15T02:44:34.210541Z","end":"2026-08-15T02:44:36.209782Z","steps":["trace[4819424] 'agreement among raft nodes before linearized reading' (duration: 1.997967119s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.208549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011355134s","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-08-15T02:44:36.209914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:34.210514Z","time spent":"1.999383089s","remote":"127.0.0.1:56470","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-08-15T02:44:36.209963Z","caller":"traceutil/trace.go:171","msg":"trace[1825029973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"1.012828256s","start":"2026-08-15T02:44:35.197119Z","end":"2026-08-15T02:44:36.209948Z","steps":["trace[1825029973] 'agreement among raft nodes before linearized reading' (duration: 1.011325312s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.210589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:35.197095Z","time spent":"1.013323361s","remote":"127.0.0.1:56772","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-08-15T02:44:36.208735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.953314193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:44:36.208602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.750000162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:36.211168Z","caller":"traceutil/trace.go:171","msg":"trace[1393110078] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1255; }","duration":"4.752605738s","start":"2026-08-15T02:44:31.458545Z","end":"2026-08-15T02:44:36.21115Z","steps":["trace[1393110078] 'agreement among raft nodes before linearized reading' (duration: 4.750010102s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:36.211213Z","caller":"traceutil/trace.go:171","msg":"trace[286399284] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1255; }","duration":"4.955773783s","start":"2026-08-15T02:44:31.255405Z","end":"2026-08-15T02:44:36.211179Z","steps":["trace[286399284] 'agreement among raft nodes before linearized reading' (duration: 4.953190709s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:36.211239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:31.458513Z","time spent":"4.752708799s","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:36.21129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:31.25532Z","time spent":"4.955940489s","remote":"127.0.0.1:54588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:36.707735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:37.207976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:37.708337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:37.79151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.215863Z","time spent":"1.575636977s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/15 02:44:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:37.808677Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.301658227s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:44:37.809163Z","caller":"traceutil/trace.go:171","msg":"trace[435107675] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"2.302249715s","start":"2026-08-15T02:44:35.506884Z","end":"2026-08-15T02:44:37.809134Z","steps":["trace[435107675] 'process raft request' (duration: 2.30206944s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:37.809347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:35.506861Z","time spent":"2.302382068s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:44:38.211868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00045517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-15T02:44:38.211961Z","caller":"traceutil/trace.go:171","msg":"trace[98276507] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000621965s","start":"2026-08-15T02:44:36.211319Z","end":"2026-08-15T02:44:38.211941Z","steps":["trace[98276507] 'agreement among raft nodes before linearized reading' (duration: 2.000501522s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:38.212002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.211293Z","time spent":"2.000701806s","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:44:38.292951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.214427Z","time spent":"2.078512838s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/15 02:44:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:38.683525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.215898Z","time spent":"2.467617217s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/15 02:44:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:39.060775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.845536155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:39.060871Z","caller":"traceutil/trace.go:171","msg":"trace[1356591208] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"2.845693029s","start":"2026-08-15T02:44:36.215155Z","end":"2026-08-15T02:44:39.060848Z","steps":["trace[1356591208] 'agreement among raft nodes before linearized reading' (duration: 2.845549845s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:39.060926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.215147Z","time spent":"2.845768162s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/08/15 02:44:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:39.15789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.996265929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:39.157986Z","caller":"traceutil/trace.go:171","msg":"trace[1519136637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.996437162s","start":"2026-08-15T02:44:36.161526Z","end":"2026-08-15T02:44:39.157963Z","steps":["trace[1519136637] 'agreement among raft nodes before linearized reading' (duration: 2.996305509s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:39.158041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.161499Z","time spent":"2.996531165s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/15 02:44:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:39.212405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.215201Z","time spent":"2.997193745s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/15 02:44:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:40.21851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999857262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-15T02:44:40.218626Z","caller":"traceutil/trace.go:171","msg":"trace[621229167] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000051469s","start":"2026-08-15T02:44:38.21855Z","end":"2026-08-15T02:44:40.218602Z","steps":["trace[621229167] 'agreement among raft nodes before linearized reading' (duration: 1.999901854s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:40.21871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:38.218511Z","time spent":"2.000189242s","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:44:40.491264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.682404236s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-15T02:44:42.219889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999928574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-15T02:44:42.219977Z","caller":"traceutil/trace.go:171","msg":"trace[1952735076] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00009618s","start":"2026-08-15T02:44:40.21986Z","end":"2026-08-15T02:44:42.219957Z","steps":["trace[1952735076] 'agreement among raft nodes before linearized reading' (duration: 1.999977266s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:42.220031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:40.219824Z","time spent":"2.000197493s","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/15 02:44:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:42.581447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.874790685s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:42.581583Z","caller":"traceutil/trace.go:171","msg":"trace[372545684] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1256; }","duration":"2.87496819s","start":"2026-08-15T02:44:39.706592Z","end":"2026-08-15T02:44:42.58156Z","steps":["trace[372545684] 'range keys from in-memory index tree' (duration: 2.874765243s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:42.582639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.091166392s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-15T02:44:42.582899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.209051Z","time spent":"6.373837601s","remote":"127.0.0.1:56506","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-15T02:44:42.582978Z","caller":"traceutil/trace.go:171","msg":"trace[1284989045] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1309; }","duration":"6.375438218s","start":"2026-08-15T02:44:36.207521Z","end":"2026-08-15T02:44:42.58296Z","steps":["trace[1284989045] 'read index received' (duration: 1.601378905s)","trace[1284989045] 'applied index is now lower than readState.Index' (duration: 4.774058633s)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:42.583047Z","caller":"traceutil/trace.go:171","msg":"trace[459549996] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1260; }","duration":"4.788098985s","start":"2026-08-15T02:44:37.794939Z","end":"2026-08-15T02:44:42.583038Z","steps":["trace[459549996] 'process raft request' (duration: 4.787994102s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:42.583151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:37.79491Z","time spent":"4.788157297s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":62,"response count":0,"response size":526,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:42.899712Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.408196543s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-15T02:44:43.294243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:38.295205Z","time spent":"4.999020354s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/15 02:44:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:43.515846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000423929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-15T02:44:43.515937Z","caller":"traceutil/trace.go:171","msg":"trace[932865815] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000593964s","start":"2026-08-15T02:44:41.515322Z","end":"2026-08-15T02:44:43.515916Z","steps":["trace[932865815] 'agreement among raft nodes before linearized reading' (duration: 2.000472101s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.51599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:41.515293Z","time spent":"2.000685926s","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/15 02:44:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-15T02:44:43.558488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.400782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:490"} {"level":"info","ts":"2026-08-15T02:44:43.558671Z","caller":"traceutil/trace.go:171","msg":"trace[2020673935] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1260; }","duration":"4.872135885s","start":"2026-08-15T02:44:38.686517Z","end":"2026-08-15T02:44:43.558652Z","steps":["trace[2020673935] 'process raft request' (duration: 4.872057562s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.558721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:38.68649Z","time spent":"4.872206627s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":48,"response count":0,"response size":472,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:43.559514Z","caller":"traceutil/trace.go:171","msg":"trace[1543381401] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"4.495512584s","start":"2026-08-15T02:44:39.063955Z","end":"2026-08-15T02:44:43.559467Z","steps":["trace[1543381401] 'process raft request' (duration: 4.494645669s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:43.559516Z","caller":"traceutil/trace.go:171","msg":"trace[797257455] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1261; }","duration":"4.344217675s","start":"2026-08-15T02:44:39.215257Z","end":"2026-08-15T02:44:43.559475Z","steps":["trace[797257455] 'process raft request' (duration: 4.343560566s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.559668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:39.063924Z","time spent":"4.495676878s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:44:43.559761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:39.215215Z","time spent":"4.344425761s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":46,"response count":0,"response size":467,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:43.559984Z","caller":"traceutil/trace.go:171","msg":"trace[621995339] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"973.323514ms","start":"2026-08-15T02:44:42.586629Z","end":"2026-08-15T02:44:43.559953Z","steps":["trace[621995339] 'process raft request' (duration: 972.287224ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:43.560073Z","caller":"traceutil/trace.go:171","msg":"trace[1451668541] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1316; }","duration":"977.032371ms","start":"2026-08-15T02:44:42.583008Z","end":"2026-08-15T02:44:43.560041Z","steps":["trace[1451668541] 'read index received' (duration: 316.976911ms)","trace[1451668541] 'applied index is now lower than readState.Index' (duration: 660.05146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:43.560142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.706509798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:44:43.560165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:42.586569Z","time spent":"973.498089ms","remote":"127.0.0.1:56506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:43.560201Z","caller":"traceutil/trace.go:171","msg":"trace[97970722] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1262; }","duration":"2.706605511s","start":"2026-08-15T02:44:40.853578Z","end":"2026-08-15T02:44:43.560184Z","steps":["trace[97970722] 'agreement among raft nodes before linearized reading' (duration: 2.706473687s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.560204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.344509672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-08-15T02:44:43.560214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.109862526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-15T02:44:43.560243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:40.853554Z","time spent":"2.706679993s","remote":"127.0.0.1:54546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-15T02:44:43.560265Z","caller":"traceutil/trace.go:171","msg":"trace[424896838] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1262; }","duration":"3.109969269s","start":"2026-08-15T02:44:40.450283Z","end":"2026-08-15T02:44:43.560253Z","steps":["trace[424896838] 'agreement among raft nodes before linearized reading' (duration: 3.109803934s)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:43.560264Z","caller":"traceutil/trace.go:171","msg":"trace[1185580213] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1262; }","duration":"5.344598104s","start":"2026-08-15T02:44:38.215649Z","end":"2026-08-15T02:44:43.560247Z","steps":["trace[1185580213] 'agreement among raft nodes before linearized reading' (duration: 5.34443614s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.560314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:40.450255Z","time spent":"3.110047261s","remote":"127.0.0.1:56816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:43.56032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:38.215634Z","time spent":"5.344675196s","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7135,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-15T02:44:43.56037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.74580841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-15T02:44:43.560412Z","caller":"traceutil/trace.go:171","msg":"trace[1498854689] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1; range_end:; response_count:1; response_revision:1262; }","duration":"5.745941125s","start":"2026-08-15T02:44:37.81446Z","end":"2026-08-15T02:44:43.560402Z","steps":["trace[1498854689] 'agreement among raft nodes before linearized reading' (duration: 5.745740539s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.560443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:37.81444Z","time spent":"5.745994365s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1\" "} {"level":"warn","ts":"2026-08-15T02:44:43.560467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265138752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:43.560506Z","caller":"traceutil/trace.go:171","msg":"trace[1834154118] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1262; }","duration":"1.265212195s","start":"2026-08-15T02:44:42.295284Z","end":"2026-08-15T02:44:43.560496Z","steps":["trace[1834154118] 'agreement among raft nodes before linearized reading' (duration: 1.265139482s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.560535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:42.295266Z","time spent":"1.265261875s","remote":"127.0.0.1:56820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:43.560616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.414447893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:43.560669Z","caller":"traceutil/trace.go:171","msg":"trace[1834773585] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1262; }","duration":"2.414546525s","start":"2026-08-15T02:44:41.14611Z","end":"2026-08-15T02:44:43.560656Z","steps":["trace[1834773585] 'agreement among raft nodes before linearized reading' (duration: 2.414446362s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.56071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:41.146083Z","time spent":"2.414617668s","remote":"127.0.0.1:52278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:43.560741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.322775235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:43.560784Z","caller":"traceutil/trace.go:171","msg":"trace[535258453] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1262; }","duration":"7.322844177s","start":"2026-08-15T02:44:36.237931Z","end":"2026-08-15T02:44:43.560775Z","steps":["trace[535258453] 'agreement among raft nodes before linearized reading' (duration: 7.322777516s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.560814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:36.237919Z","time spent":"7.322887409s","remote":"127.0.0.1:56650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:43.561194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332346229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:43.561237Z","caller":"traceutil/trace.go:171","msg":"trace[1981352928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"1.332431093s","start":"2026-08-15T02:44:42.228795Z","end":"2026-08-15T02:44:43.561226Z","steps":["trace[1981352928] 'agreement among raft nodes before linearized reading' (duration: 1.331841175s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:43.561269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:42.228773Z","time spent":"1.332489774s","remote":"127.0.0.1:56480","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-08-15T02:44:44.05056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.987251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:44.05071Z","caller":"traceutil/trace.go:171","msg":"trace[479188743] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"483.240639ms","start":"2026-08-15T02:44:43.567449Z","end":"2026-08-15T02:44:44.050689Z","steps":["trace[479188743] 'read index received' (duration: 196.081284ms)","trace[479188743] 'applied index is now lower than readState.Index' (duration: 287.157925ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:44.050781Z","caller":"traceutil/trace.go:171","msg":"trace[141020291] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"483.980139ms","start":"2026-08-15T02:44:43.566791Z","end":"2026-08-15T02:44:44.050771Z","steps":["trace[141020291] 'process raft request' (duration: 196.655389ms)","trace[141020291] 'compare' (duration: 286.783885ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:44.050848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:43.566751Z","time spent":"484.069282ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:44.050967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.478136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:44.051083Z","caller":"traceutil/trace.go:171","msg":"trace[1701709672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"483.655091ms","start":"2026-08-15T02:44:43.567399Z","end":"2026-08-15T02:44:44.051054Z","steps":["trace[1701709672] 'agreement among raft nodes before linearized reading' (duration: 483.427125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.051144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:43.567374Z","time spent":"483.754823ms","remote":"127.0.0.1:56470","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-08-15T02:44:44.550851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581556,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:44.697054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.159939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:44.697195Z","caller":"traceutil/trace.go:171","msg":"trace[266741154] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"646.431188ms","start":"2026-08-15T02:44:44.050735Z","end":"2026-08-15T02:44:44.697166Z","steps":["trace[266741154] 'read index received' (duration: 529.017091ms)","trace[266741154] 'applied index is now lower than readState.Index' (duration: 117.412297ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:44.697318Z","caller":"traceutil/trace.go:171","msg":"trace[1738112398] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.126544027s","start":"2026-08-15T02:44:43.570757Z","end":"2026-08-15T02:44:44.697301Z","steps":["trace[1738112398] 'process raft request' (duration: 1.009034367s)","trace[1738112398] 'compare' (duration: 117.032406ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:44.697412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:43.570744Z","time spent":"1.12662222s","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:44.697668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.433149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:44:44.697643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130035617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-15T02:44:44.697706Z","caller":"traceutil/trace.go:171","msg":"trace[915481836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"645.521522ms","start":"2026-08-15T02:44:44.052174Z","end":"2026-08-15T02:44:44.697695Z","steps":["trace[915481836] 'agreement among raft nodes before linearized reading' (duration: 645.457421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.697738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.052152Z","time spent":"645.579375ms","remote":"127.0.0.1:56480","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-08-15T02:44:44.697742Z","caller":"traceutil/trace.go:171","msg":"trace[685184553] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1264; }","duration":"1.130160761s","start":"2026-08-15T02:44:43.567559Z","end":"2026-08-15T02:44:44.697719Z","steps":["trace[685184553] 'agreement among raft nodes before linearized reading' (duration: 1.129965607s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.697795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:43.567551Z","time spent":"1.130234164s","remote":"127.0.0.1:56780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-08-15T02:44:44.697925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.61147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:44.69796Z","caller":"traceutil/trace.go:171","msg":"trace[1294387477] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1264; }","duration":"467.691003ms","start":"2026-08-15T02:44:44.230261Z","end":"2026-08-15T02:44:44.697952Z","steps":["trace[1294387477] 'agreement among raft nodes before linearized reading' (duration: 467.627371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.697994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.230237Z","time spent":"467.745414ms","remote":"127.0.0.1:56780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:44.698053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.75026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:44.698095Z","caller":"traceutil/trace.go:171","msg":"trace[1197984807] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1264; }","duration":"652.795801ms","start":"2026-08-15T02:44:44.045288Z","end":"2026-08-15T02:44:44.698084Z","steps":["trace[1197984807] 'agreement among raft nodes before linearized reading' (duration: 652.729379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.698127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.04521Z","time spent":"652.909384ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:44.698276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.409482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-15T02:44:44.698331Z","caller":"traceutil/trace.go:171","msg":"trace[449220169] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1; range_end:; response_count:1; response_revision:1264; }","duration":"643.499584ms","start":"2026-08-15T02:44:44.054817Z","end":"2026-08-15T02:44:44.698317Z","steps":["trace[449220169] 'agreement among raft nodes before linearized reading' (duration: 643.331629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:44.698362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.054799Z","time spent":"643.556225ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1\" "} {"level":"warn","ts":"2026-08-15T02:44:44.697913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.920928ms","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-08-15T02:44:44.698953Z","caller":"traceutil/trace.go:171","msg":"trace[1725058487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1264; }","duration":"243.037119ms","start":"2026-08-15T02:44:44.455865Z","end":"2026-08-15T02:44:44.698903Z","steps":["trace[1725058487] 'agreement among raft nodes before linearized reading' (duration: 241.788374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:45.203244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:45.704049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:45.747295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.046475082s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:44:45.747737Z","caller":"traceutil/trace.go:171","msg":"trace[354298859] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.047007387s","start":"2026-08-15T02:44:44.700707Z","end":"2026-08-15T02:44:45.747714Z","steps":["trace[354298859] 'process raft request' (duration: 1.046832903s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:45.747889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.700688Z","time spent":"1.04710942s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:46.535461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.773803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:46.535997Z","caller":"traceutil/trace.go:171","msg":"trace[372421098] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"1.7274762s","start":"2026-08-15T02:44:44.808484Z","end":"2026-08-15T02:44:46.53596Z","steps":["trace[372421098] 'process raft request' (duration: 1.1301102s)","trace[372421098] 'compare' (duration: 596.466285ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:46.536156Z","caller":"traceutil/trace.go:171","msg":"trace[658123602] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"1.834016846s","start":"2026-08-15T02:44:44.702108Z","end":"2026-08-15T02:44:46.536125Z","steps":["trace[658123602] 'read index received' (duration: 1.045514953s)","trace[658123602] 'applied index is now lower than readState.Index' (duration: 788.470593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:46.536202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.808457Z","time spent":"1.727623375s","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:46.536517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.834406778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:44:46.536585Z","caller":"traceutil/trace.go:171","msg":"trace[255479019] range","detail":"{range_begin:/registry/masterleases/199.19.213.221; range_end:; response_count:1; response_revision:1267; }","duration":"1.83452055s","start":"2026-08-15T02:44:44.702052Z","end":"2026-08-15T02:44:46.536573Z","steps":["trace[255479019] 'agreement among raft nodes before linearized reading' (duration: 1.834264923s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.536721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.702033Z","time spent":"1.834607083s","remote":"127.0.0.1:56506","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.221\" "} {"level":"info","ts":"2026-08-15T02:44:46.537058Z","caller":"traceutil/trace.go:171","msg":"trace[1919336907] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"182.444922ms","start":"2026-08-15T02:44:46.354588Z","end":"2026-08-15T02:44:46.537033Z","steps":["trace[1919336907] 'process raft request' (duration: 180.991711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.537207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.635327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:46.537313Z","caller":"traceutil/trace.go:171","msg":"trace[252098183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"644.812133ms","start":"2026-08-15T02:44:45.892478Z","end":"2026-08-15T02:44:46.53729Z","steps":["trace[252098183] 'agreement among raft nodes before linearized reading' (duration: 644.620587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.537326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.759384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda7512fc4150\" ","response":"range_response_count:1 size:773"} {"level":"warn","ts":"2026-08-15T02:44:46.537362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:45.892451Z","time spent":"644.900434ms","remote":"127.0.0.1:56470","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-08-15T02:44:46.537389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.972241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:46.537397Z","caller":"traceutil/trace.go:171","msg":"trace[1105546659] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cbda7512fc4150; range_end:; response_count:1; response_revision:1267; }","duration":"785.878529ms","start":"2026-08-15T02:44:45.751506Z","end":"2026-08-15T02:44:46.537385Z","steps":["trace[1105546659] 'agreement among raft nodes before linearized reading' (duration: 785.710394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.537355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833187673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-08-15T02:44:46.53743Z","caller":"traceutil/trace.go:171","msg":"trace[2017936075] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1267; }","duration":"272.069062ms","start":"2026-08-15T02:44:46.265349Z","end":"2026-08-15T02:44:46.537418Z","steps":["trace[2017936075] 'agreement among raft nodes before linearized reading' (duration: 271.9935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.537471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:45.751478Z","time spent":"785.97702ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda7512fc4150\" "} {"level":"info","ts":"2026-08-15T02:44:46.5375Z","caller":"traceutil/trace.go:171","msg":"trace[1801747642] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1267; }","duration":"1.833362077s","start":"2026-08-15T02:44:44.704091Z","end":"2026-08-15T02:44:46.537453Z","steps":["trace[1801747642] 'agreement among raft nodes before linearized reading' (duration: 1.832951125s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.537582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.704085Z","time spent":"1.83345809s","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7327,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-15T02:44:46.537671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.321324ms","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":"warn","ts":"2026-08-15T02:44:46.5377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830436514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:46.537725Z","caller":"traceutil/trace.go:171","msg":"trace[679879280] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1267; }","duration":"495.429148ms","start":"2026-08-15T02:44:46.042281Z","end":"2026-08-15T02:44:46.53771Z","steps":["trace[679879280] 'agreement among raft nodes before linearized reading' (duration: 495.340475ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:46.537732Z","caller":"traceutil/trace.go:171","msg":"trace[1197170641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"1.830478955s","start":"2026-08-15T02:44:44.707244Z","end":"2026-08-15T02:44:46.537723Z","steps":["trace[1197170641] 'agreement among raft nodes before linearized reading' (duration: 1.830429273s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:46.53776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:44.707238Z","time spent":"1.830516236s","remote":"127.0.0.1:56470","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-08-15T02:44:46.537761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:46.042213Z","time spent":"495.538731ms","remote":"127.0.0.1:52286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:47.23246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.893222ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-15T02:44:47.232793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:46.539565Z","time spent":"693.223161ms","remote":"127.0.0.1:56506","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-15T02:44:47.232931Z","caller":"traceutil/trace.go:171","msg":"trace[1831778708] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1326; }","duration":"690.911305ms","start":"2026-08-15T02:44:46.541998Z","end":"2026-08-15T02:44:47.232909Z","steps":["trace[1831778708] 'read index received' (duration: 255.628902ms)","trace[1831778708] 'applied index is now lower than readState.Index' (duration: 435.280513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:47.233251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.235534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-08-15T02:44:47.233293Z","caller":"traceutil/trace.go:171","msg":"trace[398818653] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1268; }","duration":"691.311055ms","start":"2026-08-15T02:44:46.54197Z","end":"2026-08-15T02:44:47.233281Z","steps":["trace[398818653] 'agreement among raft nodes before linearized reading' (duration: 691.043838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:47.233325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:46.541955Z","time spent":"691.362447ms","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7327,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-08-15T02:44:47.233415Z","caller":"traceutil/trace.go:171","msg":"trace[1282872306] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"692.953263ms","start":"2026-08-15T02:44:46.540446Z","end":"2026-08-15T02:44:47.233399Z","steps":["trace[1282872306] 'process raft request' (duration: 692.17091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:47.23353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:46.54043Z","time spent":"693.027325ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:47.748334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.62292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:47.748532Z","caller":"traceutil/trace.go:171","msg":"trace[786657812] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"511.005795ms","start":"2026-08-15T02:44:47.237499Z","end":"2026-08-15T02:44:47.748505Z","steps":["trace[786657812] 'read index received' (duration: 254.057796ms)","trace[786657812] 'applied index is now lower than readState.Index' (duration: 256.946468ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:47.748581Z","caller":"traceutil/trace.go:171","msg":"trace[275493060] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"513.812476ms","start":"2026-08-15T02:44:47.234725Z","end":"2026-08-15T02:44:47.748538Z","steps":["trace[275493060] 'process raft request' (duration: 256.866986ms)","trace[275493060] 'compare' (duration: 256.419964ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:47.748766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:47.234687Z","time spent":"513.97918ms","remote":"127.0.0.1:56506","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-08-15T02:44:47.748793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.286952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-15T02:44:47.748825Z","caller":"traceutil/trace.go:171","msg":"trace[96460464] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1; range_end:; response_count:1; response_revision:1269; }","duration":"511.334524ms","start":"2026-08-15T02:44:47.237479Z","end":"2026-08-15T02:44:47.748814Z","steps":["trace[96460464] 'agreement among raft nodes before linearized reading' (duration: 511.126998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:47.748871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:47.237463Z","time spent":"511.398826ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cbda744b3007e1\" "} {"level":"info","ts":"2026-08-15T02:44:48.217Z","caller":"traceutil/trace.go:171","msg":"trace[926000562] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"468.396463ms","start":"2026-08-15T02:44:47.748569Z","end":"2026-08-15T02:44:48.216966Z","steps":["trace[926000562] 'read index received' (duration: 468.057253ms)","trace[926000562] 'applied index is now lower than readState.Index' (duration: 337.7µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:48.217078Z","caller":"traceutil/trace.go:171","msg":"trace[1036347796] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"975.544106ms","start":"2026-08-15T02:44:47.241493Z","end":"2026-08-15T02:44:48.217038Z","steps":["trace[1036347796] 'process raft request' (duration: 975.201497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:48.217141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.689144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:48.217198Z","caller":"traceutil/trace.go:171","msg":"trace[148085101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"958.833658ms","start":"2026-08-15T02:44:47.258349Z","end":"2026-08-15T02:44:48.217183Z","steps":["trace[148085101] 'agreement among raft nodes before linearized reading' (duration: 958.717285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:48.217247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:47.258329Z","time spent":"958.9091ms","remote":"127.0.0.1:56470","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-08-15T02:44:48.217282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:47.241481Z","time spent":"975.679771ms","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:49.00493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.016505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:49.00505Z","caller":"traceutil/trace.go:171","msg":"trace[1757252591] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"787.939096ms","start":"2026-08-15T02:44:48.21709Z","end":"2026-08-15T02:44:49.00503Z","steps":["trace[1757252591] 'read index received' (duration: 132.609243ms)","trace[1757252591] 'applied index is now lower than readState.Index' (duration: 655.327323ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:49.005084Z","caller":"traceutil/trace.go:171","msg":"trace[637977514] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.253298402s","start":"2026-08-15T02:44:47.751745Z","end":"2026-08-15T02:44:49.005043Z","steps":["trace[637977514] 'process raft request' (duration: 598.03772ms)","trace[637977514] 'compare' (duration: 654.721816ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:49.005273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254218109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-08-15T02:44:49.005294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:47.751729Z","time spent":"1.253426617s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:49.005312Z","caller":"traceutil/trace.go:171","msg":"trace[1274478053] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1271; }","duration":"1.254313241s","start":"2026-08-15T02:44:47.750986Z","end":"2026-08-15T02:44:49.005299Z","steps":["trace[1274478053] 'agreement among raft nodes before linearized reading' (duration: 1.254140766s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:49.00535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:47.750964Z","time spent":"1.254377564s","remote":"127.0.0.1:56644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-15T02:44:49.005598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.570858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:49.00566Z","caller":"traceutil/trace.go:171","msg":"trace[1979276100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"785.676052ms","start":"2026-08-15T02:44:48.219941Z","end":"2026-08-15T02:44:49.005617Z","steps":["trace[1979276100] 'agreement among raft nodes before linearized reading' (duration: 785.628031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:49.005706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:48.219909Z","time spent":"785.787734ms","remote":"127.0.0.1:56480","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-08-15T02:44:50.318638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.884284ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:50.318869Z","caller":"traceutil/trace.go:171","msg":"trace[203271303] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1271; }","duration":"612.046939ms","start":"2026-08-15T02:44:49.706715Z","end":"2026-08-15T02:44:50.318762Z","steps":["trace[203271303] 'range keys from in-memory index tree' (duration: 611.863664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:50.318956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.066958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:50.319003Z","caller":"traceutil/trace.go:171","msg":"trace[1484662114] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"1.303769765s","start":"2026-08-15T02:44:49.015224Z","end":"2026-08-15T02:44:50.318994Z","steps":["trace[1484662114] 'read index received' (duration: 456.595374ms)","trace[1484662114] 'applied index is now lower than readState.Index' (duration: 847.173261ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:50.31901Z","caller":"traceutil/trace.go:171","msg":"trace[1002457349] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.308072368s","start":"2026-08-15T02:44:49.01092Z","end":"2026-08-15T02:44:50.318993Z","steps":["trace[1002457349] 'process raft request' (duration: 460.932659ms)","trace[1002457349] 'compare' (duration: 846.79455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:50.319064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303844026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:50.319079Z","caller":"traceutil/trace.go:171","msg":"trace[1796368148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"1.303880568s","start":"2026-08-15T02:44:49.015194Z","end":"2026-08-15T02:44:50.319074Z","steps":["trace[1796368148] 'agreement among raft nodes before linearized reading' (duration: 1.303825417s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:50.319075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:49.010904Z","time spent":"1.30814128s","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:50.319096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:49.015187Z","time spent":"1.30390507s","remote":"127.0.0.1:56470","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-08-15T02:44:50.820558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960248579181581581,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:44:51.174559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.309457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-15T02:44:51.174847Z","caller":"traceutil/trace.go:171","msg":"trace[1767989395] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"854.728485ms","start":"2026-08-15T02:44:50.320098Z","end":"2026-08-15T02:44:51.174827Z","steps":["trace[1767989395] 'read index received' (duration: 549.239133ms)","trace[1767989395] 'applied index is now lower than readState.Index' (duration: 305.488392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:51.174943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.826627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:51.174913Z","caller":"traceutil/trace.go:171","msg":"trace[1950847665] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"852.032663ms","start":"2026-08-15T02:44:50.322822Z","end":"2026-08-15T02:44:51.174854Z","steps":["trace[1950847665] 'process raft request' (duration: 851.864188ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:51.17497Z","caller":"traceutil/trace.go:171","msg":"trace[1894998166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"854.894268ms","start":"2026-08-15T02:44:50.320066Z","end":"2026-08-15T02:44:51.17496Z","steps":["trace[1894998166] 'agreement among raft nodes before linearized reading' (duration: 854.817156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:51.175009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:50.320051Z","time spent":"854.95009ms","remote":"127.0.0.1:56480","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-08-15T02:44:51.175116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:50.322806Z","time spent":"852.199116ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:44:51.320185Z","caller":"traceutil/trace.go:171","msg":"trace[1081650276] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"121.099175ms","start":"2026-08-15T02:44:51.199051Z","end":"2026-08-15T02:44:51.320151Z","steps":["trace[1081650276] 'process raft request' (duration: 60.529747ms)","trace[1081650276] 'compare' (duration: 60.417854ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:53.001206Z","caller":"traceutil/trace.go:171","msg":"trace[1486981559] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"182.303945ms","start":"2026-08-15T02:44:52.818879Z","end":"2026-08-15T02:44:53.001183Z","steps":["trace[1486981559] 'process raft request' (duration: 182.190542ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:57.717737Z","caller":"traceutil/trace.go:171","msg":"trace[285161352] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"171.156767ms","start":"2026-08-15T02:44:57.546545Z","end":"2026-08-15T02:44:57.717702Z","steps":["trace[285161352] 'process raft request' (duration: 170.678824ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:57.995075Z","caller":"traceutil/trace.go:171","msg":"trace[1849475570] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"238.081403ms","start":"2026-08-15T02:44:57.756965Z","end":"2026-08-15T02:44:57.995047Z","steps":["trace[1849475570] 'process raft request' (duration: 238.025281ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:57.995355Z","caller":"traceutil/trace.go:171","msg":"trace[811001410] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"241.062651ms","start":"2026-08-15T02:44:57.754277Z","end":"2026-08-15T02:44:57.99534Z","steps":["trace[811001410] 'read index received' (duration: 230.14144ms)","trace[811001410] 'applied index is now lower than readState.Index' (duration: 10.920061ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:57.995323Z","caller":"traceutil/trace.go:171","msg":"trace[115448016] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"243.710352ms","start":"2026-08-15T02:44:57.751582Z","end":"2026-08-15T02:44:57.995292Z","steps":["trace[115448016] 'process raft request' (duration: 232.808682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:57.995598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.300528ms","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:533"} {"level":"info","ts":"2026-08-15T02:44:57.995634Z","caller":"traceutil/trace.go:171","msg":"trace[545997859] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1345; }","duration":"241.35523ms","start":"2026-08-15T02:44:57.754269Z","end":"2026-08-15T02:44:57.995624Z","steps":["trace[545997859] 'agreement among raft nodes before linearized reading' (duration: 241.192836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:57.995997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.910063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-dnz2v\" ","response":"range_response_count:1 size:11920"} {"level":"info","ts":"2026-08-15T02:44:57.99604Z","caller":"traceutil/trace.go:171","msg":"trace[2040281509] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-6f6497f79c-dnz2v; range_end:; response_count:1; response_revision:1345; }","duration":"232.994357ms","start":"2026-08-15T02:44:57.763033Z","end":"2026-08-15T02:44:57.996027Z","steps":["trace[2040281509] 'agreement among raft nodes before linearized reading' (duration: 232.867274ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:45:38.816884Z","caller":"traceutil/trace.go:171","msg":"trace[744232180] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"137.478278ms","start":"2026-08-15T02:45:38.679382Z","end":"2026-08-15T02:45:38.81686Z","steps":["trace[744232180] 'process raft request' (duration: 128.954901ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:46:29.034196Z","caller":"traceutil/trace.go:171","msg":"trace[1729260025] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"133.452133ms","start":"2026-08-15T02:46:28.900694Z","end":"2026-08-15T02:46:29.034146Z","steps":["trace[1729260025] 'process raft request' (duration: 81.264974ms)","trace[1729260025] 'compare' (duration: 51.400047ms)"],"step_count":2}