{"level":"warn","ts":"2026-07-17T11:11:59.064024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T11:11:59.064125Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121: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.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T11:11:59.064205Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T11:11:59.064222Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-07-17T11:11:59.064242Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T11:11:59.065273Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-07-17T11:11:59.065383Z","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.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T11:11:59.068426Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.765523ms"} {"level":"info","ts":"2026-07-17T11:11:59.075445Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-07-17T11:11:59.075654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T11:11:59.075691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-07-17T11:11:59.075725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T11:11:59.075737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-07-17T11:11:59.075821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-07-17T11:11:59.081688Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T11:11:59.085291Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T11:11:59.087047Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T11:11:59.08913Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T11:11:59.089346Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T11:11:59.089471Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T11:11:59.08949Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T11:11:59.089572Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T11:11:59.095357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-07-17T11:11:59.095544Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-07-17T11:11:59.09602Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T11:11:59.096112Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-07-17T11:11:59.096148Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-07-17T11:11:59.096268Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T11:11:59.096307Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T11:11:59.5764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T11:11:59.576488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T11:11:59.576501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-07-17T11:11:59.576511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-07-17T11:11:59.576517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-07-17T11:11:59.576524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-07-17T11:11:59.576531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-07-17T11:11:59.578181Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T11:11:59.578187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:11:59.578333Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:11:59.578356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:11:59.579393Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:11:59.579648Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:11:59.579706Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:11:59.579957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-07-17T11:11:59.583206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T11:11:59.584579Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T11:11:59.584657Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T11:12:03.5237Z","caller":"traceutil/trace.go:171","msg":"trace[2137360298] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"125.787236ms","start":"2026-07-17T11:12:03.397889Z","end":"2026-07-17T11:12:03.523676Z","steps":["trace[2137360298] 'read index received' (duration: 90.332661ms)","trace[2137360298] 'applied index is now lower than readState.Index' (duration: 35.453645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:12:03.523917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.004231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:12:03.524112Z","caller":"traceutil/trace.go:171","msg":"trace[2095389140] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:0; response_revision:242; }","duration":"126.301239ms","start":"2026-07-17T11:12:03.397799Z","end":"2026-07-17T11:12:03.524101Z","steps":["trace[2095389140] 'agreement among raft nodes before linearized reading' (duration: 126.050932ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:03.524Z","caller":"traceutil/trace.go:171","msg":"trace[1731499700] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"136.636792ms","start":"2026-07-17T11:12:03.387314Z","end":"2026-07-17T11:12:03.523951Z","steps":["trace[1731499700] 'process raft request' (duration: 100.90166ms)","trace[1731499700] 'compare' (duration: 35.336432ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:12:40.187163Z","caller":"traceutil/trace.go:171","msg":"trace[979280282] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"375.13272ms","start":"2026-07-17T11:12:39.811981Z","end":"2026-07-17T11:12:40.187114Z","steps":["trace[979280282] 'process raft request' (duration: 374.965515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.187808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.81196Z","time spent":"375.406527ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:40.196017Z","caller":"traceutil/trace.go:171","msg":"trace[1041526180] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"373.285649ms","start":"2026-07-17T11:12:39.822709Z","end":"2026-07-17T11:12:40.195995Z","steps":["trace[1041526180] 'process raft request' (duration: 373.165605ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.196092Z","caller":"traceutil/trace.go:171","msg":"trace[289593164] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"359.425263ms","start":"2026-07-17T11:12:39.836658Z","end":"2026-07-17T11:12:40.196083Z","steps":["trace[289593164] 'process raft request' (duration: 359.32988ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.196123Z","caller":"traceutil/trace.go:171","msg":"trace[267615975] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"369.574135ms","start":"2026-07-17T11:12:39.826542Z","end":"2026-07-17T11:12:40.196116Z","steps":["trace[267615975] 'process raft request' (duration: 369.361319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.196161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.822695Z","time spent":"373.391951ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:40.196221Z","caller":"traceutil/trace.go:171","msg":"trace[1634153641] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"351.417471ms","start":"2026-07-17T11:12:39.844799Z","end":"2026-07-17T11:12:40.196216Z","steps":["trace[1634153641] 'process raft request' (duration: 351.289247ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.196262Z","caller":"traceutil/trace.go:171","msg":"trace[80947535] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"343.348556ms","start":"2026-07-17T11:12:39.852906Z","end":"2026-07-17T11:12:40.196254Z","steps":["trace[80947535] 'process raft request' (duration: 343.229713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.196304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.852885Z","time spent":"343.396688ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:40.196013Z","caller":"traceutil/trace.go:171","msg":"trace[1828574232] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"382.775452ms","start":"2026-07-17T11:12:39.813203Z","end":"2026-07-17T11:12:40.195979Z","steps":["trace[1828574232] 'process raft request' (duration: 382.637708ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.19647Z","caller":"traceutil/trace.go:171","msg":"trace[1238219116] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:550; }","duration":"380.437308ms","start":"2026-07-17T11:12:39.816023Z","end":"2026-07-17T11:12:40.19646Z","steps":["trace[1238219116] 'read index received' (duration: 370.967534ms)","trace[1238219116] 'applied index is now lower than readState.Index' (duration: 9.469264ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:12:40.19649Z","caller":"traceutil/trace.go:171","msg":"trace[1077022524] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"313.253649ms","start":"2026-07-17T11:12:39.883225Z","end":"2026-07-17T11:12:40.196479Z","steps":["trace[1077022524] 'process raft request' (duration: 312.961761ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.196491Z","caller":"traceutil/trace.go:171","msg":"trace[1508461660] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"188.535382ms","start":"2026-07-17T11:12:40.007946Z","end":"2026-07-17T11:12:40.196481Z","steps":["trace[1508461660] 'process raft request' (duration: 188.401868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.196545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.883192Z","time spent":"313.325721ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.196588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.554081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T11:12:40.195953Z","caller":"traceutil/trace.go:171","msg":"trace[1195250072] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"383.857453ms","start":"2026-07-17T11:12:39.81208Z","end":"2026-07-17T11:12:40.195937Z","steps":["trace[1195250072] 'process raft request' (duration: 383.680058ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.196223Z","caller":"traceutil/trace.go:171","msg":"trace[545525262] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"359.487945ms","start":"2026-07-17T11:12:39.836726Z","end":"2026-07-17T11:12:40.196214Z","steps":["trace[545525262] 'process raft request' (duration: 359.31158ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:40.196623Z","caller":"traceutil/trace.go:171","msg":"trace[2004204744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"380.612962ms","start":"2026-07-17T11:12:39.816003Z","end":"2026-07-17T11:12:40.196616Z","steps":["trace[2004204744] 'agreement among raft nodes before linearized reading' (duration: 380.520369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.196699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.836721Z","time spent":"359.952257ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.196719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.815997Z","time spent":"380.712945ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:12:40.196182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.826529Z","time spent":"369.610397ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:40.196093Z","caller":"traceutil/trace.go:171","msg":"trace[1354284711] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"364.395421ms","start":"2026-07-17T11:12:39.831685Z","end":"2026-07-17T11:12:40.19608Z","steps":["trace[1354284711] 'process raft request' (duration: 364.265637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.196873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.831673Z","time spent":"365.174513ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.196186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.836647Z","time spent":"359.487715ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.196339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.844783Z","time spent":"351.488712ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.196602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.813197Z","time spent":"383.261226ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.197218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.812063Z","time spent":"384.596053ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:12:40.200022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.956735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T11:12:40.200129Z","caller":"traceutil/trace.go:171","msg":"trace[1139616210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"384.067458ms","start":"2026-07-17T11:12:39.816041Z","end":"2026-07-17T11:12:40.200108Z","steps":["trace[1139616210] 'agreement among raft nodes before linearized reading' (duration: 383.899513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.20024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.816038Z","time spent":"384.186031ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:12:40.200691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.009551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:12:40.200774Z","caller":"traceutil/trace.go:171","msg":"trace[2016187892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"382.100123ms","start":"2026-07-17T11:12:39.818662Z","end":"2026-07-17T11:12:40.200762Z","steps":["trace[2016187892] 'agreement among raft nodes before linearized reading' (duration: 381.99558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:40.200839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:39.818657Z","time spent":"382.171445ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:12:47.092886Z","caller":"traceutil/trace.go:171","msg":"trace[691687845] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"167.016914ms","start":"2026-07-17T11:12:46.925824Z","end":"2026-07-17T11:12:47.092841Z","steps":["trace[691687845] 'read index received' (duration: 164.994847ms)","trace[691687845] 'applied index is now lower than readState.Index' (duration: 2.021277ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:12:47.093895Z","caller":"traceutil/trace.go:171","msg":"trace[145716045] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"224.712907ms","start":"2026-07-17T11:12:46.8688Z","end":"2026-07-17T11:12:47.093512Z","steps":["trace[145716045] 'process raft request' (duration: 222.532237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:47.094263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.310152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:12:47.094373Z","caller":"traceutil/trace.go:171","msg":"trace[1848959635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"168.566006ms","start":"2026-07-17T11:12:46.925776Z","end":"2026-07-17T11:12:47.094342Z","steps":["trace[1848959635] 'agreement among raft nodes before linearized reading' (duration: 167.24905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.164561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.145888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:12:48.164699Z","caller":"traceutil/trace.go:171","msg":"trace[1380596265] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"864.428343ms","start":"2026-07-17T11:12:47.300254Z","end":"2026-07-17T11:12:48.164683Z","steps":["trace[1380596265] 'read index received' (duration: 100.777162ms)","trace[1380596265] 'applied index is now lower than readState.Index' (duration: 763.649871ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:12:48.164745Z","caller":"traceutil/trace.go:171","msg":"trace[2098592150] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"955.563147ms","start":"2026-07-17T11:12:47.209146Z","end":"2026-07-17T11:12:48.164709Z","steps":["trace[2098592150] 'process raft request' (duration: 191.902456ms)","trace[2098592150] 'compare' (duration: 762.92183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:12:48.16486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.058828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:12:48.164882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:47.209131Z","time spent":"955.68916ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:48.164908Z","caller":"traceutil/trace.go:171","msg":"trace[197131213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"616.13739ms","start":"2026-07-17T11:12:47.548751Z","end":"2026-07-17T11:12:48.164889Z","steps":["trace[197131213] 'agreement among raft nodes before linearized reading' (duration: 616.031207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.164947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:47.548733Z","time spent":"616.205712ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:12:48.164977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.634223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:12:48.165049Z","caller":"traceutil/trace.go:171","msg":"trace[988506908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"548.727336ms","start":"2026-07-17T11:12:47.616304Z","end":"2026-07-17T11:12:48.165032Z","steps":["trace[988506908] 'agreement among raft nodes before linearized reading' (duration: 548.536341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.165093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:47.616248Z","time spent":"548.834869ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:12:48.165088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.773822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5ct29\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-17T11:12:48.165188Z","caller":"traceutil/trace.go:171","msg":"trace[623351303] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5ct29; range_end:; response_count:1; response_revision:648; }","duration":"864.989659ms","start":"2026-07-17T11:12:47.300185Z","end":"2026-07-17T11:12:48.165175Z","steps":["trace[623351303] 'agreement among raft nodes before linearized reading' (duration: 864.572297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.166093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:47.300156Z","time spent":"865.870814ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-5ct29\" "} {"level":"info","ts":"2026-07-17T11:12:48.609235Z","caller":"traceutil/trace.go:171","msg":"trace[1374547916] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"440.314221ms","start":"2026-07-17T11:12:48.168897Z","end":"2026-07-17T11:12:48.609212Z","steps":["trace[1374547916] 'process raft request' (duration: 415.926283ms)","trace[1374547916] 'compare' (duration: 24.280725ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:12:48.60936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.168875Z","time spent":"440.431655ms","remote":"127.0.0.1:48478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:48.630708Z","caller":"traceutil/trace.go:171","msg":"trace[764631880] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"460.981777ms","start":"2026-07-17T11:12:48.169704Z","end":"2026-07-17T11:12:48.630686Z","steps":["trace[764631880] 'process raft request' (duration: 460.808462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.630822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.169693Z","time spent":"461.078209ms","remote":"127.0.0.1:48478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:12:48.631154Z","caller":"traceutil/trace.go:171","msg":"trace[1291836660] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"460.500093ms","start":"2026-07-17T11:12:48.170647Z","end":"2026-07-17T11:12:48.631147Z","steps":["trace[1291836660] 'process raft request' (duration: 459.990109ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:48.631348Z","caller":"traceutil/trace.go:171","msg":"trace[836613499] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"460.557485ms","start":"2026-07-17T11:12:48.170757Z","end":"2026-07-17T11:12:48.631315Z","steps":["trace[836613499] 'process raft request' (duration: 460.334929ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:48.631452Z","caller":"traceutil/trace.go:171","msg":"trace[1907860912] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"460.437302ms","start":"2026-07-17T11:12:48.171002Z","end":"2026-07-17T11:12:48.63144Z","steps":["trace[1907860912] 'process raft request' (duration: 460.118183ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:48.631541Z","caller":"traceutil/trace.go:171","msg":"trace[1392713026] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:669; }","duration":"370.287905ms","start":"2026-07-17T11:12:48.261245Z","end":"2026-07-17T11:12:48.631532Z","steps":["trace[1392713026] 'read index received' (duration: 323.602407ms)","trace[1392713026] 'applied index is now lower than readState.Index' (duration: 46.684848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:12:48.631695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.449179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T11:12:48.631681Z","caller":"traceutil/trace.go:171","msg":"trace[1714314546] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"451.357129ms","start":"2026-07-17T11:12:48.180292Z","end":"2026-07-17T11:12:48.631649Z","steps":["trace[1714314546] 'process raft request' (duration: 451.085432ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:12:48.631741Z","caller":"traceutil/trace.go:171","msg":"trace[272312245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"370.532522ms","start":"2026-07-17T11:12:48.261198Z","end":"2026-07-17T11:12:48.63173Z","steps":["trace[272312245] 'agreement among raft nodes before linearized reading' (duration: 370.410658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.631773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.472471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-17T11:12:48.631778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.261179Z","time spent":"370.592524ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T11:12:48.631804Z","caller":"traceutil/trace.go:171","msg":"trace[1517236882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"370.545392ms","start":"2026-07-17T11:12:48.261248Z","end":"2026-07-17T11:12:48.631794Z","steps":["trace[1517236882] 'agreement among raft nodes before linearized reading' (duration: 370.46121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:48.631839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.261224Z","time spent":"370.606603ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:12:48.632263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.170625Z","time spent":"460.549484ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:12:48.632446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.170991Z","time spent":"460.495993ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:12:48.632565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.170735Z","time spent":"460.654607ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:12:48.632534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.18026Z","time spent":"451.494093ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:12:50.339535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.277489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:12:50.339635Z","caller":"traceutil/trace.go:171","msg":"trace[312769843] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.701812834s","start":"2026-07-17T11:12:48.637808Z","end":"2026-07-17T11:12:50.33962Z","steps":["trace[312769843] 'process raft request' (duration: 746.384261ms)","trace[312769843] 'compare' (duration: 954.882558ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:12:50.33968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:48.637784Z","time spent":"1.701873696s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:12:50.345595Z","caller":"traceutil/trace.go:171","msg":"trace[1214558286] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"1.151433533s","start":"2026-07-17T11:12:49.194138Z","end":"2026-07-17T11:12:50.345571Z","steps":["trace[1214558286] 'read index received' (duration: 190.064395ms)","trace[1214558286] 'applied index is now lower than readState.Index' (duration: 961.368058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:12:50.345749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.27148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:12:50.345785Z","caller":"traceutil/trace.go:171","msg":"trace[1484561018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"795.367932ms","start":"2026-07-17T11:12:49.550408Z","end":"2026-07-17T11:12:50.345776Z","steps":["trace[1484561018] 'agreement among raft nodes before linearized reading' (duration: 795.2831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:50.345799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151725851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:12:50.345847Z","caller":"traceutil/trace.go:171","msg":"trace[359956432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"1.151781372s","start":"2026-07-17T11:12:49.194055Z","end":"2026-07-17T11:12:50.345837Z","steps":["trace[359956432] 'agreement among raft nodes before linearized reading' (duration: 1.151617288s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:50.345871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:49.194009Z","time spent":"1.151857564s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:12:50.345812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:49.550386Z","time spent":"795.419014ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:12:52.016486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.120657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:12:52.016561Z","caller":"traceutil/trace.go:171","msg":"trace[1604757076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"467.23578ms","start":"2026-07-17T11:12:51.549308Z","end":"2026-07-17T11:12:52.016544Z","steps":["trace[1604757076] 'range keys from in-memory index tree' (duration: 467.024765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:12:52.016621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:12:51.549289Z","time spent":"467.322393ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:13:02.920516Z","caller":"traceutil/trace.go:171","msg":"trace[1775656404] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"124.606784ms","start":"2026-07-17T11:13:02.795876Z","end":"2026-07-17T11:13:02.920483Z","steps":["trace[1775656404] 'process raft request' (duration: 124.387818ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:13:02.921279Z","caller":"traceutil/trace.go:171","msg":"trace[1267494284] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"123.470193ms","start":"2026-07-17T11:13:02.797788Z","end":"2026-07-17T11:13:02.921258Z","steps":["trace[1267494284] 'process raft request' (duration: 123.39065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:05.728405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.642364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:13:05.728594Z","caller":"traceutil/trace.go:171","msg":"trace[116720005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"179.881481ms","start":"2026-07-17T11:13:05.548688Z","end":"2026-07-17T11:13:05.72857Z","steps":["trace[116720005] 'range keys from in-memory index tree' (duration: 179.572443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:05.728396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.106064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q29jm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-17T11:13:05.728784Z","caller":"traceutil/trace.go:171","msg":"trace[1306765278] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q29jm; range_end:; response_count:1; response_revision:810; }","duration":"323.508554ms","start":"2026-07-17T11:13:05.405166Z","end":"2026-07-17T11:13:05.728675Z","steps":["trace[1306765278] 'range keys from in-memory index tree' (duration: 322.847886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:05.728875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:05.40515Z","time spent":"323.70588ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q29jm\" "} {"level":"warn","ts":"2026-07-17T11:13:25.261971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:25.762417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:26.262905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:26.76434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:27.355482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:27.856916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:28.39698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.057013506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-17T11:13:28.397037Z","caller":"traceutil/trace.go:171","msg":"trace[1676129115] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.057109829s","start":"2026-07-17T11:13:26.339915Z","end":"2026-07-17T11:13:28.397025Z","steps":["trace[1676129115] 'agreement among raft nodes before linearized reading' (duration: 2.057040097s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.397065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:26.339903Z","time spent":"2.057156111s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 11:13:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-17T11:13:28.397386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232175630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:13:28.399756Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.879961088s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:13:28.400907Z","caller":"traceutil/trace.go:171","msg":"trace[1110269013] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"3.881147571s","start":"2026-07-17T11:13:24.519741Z","end":"2026-07-17T11:13:28.400889Z","steps":["trace[1110269013] 'process raft request' (duration: 3.880997627s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.401051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:24.519732Z","time spent":"3.881228803s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:13:28.445919Z","caller":"traceutil/trace.go:171","msg":"trace[552104375] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"2.155865813s","start":"2026-07-17T11:13:26.290019Z","end":"2026-07-17T11:13:28.445885Z","steps":["trace[552104375] 'process raft request' (duration: 2.15251177s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.446094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:26.289999Z","time spent":"2.156006998s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:13:28.446397Z","caller":"traceutil/trace.go:171","msg":"trace[1360569976] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.765607823s","start":"2026-07-17T11:13:26.680781Z","end":"2026-07-17T11:13:28.446389Z","steps":["trace[1360569976] 'process raft request' (duration: 1.761868889s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.446447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:26.680764Z","time spent":"1.765661004s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:13:28.451326Z","caller":"traceutil/trace.go:171","msg":"trace[2098517989] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"3.690366665s","start":"2026-07-17T11:13:24.76094Z","end":"2026-07-17T11:13:28.451307Z","steps":["trace[2098517989] 'read index received' (duration: 3.640546681s)","trace[2098517989] 'applied index is now lower than readState.Index' (duration: 49.818814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:13:28.451589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.69052813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:13:28.451639Z","caller":"traceutil/trace.go:171","msg":"trace[1142769721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"3.690596172s","start":"2026-07-17T11:13:24.761031Z","end":"2026-07-17T11:13:28.451627Z","steps":["trace[1142769721] 'agreement among raft nodes before linearized reading' (duration: 3.690455398s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.45164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691069591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T11:13:28.451664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:24.761028Z","time spent":"3.690628913s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T11:13:28.451674Z","caller":"traceutil/trace.go:171","msg":"trace[1908513805] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:969; }","duration":"1.691132153s","start":"2026-07-17T11:13:26.76053Z","end":"2026-07-17T11:13:28.451662Z","steps":["trace[1908513805] 'agreement among raft nodes before linearized reading' (duration: 1.691087431s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.451694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:26.760516Z","time spent":"1.691172314s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:13:28.451597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161456108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T11:13:28.451764Z","caller":"traceutil/trace.go:171","msg":"trace[1024714584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"2.161654834s","start":"2026-07-17T11:13:26.290104Z","end":"2026-07-17T11:13:28.451758Z","steps":["trace[1024714584] 'agreement among raft nodes before linearized reading' (duration: 2.161401807s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.451785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:26.290099Z","time spent":"2.161681145s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:13:28.451931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.317381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T11:13:28.451946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161957782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T11:13:28.451962Z","caller":"traceutil/trace.go:171","msg":"trace[872427215] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:969; }","duration":"606.358392ms","start":"2026-07-17T11:13:27.845597Z","end":"2026-07-17T11:13:28.451955Z","steps":["trace[872427215] 'agreement among raft nodes before linearized reading' (duration: 606.30581ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:13:28.451965Z","caller":"traceutil/trace.go:171","msg":"trace[1099758889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"2.162002294s","start":"2026-07-17T11:13:26.289957Z","end":"2026-07-17T11:13:28.451959Z","steps":["trace[1099758889] 'agreement among raft nodes before linearized reading' (duration: 2.161940382s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.451987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:27.845594Z","time spent":"606.385973ms","remote":"127.0.0.1:48518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:13:28.451986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:26.289943Z","time spent":"2.162039585s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:13:28.452012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.691068545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:13:28.452041Z","caller":"traceutil/trace.go:171","msg":"trace[1520567297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"3.691126176s","start":"2026-07-17T11:13:24.760908Z","end":"2026-07-17T11:13:28.452034Z","steps":["trace[1520567297] 'agreement among raft nodes before linearized reading' (duration: 3.691047384s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.452063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:24.760894Z","time spent":"3.691164018s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:13:28.452169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.625449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:13:28.452195Z","caller":"traceutil/trace.go:171","msg":"trace[939832466] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:969; }","duration":"606.67976ms","start":"2026-07-17T11:13:27.84551Z","end":"2026-07-17T11:13:28.45219Z","steps":["trace[939832466] 'agreement among raft nodes before linearized reading' (duration: 606.581438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:28.452215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:13:27.845499Z","time spent":"606.710981ms","remote":"127.0.0.1:48478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:14:08.701734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.68353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T11:14:08.702114Z","caller":"traceutil/trace.go:171","msg":"trace[1751161100] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"153.336757ms","start":"2026-07-17T11:14:08.548738Z","end":"2026-07-17T11:14:08.702075Z","steps":["trace[1751161100] 'read index received' (duration: 48.761µs)","trace[1751161100] 'applied index is now lower than readState.Index' (duration: 153.285646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:14:08.702308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.553553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:08.702355Z","caller":"traceutil/trace.go:171","msg":"trace[1088444196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"153.638126ms","start":"2026-07-17T11:14:08.548703Z","end":"2026-07-17T11:14:08.702341Z","steps":["trace[1088444196] 'agreement among raft nodes before linearized reading' (duration: 153.535443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:08.702619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.082724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:14:08.702681Z","caller":"traceutil/trace.go:171","msg":"trace[1649231401] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1104; }","duration":"143.175497ms","start":"2026-07-17T11:14:08.55949Z","end":"2026-07-17T11:14:08.702666Z","steps":["trace[1649231401] 'agreement among raft nodes before linearized reading' (duration: 143.059023ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:10.869564Z","caller":"traceutil/trace.go:171","msg":"trace[454021295] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"151.6761ms","start":"2026-07-17T11:14:10.717868Z","end":"2026-07-17T11:14:10.869544Z","steps":["trace[454021295] 'read index received' (duration: 151.568267ms)","trace[454021295] 'applied index is now lower than readState.Index' (duration: 107.183µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:14:10.86963Z","caller":"traceutil/trace.go:171","msg":"trace[902300666] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"161.408698ms","start":"2026-07-17T11:14:10.708199Z","end":"2026-07-17T11:14:10.869608Z","steps":["trace[902300666] 'process raft request' (duration: 161.217613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:10.869721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.837965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:14:10.869747Z","caller":"traceutil/trace.go:171","msg":"trace[1281912187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"151.913007ms","start":"2026-07-17T11:14:10.717827Z","end":"2026-07-17T11:14:10.86974Z","steps":["trace[1281912187] 'agreement among raft nodes before linearized reading' (duration: 151.805444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:10.869855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.399247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:14:10.869876Z","caller":"traceutil/trace.go:171","msg":"trace[148519565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"120.461299ms","start":"2026-07-17T11:14:10.74941Z","end":"2026-07-17T11:14:10.869871Z","steps":["trace[148519565] 'agreement among raft nodes before linearized reading' (duration: 120.408997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:10.869879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.82086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T11:14:10.869935Z","caller":"traceutil/trace.go:171","msg":"trace[1940913804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"120.915822ms","start":"2026-07-17T11:14:10.749005Z","end":"2026-07-17T11:14:10.869921Z","steps":["trace[1940913804] 'agreement among raft nodes before linearized reading' (duration: 120.775658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:10.869979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.895132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T11:14:10.869999Z","caller":"traceutil/trace.go:171","msg":"trace[1231173275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"120.929472ms","start":"2026-07-17T11:14:10.749065Z","end":"2026-07-17T11:14:10.869995Z","steps":["trace[1231173275] 'agreement among raft nodes before linearized reading' (duration: 120.85127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:10.870036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.13901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:14:10.870085Z","caller":"traceutil/trace.go:171","msg":"trace[2013126568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"120.229663ms","start":"2026-07-17T11:14:10.749844Z","end":"2026-07-17T11:14:10.870073Z","steps":["trace[2013126568] 'agreement among raft nodes before linearized reading' (duration: 120.038447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.417815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232176041,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:14:15.630886Z","caller":"traceutil/trace.go:171","msg":"trace[1882630593] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"713.124941ms","start":"2026-07-17T11:14:14.91774Z","end":"2026-07-17T11:14:15.630865Z","steps":["trace[1882630593] 'read index received' (duration: 712.931615ms)","trace[1882630593] 'applied index is now lower than readState.Index' (duration: 192.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:14:15.631Z","caller":"traceutil/trace.go:171","msg":"trace[569740631] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"745.123884ms","start":"2026-07-17T11:14:14.885868Z","end":"2026-07-17T11:14:15.630992Z","steps":["trace[569740631] 'process raft request' (duration: 744.860217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.631093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:14.885839Z","time spent":"745.182945ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:14:15.631301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.550889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T11:14:15.631401Z","caller":"traceutil/trace.go:171","msg":"trace[1446455787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1125; }","duration":"702.677272ms","start":"2026-07-17T11:14:14.928703Z","end":"2026-07-17T11:14:15.63138Z","steps":["trace[1446455787] 'agreement among raft nodes before linearized reading' (duration: 702.341733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.631465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:14.928668Z","time spent":"702.788626ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:14:15.631494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.761548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:14:15.631527Z","caller":"traceutil/trace.go:171","msg":"trace[2052434310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"713.853681ms","start":"2026-07-17T11:14:14.917665Z","end":"2026-07-17T11:14:15.631519Z","steps":["trace[2052434310] 'agreement among raft nodes before linearized reading' (duration: 713.764969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.631557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:14.917629Z","time spent":"713.922613ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:14:15.631668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.412206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T11:14:15.631693Z","caller":"traceutil/trace.go:171","msg":"trace[495035175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1125; }","duration":"701.456127ms","start":"2026-07-17T11:14:14.930229Z","end":"2026-07-17T11:14:15.631685Z","steps":["trace[495035175] 'agreement among raft nodes before linearized reading' (duration: 701.389175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.631711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:14.930221Z","time spent":"701.484598ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:14:15.631753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.04467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:14:15.631792Z","caller":"traceutil/trace.go:171","msg":"trace[1472452913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"698.130592ms","start":"2026-07-17T11:14:14.933655Z","end":"2026-07-17T11:14:15.631786Z","steps":["trace[1472452913] 'agreement among raft nodes before linearized reading' (duration: 697.490774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.632459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:14.933632Z","time spent":"698.810182ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:14:15.631816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.416475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:14:15.632656Z","caller":"traceutil/trace.go:171","msg":"trace[557867721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"703.291379ms","start":"2026-07-17T11:14:14.929351Z","end":"2026-07-17T11:14:15.632642Z","steps":["trace[557867721] 'agreement among raft nodes before linearized reading' (duration: 702.416514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.632686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:14.929326Z","time spent":"703.351531ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:14:15.631867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.00594ms","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-07-17T11:14:15.632764Z","caller":"traceutil/trace.go:171","msg":"trace[326166164] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1125; }","duration":"387.068382ms","start":"2026-07-17T11:14:15.245687Z","end":"2026-07-17T11:14:15.632756Z","steps":["trace[326166164] 'agreement among raft nodes before linearized reading' (duration: 385.95334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:15.632795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:15.245616Z","time spent":"387.168924ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:14:16.755223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232176044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:14:17.117536Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.478494331s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:14:17.124134Z","caller":"traceutil/trace.go:171","msg":"trace[1481216456] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"1.485217803s","start":"2026-07-17T11:14:15.638895Z","end":"2026-07-17T11:14:17.124113Z","steps":["trace[1481216456] 'process raft request' (duration: 1.478965815s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.124268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:15.638863Z","time spent":"1.485333797s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:17.150383Z","caller":"traceutil/trace.go:171","msg":"trace[395352168] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1168; }","duration":"895.340143ms","start":"2026-07-17T11:14:16.255024Z","end":"2026-07-17T11:14:17.150365Z","steps":["trace[395352168] 'read index received' (duration: 862.743282ms)","trace[395352168] 'applied index is now lower than readState.Index' (duration: 32.59631ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:14:17.150665Z","caller":"traceutil/trace.go:171","msg":"trace[1985593171] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"1.511306708s","start":"2026-07-17T11:14:15.639348Z","end":"2026-07-17T11:14:17.150655Z","steps":["trace[1985593171] 'process raft request' (duration: 1.510834024s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.150752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:15.63934Z","time spent":"1.511347209s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:17.150882Z","caller":"traceutil/trace.go:171","msg":"trace[742150495] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"1.511430571s","start":"2026-07-17T11:14:15.639444Z","end":"2026-07-17T11:14:17.150874Z","steps":["trace[742150495] 'process raft request' (duration: 1.510853275s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.150919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:15.639411Z","time spent":"1.511486713s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:17.151003Z","caller":"traceutil/trace.go:171","msg":"trace[246979724] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"1.511551454s","start":"2026-07-17T11:14:15.639444Z","end":"2026-07-17T11:14:17.150995Z","steps":["trace[246979724] 'process raft request' (duration: 1.510877505s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.151036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:15.639411Z","time spent":"1.511605386s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:17.151126Z","caller":"traceutil/trace.go:171","msg":"trace[2108723194] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"1.511673998s","start":"2026-07-17T11:14:15.639444Z","end":"2026-07-17T11:14:17.151118Z","steps":["trace[2108723194] 'process raft request' (duration: 1.510898416s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.15116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:15.639411Z","time spent":"1.51172928s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:14:17.151264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.236138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:14:17.151287Z","caller":"traceutil/trace.go:171","msg":"trace[1464132864] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1130; }","duration":"896.28699ms","start":"2026-07-17T11:14:16.254992Z","end":"2026-07-17T11:14:17.151279Z","steps":["trace[1464132864] 'agreement among raft nodes before linearized reading' (duration: 896.239868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.151306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:16.254976Z","time spent":"896.32464ms","remote":"127.0.0.1:44400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:14:17.151412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.99253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:17.151429Z","caller":"traceutil/trace.go:171","msg":"trace[2015979146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"593.035501ms","start":"2026-07-17T11:14:16.558388Z","end":"2026-07-17T11:14:17.151423Z","steps":["trace[2015979146] 'agreement among raft nodes before linearized reading' (duration: 593.00502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:17.151443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:16.558372Z","time spent":"593.067592ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:14:45.397111Z","caller":"traceutil/trace.go:171","msg":"trace[1250745912] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"196.795209ms","start":"2026-07-17T11:14:45.200299Z","end":"2026-07-17T11:14:45.397094Z","steps":["trace[1250745912] 'process raft request' (duration: 196.451049ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:45.718789Z","caller":"traceutil/trace.go:171","msg":"trace[25677559] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1327; }","duration":"166.609957ms","start":"2026-07-17T11:14:45.552161Z","end":"2026-07-17T11:14:45.718771Z","steps":["trace[25677559] 'read index received' (duration: 9.569583ms)","trace[25677559] 'applied index is now lower than readState.Index' (duration: 157.039784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:14:45.718882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.706839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:45.718903Z","caller":"traceutil/trace.go:171","msg":"trace[1579537652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"166.769301ms","start":"2026-07-17T11:14:45.552127Z","end":"2026-07-17T11:14:45.718896Z","steps":["trace[1579537652] 'agreement among raft nodes before linearized reading' (duration: 166.71722ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:45.71894Z","caller":"traceutil/trace.go:171","msg":"trace[766589709] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"219.96768ms","start":"2026-07-17T11:14:45.498951Z","end":"2026-07-17T11:14:45.718919Z","steps":["trace[766589709] 'process raft request' (duration: 191.095316ms)","trace[766589709] 'compare' (duration: 28.38631ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:14:45.720028Z","caller":"traceutil/trace.go:171","msg":"trace[2018144676] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"220.006981ms","start":"2026-07-17T11:14:45.499016Z","end":"2026-07-17T11:14:45.719023Z","steps":["trace[2018144676] 'process raft request' (duration: 219.714233ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:47.778674Z","caller":"traceutil/trace.go:171","msg":"trace[1449691405] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"278.643615ms","start":"2026-07-17T11:14:47.500009Z","end":"2026-07-17T11:14:47.778652Z","steps":["trace[1449691405] 'read index received' (duration: 278.426919ms)","trace[1449691405] 'applied index is now lower than readState.Index' (duration: 215.756µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:14:47.778843Z","caller":"traceutil/trace.go:171","msg":"trace[255897224] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"322.714894ms","start":"2026-07-17T11:14:47.456095Z","end":"2026-07-17T11:14:47.77881Z","steps":["trace[255897224] 'process raft request' (duration: 322.402465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:47.778893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.197942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:47.778966Z","caller":"traceutil/trace.go:171","msg":"trace[496887554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"230.292225ms","start":"2026-07-17T11:14:47.548634Z","end":"2026-07-17T11:14:47.778926Z","steps":["trace[496887554] 'agreement among raft nodes before linearized reading' (duration: 230.210643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:47.778981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.370472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-17T11:14:47.778988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:47.456075Z","time spent":"322.834428ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:47.779023Z","caller":"traceutil/trace.go:171","msg":"trace[331520194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"213.440614ms","start":"2026-07-17T11:14:47.565571Z","end":"2026-07-17T11:14:47.779012Z","steps":["trace[331520194] 'agreement among raft nodes before linearized reading' (duration: 213.30256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:47.779067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.879492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:14:47.779128Z","caller":"traceutil/trace.go:171","msg":"trace[542202098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"279.176181ms","start":"2026-07-17T11:14:47.499941Z","end":"2026-07-17T11:14:47.779117Z","steps":["trace[542202098] 'agreement among raft nodes before linearized reading' (duration: 278.834871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:48.390251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.921081ms","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-07-17T11:14:48.390399Z","caller":"traceutil/trace.go:171","msg":"trace[1182795078] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1281; }","duration":"170.084946ms","start":"2026-07-17T11:14:48.220295Z","end":"2026-07-17T11:14:48.39038Z","steps":["trace[1182795078] 'range keys from in-memory index tree' (duration: 169.903891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:48.390808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.745743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:14:48.390897Z","caller":"traceutil/trace.go:171","msg":"trace[1033282876] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"608.106971ms","start":"2026-07-17T11:14:47.782776Z","end":"2026-07-17T11:14:48.390883Z","steps":["trace[1033282876] 'process raft request' (duration: 291.184413ms)","trace[1033282876] 'compare' (duration: 316.340232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:14:48.390943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:47.78275Z","time spent":"608.169913ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:48.596438Z","caller":"traceutil/trace.go:171","msg":"trace[1409064576] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"812.025482ms","start":"2026-07-17T11:14:47.784401Z","end":"2026-07-17T11:14:48.596427Z","steps":["trace[1409064576] 'process raft request' (duration: 811.816176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:48.59655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:47.784375Z","time spent":"812.106385ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:48.596888Z","caller":"traceutil/trace.go:171","msg":"trace[882546609] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"795.426639ms","start":"2026-07-17T11:14:47.801443Z","end":"2026-07-17T11:14:48.59687Z","steps":["trace[882546609] 'process raft request' (duration: 794.943385ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:48.596284Z","caller":"traceutil/trace.go:171","msg":"trace[985385910] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"811.835077ms","start":"2026-07-17T11:14:47.784405Z","end":"2026-07-17T11:14:48.59624Z","steps":["trace[985385910] 'process raft request' (duration: 811.782555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:48.597085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:47.801423Z","time spent":"795.520852ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:14:48.59714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:47.78439Z","time spent":"812.712563ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:48.596336Z","caller":"traceutil/trace.go:171","msg":"trace[1322558382] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"812.775434ms","start":"2026-07-17T11:14:47.783494Z","end":"2026-07-17T11:14:48.59627Z","steps":["trace[1322558382] 'process raft request' (duration: 734.386646ms)","trace[1322558382] 'compare' (duration: 78.206973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:14:48.597358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:47.783487Z","time spent":"813.794734ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:14:48.652567Z","caller":"traceutil/trace.go:171","msg":"trace[1186014242] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1333; }","duration":"100.021065ms","start":"2026-07-17T11:14:48.552518Z","end":"2026-07-17T11:14:48.652539Z","steps":["trace[1186014242] 'read index received' (duration: 12.97146ms)","trace[1186014242] 'applied index is now lower than readState.Index' (duration: 87.048215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:14:48.652692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.17521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:48.652729Z","caller":"traceutil/trace.go:171","msg":"trace[950269720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"100.287243ms","start":"2026-07-17T11:14:48.552432Z","end":"2026-07-17T11:14:48.65272Z","steps":["trace[950269720] 'agreement among raft nodes before linearized reading' (duration: 100.188721ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:50.090124Z","caller":"traceutil/trace.go:171","msg":"trace[1857419454] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"267.357635ms","start":"2026-07-17T11:14:49.822737Z","end":"2026-07-17T11:14:50.090095Z","steps":["trace[1857419454] 'read index received' (duration: 267.081697ms)","trace[1857419454] 'applied index is now lower than readState.Index' (duration: 274.928µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:14:50.090291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.523119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:50.090373Z","caller":"traceutil/trace.go:171","msg":"trace[652921140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"267.687894ms","start":"2026-07-17T11:14:49.822668Z","end":"2026-07-17T11:14:50.090356Z","steps":["trace[652921140] 'agreement among raft nodes before linearized reading' (duration: 267.513559ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:50.09086Z","caller":"traceutil/trace.go:171","msg":"trace[1054525648] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"302.564549ms","start":"2026-07-17T11:14:49.788276Z","end":"2026-07-17T11:14:50.09084Z","steps":["trace[1054525648] 'process raft request' (duration: 301.633603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:50.090982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:49.788248Z","time spent":"302.662071ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:14:50.182524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.730953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:14:50.182598Z","caller":"traceutil/trace.go:171","msg":"trace[1448863847] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1289; }","duration":"343.854356ms","start":"2026-07-17T11:14:49.838727Z","end":"2026-07-17T11:14:50.182581Z","steps":["trace[1448863847] 'agreement among raft nodes before linearized reading' (duration: 343.724623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:14:50.182638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:14:49.838708Z","time spent":"343.920058ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-17T11:14:50.182928Z","caller":"traceutil/trace.go:171","msg":"trace[1568793904] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"185.742777ms","start":"2026-07-17T11:14:49.997174Z","end":"2026-07-17T11:14:50.182917Z","steps":["trace[1568793904] 'process raft request' (duration: 185.14901ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:14:50.378607Z","caller":"traceutil/trace.go:171","msg":"trace[677340381] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"190.839412ms","start":"2026-07-17T11:14:50.187739Z","end":"2026-07-17T11:14:50.378578Z","steps":["trace[677340381] 'process raft request' (duration: 114.383282ms)","trace[677340381] 'compare' (duration: 76.079709ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:15:03.285146Z","caller":"traceutil/trace.go:171","msg":"trace[1185421975] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"170.466142ms","start":"2026-07-17T11:15:03.114657Z","end":"2026-07-17T11:15:03.285124Z","steps":["trace[1185421975] 'process raft request' (duration: 170.229895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:15:04.585227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185742837s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-17T11:15:04.585351Z","caller":"traceutil/trace.go:171","msg":"trace[756513810] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"1.297762262s","start":"2026-07-17T11:15:03.287564Z","end":"2026-07-17T11:15:04.585326Z","steps":["trace[756513810] 'read index received' (duration: 111.916292ms)","trace[756513810] 'applied index is now lower than readState.Index' (duration: 1.18584351s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:15:04.585913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:15:03.286882Z","time spent":"1.299026028s","remote":"127.0.0.1:48440","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T11:15:04.586156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298571685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-17T11:15:04.586269Z","caller":"traceutil/trace.go:171","msg":"trace[274623118] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1396; }","duration":"1.298712789s","start":"2026-07-17T11:15:03.287538Z","end":"2026-07-17T11:15:04.586251Z","steps":["trace[274623118] 'agreement among raft nodes before linearized reading' (duration: 1.298370389s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:15:04.586384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:15:03.287519Z","time spent":"1.298854883s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T11:15:04.586484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.794262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-17T11:15:04.586415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.581672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T11:15:04.58662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.13932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:15:04.586647Z","caller":"traceutil/trace.go:171","msg":"trace[1297265867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1396; }","duration":"433.991207ms","start":"2026-07-17T11:15:04.152634Z","end":"2026-07-17T11:15:04.586626Z","steps":["trace[1297265867] 'agreement among raft nodes before linearized reading' (duration: 433.74863ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:15:04.586678Z","caller":"traceutil/trace.go:171","msg":"trace[1539669630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1396; }","duration":"104.231532ms","start":"2026-07-17T11:15:04.482431Z","end":"2026-07-17T11:15:04.586663Z","steps":["trace[1539669630] 'agreement among raft nodes before linearized reading' (duration: 103.79877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:15:04.586715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:15:04.152608Z","time spent":"434.0904ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T11:15:04.586724Z","caller":"traceutil/trace.go:171","msg":"trace[1371730576] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1396; }","duration":"692.944493ms","start":"2026-07-17T11:15:03.893699Z","end":"2026-07-17T11:15:04.586643Z","steps":["trace[1371730576] 'agreement among raft nodes before linearized reading' (duration: 692.516621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:15:04.586484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.566843ms","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:530"} {"level":"info","ts":"2026-07-17T11:15:04.586857Z","caller":"traceutil/trace.go:171","msg":"trace[603637219] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1396; }","duration":"347.971784ms","start":"2026-07-17T11:15:04.238854Z","end":"2026-07-17T11:15:04.586826Z","steps":["trace[603637219] 'agreement among raft nodes before linearized reading' (duration: 347.46519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:15:04.586921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:15:04.238824Z","time spent":"348.078437ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-17T11:15:04.58692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:15:03.893682Z","time spent":"693.143428ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-17T11:18:02.219032Z","caller":"traceutil/trace.go:171","msg":"trace[342926529] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"223.007478ms","start":"2026-07-17T11:18:01.995995Z","end":"2026-07-17T11:18:02.219003Z","steps":["trace[342926529] 'process raft request' (duration: 222.839993ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:18:35.885657Z","caller":"traceutil/trace.go:171","msg":"trace[623196150] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"104.879195ms","start":"2026-07-17T11:18:35.780669Z","end":"2026-07-17T11:18:35.885549Z","steps":["trace[623196150] 'process raft request' (duration: 104.415982ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:18:36.039517Z","caller":"traceutil/trace.go:171","msg":"trace[601356267] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"148.200381ms","start":"2026-07-17T11:18:35.891289Z","end":"2026-07-17T11:18:36.039489Z","steps":["trace[601356267] 'process raft request' (duration: 148.115039ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:18:36.039522Z","caller":"traceutil/trace.go:171","msg":"trace[1151630225] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"148.236192ms","start":"2026-07-17T11:18:35.891256Z","end":"2026-07-17T11:18:36.039492Z","steps":["trace[1151630225] 'process raft request' (duration: 139.654257ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:18:36.039764Z","caller":"traceutil/trace.go:171","msg":"trace[1483388547] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"148.1596ms","start":"2026-07-17T11:18:35.891586Z","end":"2026-07-17T11:18:36.039745Z","steps":["trace[1483388547] 'process raft request' (duration: 148.037977ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:18:36.040026Z","caller":"traceutil/trace.go:171","msg":"trace[1529476321] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"148.550671ms","start":"2026-07-17T11:18:35.891465Z","end":"2026-07-17T11:18:36.040016Z","steps":["trace[1529476321] 'process raft request' (duration: 147.974405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:36.795203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232179749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:19:37.296407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824334034232179749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:19:37.374386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114972006s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:19:37.374837Z","caller":"traceutil/trace.go:171","msg":"trace[226996876] linearizableReadLoop","detail":"{readStateIndex:2777; appliedIndex:2776; }","duration":"1.079942156s","start":"2026-07-17T11:19:36.294873Z","end":"2026-07-17T11:19:37.374815Z","steps":["trace[226996876] 'read index received' (duration: 1.079657188s)","trace[226996876] 'applied index is now lower than readState.Index' (duration: 283.968µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:19:37.37493Z","caller":"traceutil/trace.go:171","msg":"trace[1550895143] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"1.115588154s","start":"2026-07-17T11:19:36.259298Z","end":"2026-07-17T11:19:37.374886Z","steps":["trace[1550895143] 'process raft request' (duration: 1.115363857s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080141131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T11:19:37.375084Z","caller":"traceutil/trace.go:171","msg":"trace[757413778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2667; }","duration":"1.080257564s","start":"2026-07-17T11:19:36.294817Z","end":"2026-07-17T11:19:37.375075Z","steps":["trace[757413778] 'agreement among raft nodes before linearized reading' (duration: 1.080079469s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.29479Z","time spent":"1.080324986s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:19:37.375119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.25927Z","time spent":"1.115747268s","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:19:37.375306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.00646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:19:37.375343Z","caller":"traceutil/trace.go:171","msg":"trace[1192986286] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2667; }","duration":"251.097283ms","start":"2026-07-17T11:19:37.124238Z","end":"2026-07-17T11:19:37.375335Z","steps":["trace[1192986286] 'agreement among raft nodes before linearized reading' (duration: 251.030521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.366687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-17T11:19:37.375382Z","caller":"traceutil/trace.go:171","msg":"trace[1450874225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2667; }","duration":"992.440189ms","start":"2026-07-17T11:19:36.382929Z","end":"2026-07-17T11:19:37.37537Z","steps":["trace[1450874225] 'agreement among raft nodes before linearized reading' (duration: 992.280655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.888586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T11:19:37.375475Z","caller":"traceutil/trace.go:171","msg":"trace[871113469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2667; }","duration":"937.972149ms","start":"2026-07-17T11:19:36.437492Z","end":"2026-07-17T11:19:37.375464Z","steps":["trace[871113469] 'agreement among raft nodes before linearized reading' (duration: 937.866196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.437478Z","time spent":"938.017329ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:19:37.375416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.382911Z","time spent":"992.497111ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:19:37.375309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.053763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-17T11:19:37.375427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.06805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:19:37.37574Z","caller":"traceutil/trace.go:171","msg":"trace[683368544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2667; }","duration":"824.43995ms","start":"2026-07-17T11:19:36.551268Z","end":"2026-07-17T11:19:37.375708Z","steps":["trace[683368544] 'agreement among raft nodes before linearized reading' (duration: 824.05118ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:19:37.375595Z","caller":"traceutil/trace.go:171","msg":"trace[971717700] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2667; }","duration":"937.356082ms","start":"2026-07-17T11:19:36.438232Z","end":"2026-07-17T11:19:37.375588Z","steps":["trace[971717700] 'agreement among raft nodes before linearized reading' (duration: 937.025663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.438224Z","time spent":"937.570498ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-17T11:19:37.375787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.551243Z","time spent":"824.534433ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:19:37.375652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.829778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:19:37.375864Z","caller":"traceutil/trace.go:171","msg":"trace[126499832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2667; }","duration":"963.083285ms","start":"2026-07-17T11:19:36.412769Z","end":"2026-07-17T11:19:37.375852Z","steps":["trace[126499832] 'agreement among raft nodes before linearized reading' (duration: 962.678244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:19:37.375906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:19:36.412751Z","time spent":"963.142116ms","remote":"127.0.0.1:48688","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\" "}