{"level":"warn","ts":"2026-07-27T20:11:51.106206Z","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-27T20:11:51.106324Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.12: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.12:2380","--initial-cluster=instance=https://199.19.213.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.12: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-27T20:11:51.106422Z","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-27T20:11:51.106444Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-07-27T20:11:51.106472Z","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-27T20:11:51.107445Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"]} {"level":"info","ts":"2026-07-27T20:11:51.107631Z","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.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.12: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-27T20:11:51.110759Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.870748ms"} {"level":"info","ts":"2026-07-27T20:11:51.116217Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"790561176553eafe","cluster-id":"e32c72c573d8545f"} {"level":"info","ts":"2026-07-27T20:11:51.116309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T20:11:51.116338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 0"} {"level":"info","ts":"2026-07-27T20:11:51.116351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 790561176553eafe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T20:11:51.116362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 1"} {"level":"info","ts":"2026-07-27T20:11:51.116406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"warn","ts":"2026-07-27T20:11:51.12587Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T20:11:51.128661Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T20:11:51.130388Z","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-27T20:11:51.133339Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"790561176553eafe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T20:11:51.133476Z","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-27T20:11:51.133559Z","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-27T20:11:51.133569Z","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-27T20:11:51.133933Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"790561176553eafe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T20:11:51.135925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"info","ts":"2026-07-27T20:11:51.136083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","added-peer-id":"790561176553eafe","added-peer-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-07-27T20:11:51.139285Z","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-27T20:11:51.139584Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"790561176553eafe","initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T20:11:51.139605Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T20:11:51.140108Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-07-27T20:11:51.140145Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-07-27T20:11:51.61722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T20:11:51.617295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T20:11:51.617324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgPreVoteResp from 790561176553eafe at term 1"} {"level":"info","ts":"2026-07-27T20:11:51.617345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became candidate at term 2"} {"level":"info","ts":"2026-07-27T20:11:51.617357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgVoteResp from 790561176553eafe at term 2"} {"level":"info","ts":"2026-07-27T20:11:51.617373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became leader at term 2"} {"level":"info","ts":"2026-07-27T20:11:51.617393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 790561176553eafe elected leader 790561176553eafe at term 2"} {"level":"info","ts":"2026-07-27T20:11:51.6231Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:11:51.62473Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:11:51.624807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:11:51.62483Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:11:51.624858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"790561176553eafe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.12:2379]}","request-path":"/0/members/790561176553eafe/attributes","cluster-id":"e32c72c573d8545f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T20:11:51.625071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:11:51.625686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T20:11:51.625716Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T20:11:51.625738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:11:51.628755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.12:2379"} {"level":"info","ts":"2026-07-27T20:11:51.629033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T20:12:09.494359Z","caller":"traceutil/trace.go:171","msg":"trace[937284683] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"105.679659ms","start":"2026-07-27T20:12:09.388661Z","end":"2026-07-27T20:12:09.494341Z","steps":["trace[937284683] 'process raft request' (duration: 101.46125ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:09.651912Z","caller":"traceutil/trace.go:171","msg":"trace[48547182] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"262.461355ms","start":"2026-07-27T20:12:09.389423Z","end":"2026-07-27T20:12:09.651884Z","steps":["trace[48547182] 'process raft request' (duration: 256.177369ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:11.045626Z","caller":"traceutil/trace.go:171","msg":"trace[1888708812] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"150.06231ms","start":"2026-07-27T20:12:10.895531Z","end":"2026-07-27T20:12:11.045594Z","steps":["trace[1888708812] 'process raft request' (duration: 149.845795ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:11.191113Z","caller":"traceutil/trace.go:171","msg":"trace[115190898] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"121.869205ms","start":"2026-07-27T20:12:11.069225Z","end":"2026-07-27T20:12:11.191095Z","steps":["trace[115190898] 'process raft request' (duration: 115.428165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:11.932193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.63948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:11.932323Z","caller":"traceutil/trace.go:171","msg":"trace[1801323196] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"273.020649ms","start":"2026-07-27T20:12:11.659284Z","end":"2026-07-27T20:12:11.932305Z","steps":["trace[1801323196] 'process raft request' (duration: 27.709654ms)","trace[1801323196] 'compare' (duration: 244.476316ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:14.016936Z","caller":"traceutil/trace.go:171","msg":"trace[610103850] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"176.951235ms","start":"2026-07-27T20:12:13.83996Z","end":"2026-07-27T20:12:14.016911Z","steps":["trace[610103850] 'process raft request' (duration: 176.592337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:16.436006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.842155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-27T20:12:16.436079Z","caller":"traceutil/trace.go:171","msg":"trace[1734567376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"377.000369ms","start":"2026-07-27T20:12:16.059061Z","end":"2026-07-27T20:12:16.436061Z","steps":["trace[1734567376] 'range keys from in-memory index tree' (duration: 376.708452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:16.436117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.05904Z","time spent":"377.06666ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:12:16.436151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.647519ms","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-27T20:12:16.436204Z","caller":"traceutil/trace.go:171","msg":"trace[1705875365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"845.740081ms","start":"2026-07-27T20:12:15.590448Z","end":"2026-07-27T20:12:16.436188Z","steps":["trace[1705875365] 'range keys from in-memory index tree' (duration: 845.514496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:16.436247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:15.590431Z","time spent":"845.807362ms","remote":"127.0.0.1:47894","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-27T20:12:16.436006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602896121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-27T20:12:16.436263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.220317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:16.4363Z","caller":"traceutil/trace.go:171","msg":"trace[382286829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"479.255517ms","start":"2026-07-27T20:12:15.957032Z","end":"2026-07-27T20:12:16.436287Z","steps":["trace[382286829] 'range keys from in-memory index tree' (duration: 479.105773ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:16.43631Z","caller":"traceutil/trace.go:171","msg":"trace[584578339] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:426; }","duration":"1.603229438s","start":"2026-07-27T20:12:14.833056Z","end":"2026-07-27T20:12:16.436285Z","steps":["trace[584578339] 'range keys from in-memory index tree' (duration: 1.602800819s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:16.436337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:15.956974Z","time spent":"479.354789ms","remote":"127.0.0.1:47666","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-27T20:12:16.436351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.229544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-27T20:12:16.436374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:14.833035Z","time spent":"1.603320401s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.12\" "} {"level":"info","ts":"2026-07-27T20:12:16.436386Z","caller":"traceutil/trace.go:171","msg":"trace[830626724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"963.295155ms","start":"2026-07-27T20:12:15.473081Z","end":"2026-07-27T20:12:16.436376Z","steps":["trace[830626724] 'range keys from in-memory index tree' (duration: 963.153451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:16.436413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:15.473057Z","time spent":"963.349646ms","remote":"127.0.0.1:47894","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-27T20:12:16.436066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139570134s","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-27T20:12:16.436811Z","caller":"traceutil/trace.go:171","msg":"trace[875245569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"1.140334242s","start":"2026-07-27T20:12:15.296451Z","end":"2026-07-27T20:12:16.436785Z","steps":["trace[875245569] 'range keys from in-memory index tree' (duration: 1.139479042s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:16.436898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:15.296403Z","time spent":"1.140468025s","remote":"127.0.0.1:47894","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-27T20:12:16.813023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.437758Z","time spent":"375.257178ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-27T20:12:17.170247Z","caller":"traceutil/trace.go:171","msg":"trace[77890522] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"729.726662ms","start":"2026-07-27T20:12:16.440484Z","end":"2026-07-27T20:12:17.170211Z","steps":["trace[77890522] 'process raft request' (duration: 685.177366ms)","trace[77890522] 'compare' (duration: 44.304031ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:17.170294Z","caller":"traceutil/trace.go:171","msg":"trace[207855097] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"729.716413ms","start":"2026-07-27T20:12:16.440556Z","end":"2026-07-27T20:12:17.170273Z","steps":["trace[207855097] 'process raft request' (duration: 729.58086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:17.170389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.440466Z","time spent":"729.849006ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:17.170406Z","caller":"traceutil/trace.go:171","msg":"trace[768824115] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"729.310823ms","start":"2026-07-27T20:12:16.441083Z","end":"2026-07-27T20:12:17.170394Z","steps":["trace[768824115] 'process raft request' (duration: 729.151529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:17.170445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.440549Z","time spent":"729.806215ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:17.17047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.441073Z","time spent":"729.368604ms","remote":"127.0.0.1:47894","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-27T20:12:17.170679Z","caller":"traceutil/trace.go:171","msg":"trace[344937068] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"729.261441ms","start":"2026-07-27T20:12:16.441404Z","end":"2026-07-27T20:12:17.170665Z","steps":["trace[344937068] 'process raft request' (duration: 728.942683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:17.170737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.441394Z","time spent":"729.311413ms","remote":"127.0.0.1:47894","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-27T20:12:17.178001Z","caller":"traceutil/trace.go:171","msg":"trace[1270106004] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:438; }","duration":"220.694143ms","start":"2026-07-27T20:12:16.957282Z","end":"2026-07-27T20:12:17.177977Z","steps":["trace[1270106004] 'read index received' (duration: 168.381906ms)","trace[1270106004] 'applied index is now lower than readState.Index' (duration: 52.310827ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:17.178044Z","caller":"traceutil/trace.go:171","msg":"trace[1088486209] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"363.264689ms","start":"2026-07-27T20:12:16.814756Z","end":"2026-07-27T20:12:17.17802Z","steps":["trace[1088486209] 'process raft request' (duration: 363.071345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:17.178115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.829816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:17.178154Z","caller":"traceutil/trace.go:171","msg":"trace[1507218366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"220.910928ms","start":"2026-07-27T20:12:16.957235Z","end":"2026-07-27T20:12:17.178145Z","steps":["trace[1507218366] 'agreement among raft nodes before linearized reading' (duration: 220.821126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:17.178216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:16.814692Z","time spent":"363.457683ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:17.554809Z","caller":"traceutil/trace.go:171","msg":"trace[1871311216] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"226.612251ms","start":"2026-07-27T20:12:17.328169Z","end":"2026-07-27T20:12:17.554781Z","steps":["trace[1871311216] 'process raft request' (duration: 226.438797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:17.928819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.610165ms","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-27T20:12:17.928984Z","caller":"traceutil/trace.go:171","msg":"trace[1792045347] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:432; }","duration":"206.786789ms","start":"2026-07-27T20:12:17.72218Z","end":"2026-07-27T20:12:17.928966Z","steps":["trace[1792045347] 'range keys from in-memory index tree' (duration: 206.595055ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:20.411998Z","caller":"traceutil/trace.go:171","msg":"trace[1135619504] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"226.52984ms","start":"2026-07-27T20:12:20.185444Z","end":"2026-07-27T20:12:20.411974Z","steps":["trace[1135619504] 'process raft request' (duration: 226.448518ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:20.412425Z","caller":"traceutil/trace.go:171","msg":"trace[1347717263] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"230.244706ms","start":"2026-07-27T20:12:20.182168Z","end":"2026-07-27T20:12:20.412413Z","steps":["trace[1347717263] 'process raft request' (duration: 196.237375ms)","trace[1347717263] 'compare' (duration: 33.335925ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:20.571665Z","caller":"traceutil/trace.go:171","msg":"trace[334897456] transaction","detail":"{read_only:false; number_of_response:1; response_revision:452; }","duration":"118.863624ms","start":"2026-07-27T20:12:20.452776Z","end":"2026-07-27T20:12:20.571639Z","steps":["trace[334897456] 'process raft request' (duration: 118.819933ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:20.571731Z","caller":"traceutil/trace.go:171","msg":"trace[1124984873] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"120.308419ms","start":"2026-07-27T20:12:20.451385Z","end":"2026-07-27T20:12:20.571693Z","steps":["trace[1124984873] 'process raft request' (duration: 120.152105ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:20.571964Z","caller":"traceutil/trace.go:171","msg":"trace[1733109522] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"123.072293ms","start":"2026-07-27T20:12:20.448864Z","end":"2026-07-27T20:12:20.571936Z","steps":["trace[1733109522] 'process raft request' (duration: 95.986024ms)","trace[1733109522] 'compare' (duration: 26.549187ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:20.880781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.284582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:20.881038Z","caller":"traceutil/trace.go:171","msg":"trace[185470096] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"299.832404ms","start":"2026-07-27T20:12:20.581191Z","end":"2026-07-27T20:12:20.881023Z","steps":["trace[185470096] 'process raft request' (duration: 101.241705ms)","trace[185470096] 'compare' (duration: 198.185918ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:20.881246Z","caller":"traceutil/trace.go:171","msg":"trace[2093894138] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"226.462828ms","start":"2026-07-27T20:12:20.654775Z","end":"2026-07-27T20:12:20.881238Z","steps":["trace[2093894138] 'process raft request' (duration: 226.082499ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:20.881464Z","caller":"traceutil/trace.go:171","msg":"trace[488483163] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"226.57168ms","start":"2026-07-27T20:12:20.654875Z","end":"2026-07-27T20:12:20.881447Z","steps":["trace[488483163] 'process raft request' (duration: 226.322345ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:20.881482Z","caller":"traceutil/trace.go:171","msg":"trace[1137848328] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"223.350385ms","start":"2026-07-27T20:12:20.658096Z","end":"2026-07-27T20:12:20.881446Z","steps":["trace[1137848328] 'process raft request' (duration: 223.237363ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:21.404553Z","caller":"traceutil/trace.go:171","msg":"trace[608207462] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"152.479106ms","start":"2026-07-27T20:12:21.252049Z","end":"2026-07-27T20:12:21.404528Z","steps":["trace[608207462] 'process raft request' (duration: 152.429935ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:21.404604Z","caller":"traceutil/trace.go:171","msg":"trace[1111070576] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"152.705771ms","start":"2026-07-27T20:12:21.25187Z","end":"2026-07-27T20:12:21.404575Z","steps":["trace[1111070576] 'process raft request' (duration: 80.938012ms)","trace[1111070576] 'compare' (duration: 71.486122ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:21.404628Z","caller":"traceutil/trace.go:171","msg":"trace[1068143795] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"152.588098ms","start":"2026-07-27T20:12:21.252021Z","end":"2026-07-27T20:12:21.404609Z","steps":["trace[1068143795] 'process raft request' (duration: 152.415965ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:23.436738Z","caller":"traceutil/trace.go:171","msg":"trace[2042230226] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"150.612263ms","start":"2026-07-27T20:12:23.286092Z","end":"2026-07-27T20:12:23.436703Z","steps":["trace[2042230226] 'process raft request' (duration: 150.202374ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:24.07853Z","caller":"traceutil/trace.go:171","msg":"trace[156497500] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"637.631191ms","start":"2026-07-27T20:12:23.440881Z","end":"2026-07-27T20:12:24.078512Z","steps":["trace[156497500] 'process raft request' (duration: 637.539559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:24.078647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:23.440847Z","time spent":"637.739583ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:25.123853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.926294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:25.124104Z","caller":"traceutil/trace.go:171","msg":"trace[1588244861] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.682659646s","start":"2026-07-27T20:12:23.441406Z","end":"2026-07-27T20:12:25.124065Z","steps":["trace[1588244861] 'process raft request' (duration: 1.682554103s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:25.124204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:23.441378Z","time spent":"1.682777719s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:25.12443Z","caller":"traceutil/trace.go:171","msg":"trace[805648944] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.683048734s","start":"2026-07-27T20:12:23.441354Z","end":"2026-07-27T20:12:25.124403Z","steps":["trace[805648944] 'process raft request' (duration: 1.094474015s)","trace[805648944] 'compare' (duration: 587.469484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:25.124566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:23.441342Z","time spent":"1.683152086s","remote":"127.0.0.1:47894","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-27T20:12:25.285069Z","caller":"traceutil/trace.go:171","msg":"trace[1067748119] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"451.466141ms","start":"2026-07-27T20:12:24.833581Z","end":"2026-07-27T20:12:25.285048Z","steps":["trace[1067748119] 'read index received' (duration: 442.551794ms)","trace[1067748119] 'applied index is now lower than readState.Index' (duration: 8.913457ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:25.285357Z","caller":"traceutil/trace.go:171","msg":"trace[252026601] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"934.66202ms","start":"2026-07-27T20:12:24.350666Z","end":"2026-07-27T20:12:25.285328Z","steps":["trace[252026601] 'process raft request' (duration: 925.543818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:25.285681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:24.350636Z","time spent":"934.968926ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:25.285401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.806848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-27T20:12:25.285733Z","caller":"traceutil/trace.go:171","msg":"trace[1857949055] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:475; }","duration":"452.173446ms","start":"2026-07-27T20:12:24.83355Z","end":"2026-07-27T20:12:25.285723Z","steps":["trace[1857949055] 'agreement among raft nodes before linearized reading' (duration: 451.804868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:25.285778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:24.833534Z","time spent":"452.226698ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.12\" "} {"level":"warn","ts":"2026-07-27T20:12:25.285742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.771221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:25.28595Z","caller":"traceutil/trace.go:171","msg":"trace[1916701423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"330.019797ms","start":"2026-07-27T20:12:24.955901Z","end":"2026-07-27T20:12:25.285921Z","steps":["trace[1916701423] 'agreement among raft nodes before linearized reading' (duration: 329.74576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:25.28603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:24.955878Z","time spent":"330.135029ms","remote":"127.0.0.1:47666","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-27T20:12:27.267782Z","caller":"traceutil/trace.go:171","msg":"trace[1238555341] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"134.742074ms","start":"2026-07-27T20:12:27.133017Z","end":"2026-07-27T20:12:27.267759Z","steps":["trace[1238555341] 'process raft request' (duration: 134.613371ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:33.377418Z","caller":"traceutil/trace.go:171","msg":"trace[1758961452] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"111.614565ms","start":"2026-07-27T20:12:33.265776Z","end":"2026-07-27T20:12:33.377391Z","steps":["trace[1758961452] 'read index received' (duration: 111.486542ms)","trace[1758961452] 'applied index is now lower than readState.Index' (duration: 126.683µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:33.377577Z","caller":"traceutil/trace.go:171","msg":"trace[845725588] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"114.193705ms","start":"2026-07-27T20:12:33.263372Z","end":"2026-07-27T20:12:33.377566Z","steps":["trace[845725588] 'process raft request' (duration: 113.855518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:33.377794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.000065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:33.377856Z","caller":"traceutil/trace.go:171","msg":"trace[317227717] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:526; }","duration":"112.116138ms","start":"2026-07-27T20:12:33.265728Z","end":"2026-07-27T20:12:33.377844Z","steps":["trace[317227717] 'agreement among raft nodes before linearized reading' (duration: 112.009375ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.238827Z","caller":"traceutil/trace.go:171","msg":"trace[376816881] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"520.569148ms","start":"2026-07-27T20:12:33.718232Z","end":"2026-07-27T20:12:34.238801Z","steps":["trace[376816881] 'process raft request' (duration: 520.407805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.239011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.71821Z","time spent":"520.698911ms","remote":"127.0.0.1:47722","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-27T20:12:34.303217Z","caller":"traceutil/trace.go:171","msg":"trace[1623618796] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"573.760816ms","start":"2026-07-27T20:12:33.729409Z","end":"2026-07-27T20:12:34.30317Z","steps":["trace[1623618796] 'process raft request' (duration: 573.540181ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.303283Z","caller":"traceutil/trace.go:171","msg":"trace[658193512] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"583.910572ms","start":"2026-07-27T20:12:33.719229Z","end":"2026-07-27T20:12:34.303139Z","steps":["trace[658193512] 'process raft request' (duration: 583.506093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.303357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.729396Z","time spent":"573.902159ms","remote":"127.0.0.1:47722","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-27T20:12:34.303428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.719209Z","time spent":"584.144107ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.30358Z","caller":"traceutil/trace.go:171","msg":"trace[1586801298] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"573.612422ms","start":"2026-07-27T20:12:33.729957Z","end":"2026-07-27T20:12:34.30357Z","steps":["trace[1586801298] 'process raft request' (duration: 573.066289ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.304189Z","caller":"traceutil/trace.go:171","msg":"trace[758109913] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"569.496276ms","start":"2026-07-27T20:12:33.734022Z","end":"2026-07-27T20:12:34.303519Z","steps":["trace[758109913] 'process raft request' (duration: 569.170968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.304261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.729951Z","time spent":"574.275127ms","remote":"127.0.0.1:47722","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-27T20:12:34.304318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.733998Z","time spent":"570.259644ms","remote":"127.0.0.1:47722","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-27T20:12:34.303543Z","caller":"traceutil/trace.go:171","msg":"trace[377071244] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"571.587994ms","start":"2026-07-27T20:12:33.731907Z","end":"2026-07-27T20:12:34.303495Z","steps":["trace[377071244] 'process raft request' (duration: 571.228436ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.304637Z","caller":"traceutil/trace.go:171","msg":"trace[398384248] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"560.991578ms","start":"2026-07-27T20:12:33.742538Z","end":"2026-07-27T20:12:34.303529Z","steps":["trace[398384248] 'process raft request' (duration: 560.715042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.304659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.731901Z","time spent":"572.735711ms","remote":"127.0.0.1:47722","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-27T20:12:34.304729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.742529Z","time spent":"562.141134ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.30482Z","caller":"traceutil/trace.go:171","msg":"trace[1819038681] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"573.024389ms","start":"2026-07-27T20:12:33.730547Z","end":"2026-07-27T20:12:34.303571Z","steps":["trace[1819038681] 'process raft request' (duration: 572.535368ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.304906Z","caller":"traceutil/trace.go:171","msg":"trace[2043300248] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"584.223329ms","start":"2026-07-27T20:12:33.719401Z","end":"2026-07-27T20:12:34.303625Z","steps":["trace[2043300248] 'process raft request' (duration: 583.489932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.304976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.730529Z","time spent":"574.354769ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:12:34.305018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.719395Z","time spent":"585.590731ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.305317Z","caller":"traceutil/trace.go:171","msg":"trace[166160352] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"516.943784ms","start":"2026-07-27T20:12:33.78835Z","end":"2026-07-27T20:12:34.305294Z","steps":["trace[166160352] 'process raft request' (duration: 515.184203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.305358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.788328Z","time spent":"517.012155ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.304164Z","caller":"traceutil/trace.go:171","msg":"trace[2062496367] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"537.447531ms","start":"2026-07-27T20:12:33.766127Z","end":"2026-07-27T20:12:34.303574Z","steps":["trace[2062496367] 'process raft request' (duration: 537.186445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.30555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.76612Z","time spent":"539.377356ms","remote":"127.0.0.1:47722","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-27T20:12:34.307896Z","caller":"traceutil/trace.go:171","msg":"trace[1014341879] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:544; }","duration":"350.535044ms","start":"2026-07-27T20:12:33.957319Z","end":"2026-07-27T20:12:34.307854Z","steps":["trace[1014341879] 'read index received' (duration: 281.415686ms)","trace[1014341879] 'applied index is now lower than readState.Index' (duration: 69.118518ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:34.307981Z","caller":"traceutil/trace.go:171","msg":"trace[1187376818] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"397.577258ms","start":"2026-07-27T20:12:33.910396Z","end":"2026-07-27T20:12:34.307974Z","steps":["trace[1187376818] 'process raft request' (duration: 397.334851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.308109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.910368Z","time spent":"397.634209ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:12:34.309575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.254423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:34.309651Z","caller":"traceutil/trace.go:171","msg":"trace[803733249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"352.325465ms","start":"2026-07-27T20:12:33.957299Z","end":"2026-07-27T20:12:34.309624Z","steps":["trace[803733249] 'agreement among raft nodes before linearized reading' (duration: 351.569388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.309695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:33.957237Z","time spent":"352.449838ms","remote":"127.0.0.1:47666","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-27T20:12:34.61398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.48089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:34.614476Z","caller":"traceutil/trace.go:171","msg":"trace[450424933] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"369.842872ms","start":"2026-07-27T20:12:34.244608Z","end":"2026-07-27T20:12:34.614451Z","steps":["trace[450424933] 'process raft request' (duration: 77.781129ms)","trace[450424933] 'compare' (duration: 291.333867ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:34.614599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.244587Z","time spent":"369.974125ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.615188Z","caller":"traceutil/trace.go:171","msg":"trace[1263454174] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"367.781724ms","start":"2026-07-27T20:12:34.247394Z","end":"2026-07-27T20:12:34.615176Z","steps":["trace[1263454174] 'process raft request' (duration: 366.991986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.615274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.247383Z","time spent":"367.844675ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:34.615339Z","caller":"traceutil/trace.go:171","msg":"trace[129096076] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"367.724583ms","start":"2026-07-27T20:12:34.247573Z","end":"2026-07-27T20:12:34.615297Z","steps":["trace[129096076] 'process raft request' (duration: 367.495668ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.615388Z","caller":"traceutil/trace.go:171","msg":"trace[1384113036] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:556; }","duration":"307.408919ms","start":"2026-07-27T20:12:34.307937Z","end":"2026-07-27T20:12:34.615346Z","steps":["trace[1384113036] 'read index received' (duration: 14.460246ms)","trace[1384113036] 'applied index is now lower than readState.Index' (duration: 292.941023ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:34.615561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.051231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-27T20:12:34.61559Z","caller":"traceutil/trace.go:171","msg":"trace[167920062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"473.121524ms","start":"2026-07-27T20:12:34.142461Z","end":"2026-07-27T20:12:34.615583Z","steps":["trace[167920062] 'agreement among raft nodes before linearized reading' (duration: 473.004132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.615612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.142445Z","time spent":"473.162095ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-27T20:12:34.615743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.247548Z","time spent":"367.944187ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:34.616419Z","caller":"traceutil/trace.go:171","msg":"trace[1440403576] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"304.261207ms","start":"2026-07-27T20:12:34.312143Z","end":"2026-07-27T20:12:34.616404Z","steps":["trace[1440403576] 'process raft request' (duration: 304.059482ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.616446Z","caller":"traceutil/trace.go:171","msg":"trace[1567371468] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"299.440354ms","start":"2026-07-27T20:12:34.316976Z","end":"2026-07-27T20:12:34.616416Z","steps":["trace[1567371468] 'process raft request' (duration: 299.347652ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.616491Z","caller":"traceutil/trace.go:171","msg":"trace[252193475] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"300.332676ms","start":"2026-07-27T20:12:34.316145Z","end":"2026-07-27T20:12:34.616478Z","steps":["trace[252193475] 'process raft request' (duration: 300.104971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.616507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.312112Z","time spent":"304.35478ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:12:34.616553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.316124Z","time spent":"300.393558ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.616536Z","caller":"traceutil/trace.go:171","msg":"trace[986066268] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"305.363713ms","start":"2026-07-27T20:12:34.311157Z","end":"2026-07-27T20:12:34.616521Z","steps":["trace[986066268] 'process raft request' (duration: 304.857471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.619135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.311132Z","time spent":"307.967133ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.675868Z","caller":"traceutil/trace.go:171","msg":"trace[145873928] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"353.522423ms","start":"2026-07-27T20:12:34.322324Z","end":"2026-07-27T20:12:34.675846Z","steps":["trace[145873928] 'process raft request' (duration: 353.193565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.676031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.322307Z","time spent":"353.640085ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:34.67621Z","caller":"traceutil/trace.go:171","msg":"trace[1212874103] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"351.847884ms","start":"2026-07-27T20:12:34.32432Z","end":"2026-07-27T20:12:34.676168Z","steps":["trace[1212874103] 'process raft request' (duration: 351.454385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.676319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.324304Z","time spent":"351.964627ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:12:34.676522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.634728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:34.676585Z","caller":"traceutil/trace.go:171","msg":"trace[1870465957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"363.74365ms","start":"2026-07-27T20:12:34.312833Z","end":"2026-07-27T20:12:34.676576Z","steps":["trace[1870465957] 'agreement among raft nodes before linearized reading' (duration: 363.601187ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.676589Z","caller":"traceutil/trace.go:171","msg":"trace[1570483410] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"350.004141ms","start":"2026-07-27T20:12:34.326576Z","end":"2026-07-27T20:12:34.67658Z","steps":["trace[1570483410] 'process raft request' (duration: 349.230974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.676612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.312807Z","time spent":"363.797942ms","remote":"127.0.0.1:47660","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-27T20:12:34.676664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.326565Z","time spent":"350.053052ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:34.676721Z","caller":"traceutil/trace.go:171","msg":"trace[309291807] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"300.779375ms","start":"2026-07-27T20:12:34.375923Z","end":"2026-07-27T20:12:34.676702Z","steps":["trace[309291807] 'process raft request' (duration: 300.461698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.676815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.375885Z","time spent":"300.888588ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:34.676545Z","caller":"traceutil/trace.go:171","msg":"trace[825893366] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"346.808566ms","start":"2026-07-27T20:12:34.329724Z","end":"2026-07-27T20:12:34.676533Z","steps":["trace[825893366] 'process raft request' (duration: 346.55134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:34.676897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:34.329712Z","time spent":"347.152504ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:34.775442Z","caller":"traceutil/trace.go:171","msg":"trace[672756670] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"156.0961ms","start":"2026-07-27T20:12:34.619322Z","end":"2026-07-27T20:12:34.775418Z","steps":["trace[672756670] 'process raft request' (duration: 126.599164ms)","trace[672756670] 'compare' (duration: 28.702068ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:34.775638Z","caller":"traceutil/trace.go:171","msg":"trace[427804369] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"146.655112ms","start":"2026-07-27T20:12:34.628948Z","end":"2026-07-27T20:12:34.775603Z","steps":["trace[427804369] 'process raft request' (duration: 146.300913ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.775775Z","caller":"traceutil/trace.go:171","msg":"trace[113287085] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"136.164157ms","start":"2026-07-27T20:12:34.639581Z","end":"2026-07-27T20:12:34.775745Z","steps":["trace[113287085] 'process raft request' (duration: 135.754627ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:34.775988Z","caller":"traceutil/trace.go:171","msg":"trace[1688540576] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"125.478409ms","start":"2026-07-27T20:12:34.650497Z","end":"2026-07-27T20:12:34.775976Z","steps":["trace[1688540576] 'process raft request' (duration: 124.917165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:35.625678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.87821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:35.625762Z","caller":"traceutil/trace.go:171","msg":"trace[709192331] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"206.415911ms","start":"2026-07-27T20:12:35.419335Z","end":"2026-07-27T20:12:35.625751Z","steps":["trace[709192331] 'process raft request' (duration: 64.413348ms)","trace[709192331] 'compare' (duration: 141.785058ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:35.642223Z","caller":"traceutil/trace.go:171","msg":"trace[537114078] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"198.434506ms","start":"2026-07-27T20:12:35.443768Z","end":"2026-07-27T20:12:35.642203Z","steps":["trace[537114078] 'process raft request' (duration: 198.296802ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:35.813544Z","caller":"traceutil/trace.go:171","msg":"trace[1751618404] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"297.004248ms","start":"2026-07-27T20:12:35.516519Z","end":"2026-07-27T20:12:35.813524Z","steps":["trace[1751618404] 'read index received' (duration: 125.55118ms)","trace[1751618404] 'applied index is now lower than readState.Index' (duration: 171.451958ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:35.813736Z","caller":"traceutil/trace.go:171","msg":"trace[806282256] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"298.112343ms","start":"2026-07-27T20:12:35.515576Z","end":"2026-07-27T20:12:35.813688Z","steps":["trace[806282256] 'process raft request' (duration: 295.920242ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:35.813838Z","caller":"traceutil/trace.go:171","msg":"trace[929243920] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"181.952052ms","start":"2026-07-27T20:12:35.631843Z","end":"2026-07-27T20:12:35.813795Z","steps":["trace[929243920] 'process raft request' (duration: 181.631455ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:35.813773Z","caller":"traceutil/trace.go:171","msg":"trace[1987973570] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"282.106171ms","start":"2026-07-27T20:12:35.531603Z","end":"2026-07-27T20:12:35.81371Z","steps":["trace[1987973570] 'process raft request' (duration: 281.682732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:35.814941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.404221ms","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-27T20:12:35.815016Z","caller":"traceutil/trace.go:171","msg":"trace[1508795679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"298.494843ms","start":"2026-07-27T20:12:35.516487Z","end":"2026-07-27T20:12:35.814982Z","steps":["trace[1508795679] 'agreement among raft nodes before linearized reading' (duration: 297.11569ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:35.932242Z","caller":"traceutil/trace.go:171","msg":"trace[1647549099] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"268.194087ms","start":"2026-07-27T20:12:35.664029Z","end":"2026-07-27T20:12:35.932223Z","steps":["trace[1647549099] 'process raft request' (duration: 255.383819ms)","trace[1647549099] 'compare' (duration: 12.01711ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:35.932655Z","caller":"traceutil/trace.go:171","msg":"trace[642063323] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"112.455326ms","start":"2026-07-27T20:12:35.820191Z","end":"2026-07-27T20:12:35.932646Z","steps":["trace[642063323] 'process raft request' (duration: 111.8041ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:37.040521Z","caller":"traceutil/trace.go:171","msg":"trace[377555923] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"256.764672ms","start":"2026-07-27T20:12:36.783734Z","end":"2026-07-27T20:12:37.040498Z","steps":["trace[377555923] 'process raft request' (duration: 256.635309ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:37.083631Z","caller":"traceutil/trace.go:171","msg":"trace[1573866130] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"126.685396ms","start":"2026-07-27T20:12:36.95692Z","end":"2026-07-27T20:12:37.083605Z","steps":["trace[1573866130] 'read index received' (duration: 83.526623ms)","trace[1573866130] 'applied index is now lower than readState.Index' (duration: 43.158073ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:37.083689Z","caller":"traceutil/trace.go:171","msg":"trace[1280862711] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"222.435614ms","start":"2026-07-27T20:12:36.861234Z","end":"2026-07-27T20:12:37.08367Z","steps":["trace[1280862711] 'process raft request' (duration: 222.26819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:37.083792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.85975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:37.083869Z","caller":"traceutil/trace.go:171","msg":"trace[163686656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"126.981993ms","start":"2026-07-27T20:12:36.956872Z","end":"2026-07-27T20:12:37.083854Z","steps":["trace[163686656] 'agreement among raft nodes before linearized reading' (duration: 126.83119ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:37.16675Z","caller":"traceutil/trace.go:171","msg":"trace[1249336351] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"105.376672ms","start":"2026-07-27T20:12:37.061342Z","end":"2026-07-27T20:12:37.166718Z","steps":["trace[1249336351] 'process raft request' (duration: 105.134136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:37.166887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.424789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-bl2tf\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-07-27T20:12:37.166951Z","caller":"traceutil/trace.go:171","msg":"trace[264554562] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-bl2tf; range_end:; response_count:1; response_revision:628; }","duration":"150.533182ms","start":"2026-07-27T20:12:37.016404Z","end":"2026-07-27T20:12:37.166938Z","steps":["trace[264554562] 'agreement among raft nodes before linearized reading' (duration: 150.322087ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:37.232699Z","caller":"traceutil/trace.go:171","msg":"trace[1975586955] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"139.957525ms","start":"2026-07-27T20:12:37.092704Z","end":"2026-07-27T20:12:37.232662Z","steps":["trace[1975586955] 'process raft request' (duration: 139.776971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:37.233278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.160816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:37.233321Z","caller":"traceutil/trace.go:171","msg":"trace[2121496916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"148.237498ms","start":"2026-07-27T20:12:37.085068Z","end":"2026-07-27T20:12:37.233306Z","steps":["trace[2121496916] 'agreement among raft nodes before linearized reading' (duration: 148.168466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:37.893118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147180859379993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:12:38.393734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147180859379993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:12:38.894491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147180859379993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:12:38.971413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24941568s","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-27T20:12:38.971619Z","caller":"traceutil/trace.go:171","msg":"trace[1003138260] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:632; }","duration":"1.249612154s","start":"2026-07-27T20:12:37.721964Z","end":"2026-07-27T20:12:38.971576Z","steps":["trace[1003138260] 'range keys from in-memory index tree' (duration: 1.249390979s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.006438Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.734556344s","expected-duration":"1s"} {"level":"info","ts":"2026-07-27T20:12:39.006809Z","caller":"traceutil/trace.go:171","msg":"trace[1690814075] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.742606191s","start":"2026-07-27T20:12:37.264174Z","end":"2026-07-27T20:12:39.00678Z","steps":["trace[1690814075] 'process raft request' (duration: 1.742455167s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.006952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.264162Z","time spent":"1.742726844s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:39.011076Z","caller":"traceutil/trace.go:171","msg":"trace[440553881] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:650; }","duration":"1.618406571s","start":"2026-07-27T20:12:37.392651Z","end":"2026-07-27T20:12:39.011058Z","steps":["trace[440553881] 'read index received' (duration: 1.614055991s)","trace[440553881] 'applied index is now lower than readState.Index' (duration: 4.34993ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:39.011252Z","caller":"traceutil/trace.go:171","msg":"trace[1650609046] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.732887364s","start":"2026-07-27T20:12:37.278356Z","end":"2026-07-27T20:12:39.011243Z","steps":["trace[1650609046] 'process raft request' (duration: 1.732480494s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.011331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.278337Z","time spent":"1.732939126s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:39.011508Z","caller":"traceutil/trace.go:171","msg":"trace[1909562383] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.73221221s","start":"2026-07-27T20:12:37.279289Z","end":"2026-07-27T20:12:39.011501Z","steps":["trace[1909562383] 'process raft request' (duration: 1.731634145s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.011549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.279282Z","time spent":"1.73224661s","remote":"127.0.0.1:47842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4675,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:39.01163Z","caller":"traceutil/trace.go:171","msg":"trace[1983755522] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.54868586s","start":"2026-07-27T20:12:37.462937Z","end":"2026-07-27T20:12:39.011623Z","steps":["trace[1983755522] 'process raft request' (duration: 1.548025494s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.011669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.462915Z","time spent":"1.548734972s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:39.011756Z","caller":"traceutil/trace.go:171","msg":"trace[218688503] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.351068885s","start":"2026-07-27T20:12:37.66068Z","end":"2026-07-27T20:12:39.011749Z","steps":["trace[218688503] 'process raft request' (duration: 1.350310047s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.011797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.660653Z","time spent":"1.351126756s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:39.011889Z","caller":"traceutil/trace.go:171","msg":"trace[627978253] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.146785223s","start":"2026-07-27T20:12:37.865098Z","end":"2026-07-27T20:12:39.011883Z","steps":["trace[627978253] 'process raft request' (duration: 1.145910452s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.011923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.865078Z","time spent":"1.146831075s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:12:39.01207Z","caller":"traceutil/trace.go:171","msg":"trace[842294414] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"938.243802ms","start":"2026-07-27T20:12:38.07382Z","end":"2026-07-27T20:12:39.012064Z","steps":["trace[842294414] 'process raft request' (duration: 937.207698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.073802Z","time spent":"938.282614ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:39.012291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61963485s","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-27T20:12:39.012339Z","caller":"traceutil/trace.go:171","msg":"trace[46411814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.619724532s","start":"2026-07-27T20:12:37.392601Z","end":"2026-07-27T20:12:39.012325Z","steps":["trace[46411814] 'agreement among raft nodes before linearized reading' (duration: 1.619550498s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.392581Z","time spent":"1.619785514s","remote":"127.0.0.1:47894","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-27T20:12:39.012366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596180866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-27T20:12:39.012399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.264994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-27T20:12:39.012441Z","caller":"traceutil/trace.go:171","msg":"trace[2085104772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.596292568s","start":"2026-07-27T20:12:37.41613Z","end":"2026-07-27T20:12:39.012422Z","steps":["trace[2085104772] 'agreement among raft nodes before linearized reading' (duration: 1.596064292s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:39.012472Z","caller":"traceutil/trace.go:171","msg":"trace[1074218219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:639; }","duration":"739.345506ms","start":"2026-07-27T20:12:38.273106Z","end":"2026-07-27T20:12:39.012451Z","steps":["trace[1074218219] 'agreement among raft nodes before linearized reading' (duration: 739.121221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.416118Z","time spent":"1.596372599s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:12:39.012515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.273031Z","time spent":"739.47508ms","remote":"127.0.0.1:47894","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":"warn","ts":"2026-07-27T20:12:39.012529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.679187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"warn","ts":"2026-07-27T20:12:39.012532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.113148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-27T20:12:39.012564Z","caller":"traceutil/trace.go:171","msg":"trace[1115340382] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:639; }","duration":"516.74669ms","start":"2026-07-27T20:12:38.495806Z","end":"2026-07-27T20:12:39.012552Z","steps":["trace[1115340382] 'agreement among raft nodes before linearized reading' (duration: 516.649987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.577122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-07-27T20:12:39.01241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.428787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"warn","ts":"2026-07-27T20:12:39.012594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.495789Z","time spent":"516.79918ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-27T20:12:39.012591Z","caller":"traceutil/trace.go:171","msg":"trace[1269958617] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"152.162859ms","start":"2026-07-27T20:12:38.860408Z","end":"2026-07-27T20:12:39.012571Z","steps":["trace[1269958617] 'agreement among raft nodes before linearized reading' (duration: 152.040246ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:39.012633Z","caller":"traceutil/trace.go:171","msg":"trace[484596339] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"352.694033ms","start":"2026-07-27T20:12:38.659925Z","end":"2026-07-27T20:12:39.012619Z","steps":["trace[484596339] 'agreement among raft nodes before linearized reading' (duration: 352.326955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054722912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:12:39.012642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.617805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:39.012664Z","caller":"traceutil/trace.go:171","msg":"trace[1022249976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.054814424s","start":"2026-07-27T20:12:37.957843Z","end":"2026-07-27T20:12:39.012658Z","steps":["trace[1022249976] 'agreement among raft nodes before linearized reading' (duration: 1.054785933s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.659903Z","time spent":"352.754534ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-07-27T20:12:39.012682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.957822Z","time spent":"1.054856545s","remote":"127.0.0.1:47666","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-27T20:12:39.012682Z","caller":"traceutil/trace.go:171","msg":"trace[1331269870] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"752.677086ms","start":"2026-07-27T20:12:38.259989Z","end":"2026-07-27T20:12:39.012666Z","steps":["trace[1331269870] 'agreement among raft nodes before linearized reading' (duration: 752.627625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.259969Z","time spent":"752.734088ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-27T20:12:39.012606Z","caller":"traceutil/trace.go:171","msg":"trace[1842012812] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"552.641044ms","start":"2026-07-27T20:12:38.459956Z","end":"2026-07-27T20:12:39.012597Z","steps":["trace[1842012812] 'agreement among raft nodes before linearized reading' (duration: 552.559112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.024886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:39.012785Z","caller":"traceutil/trace.go:171","msg":"trace[106873359] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"944.079208ms","start":"2026-07-27T20:12:38.068699Z","end":"2026-07-27T20:12:39.012778Z","steps":["trace[106873359] 'agreement among raft nodes before linearized reading' (duration: 944.052947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063051855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-27T20:12:39.012803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.068679Z","time spent":"944.118299ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-27T20:12:39.012816Z","caller":"traceutil/trace.go:171","msg":"trace[1841721248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"1.063118966s","start":"2026-07-27T20:12:37.949688Z","end":"2026-07-27T20:12:39.012807Z","steps":["trace[1841721248] 'agreement among raft nodes before linearized reading' (duration: 1.063039505s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:39.012771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:38.459935Z","time spent":"552.828917ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-07-27T20:12:39.012854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:37.949668Z","time spent":"1.063179927s","remote":"127.0.0.1:47894","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-27T20:12:39.762834Z","caller":"traceutil/trace.go:171","msg":"trace[1734642916] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"123.558074ms","start":"2026-07-27T20:12:39.639255Z","end":"2026-07-27T20:12:39.762813Z","steps":["trace[1734642916] 'process raft request' (duration: 123.38123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:40.966044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090122554s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:40.966165Z","caller":"traceutil/trace.go:171","msg":"trace[1205038877] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.278137587s","start":"2026-07-27T20:12:39.68801Z","end":"2026-07-27T20:12:40.966147Z","steps":["trace[1205038877] 'process raft request' (duration: 187.855969ms)","trace[1205038877] 'compare' (duration: 1.089376357s)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:40.966236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:39.684563Z","time spent":"1.281651419s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:12:41.053391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.177363634s","expected-duration":"1s"} {"level":"info","ts":"2026-07-27T20:12:41.054106Z","caller":"traceutil/trace.go:171","msg":"trace[1721741827] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"1.194240697s","start":"2026-07-27T20:12:39.859843Z","end":"2026-07-27T20:12:41.054084Z","steps":["trace[1721741827] 'read index received' (duration: 16.116735ms)","trace[1721741827] 'applied index is now lower than readState.Index' (duration: 1.178123232s)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:41.054156Z","caller":"traceutil/trace.go:171","msg":"trace[979679091] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.216638738s","start":"2026-07-27T20:12:39.837493Z","end":"2026-07-27T20:12:41.054132Z","steps":["trace[979679091] 'process raft request' (duration: 1.216178467s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.054271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:39.837469Z","time spent":"1.21672741s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:41.054343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194473762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-27T20:12:41.054414Z","caller":"traceutil/trace.go:171","msg":"trace[815513087] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"1.194589585s","start":"2026-07-27T20:12:39.859809Z","end":"2026-07-27T20:12:41.054398Z","steps":["trace[815513087] 'agreement among raft nodes before linearized reading' (duration: 1.19435832s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.054452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:39.859792Z","time spent":"1.194653986s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-07-27T20:12:41.220444Z","caller":"traceutil/trace.go:171","msg":"trace[1729617677] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"189.002645ms","start":"2026-07-27T20:12:41.031419Z","end":"2026-07-27T20:12:41.220422Z","steps":["trace[1729617677] 'process raft request' (duration: 188.78729ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:41.220561Z","caller":"traceutil/trace.go:171","msg":"trace[18045015] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"166.338708ms","start":"2026-07-27T20:12:41.054176Z","end":"2026-07-27T20:12:41.220514Z","steps":["trace[18045015] 'read index received' (duration: 159.026119ms)","trace[18045015] 'applied index is now lower than readState.Index' (duration: 7.308969ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:41.220765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.67192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-07-27T20:12:41.220758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.450125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-27T20:12:41.220825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.437826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:41.220828Z","caller":"traceutil/trace.go:171","msg":"trace[1454907461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"192.569529ms","start":"2026-07-27T20:12:41.028242Z","end":"2026-07-27T20:12:41.220812Z","steps":["trace[1454907461] 'agreement among raft nodes before linearized reading' (duration: 192.317633ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:41.220844Z","caller":"traceutil/trace.go:171","msg":"trace[653794690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"263.487848ms","start":"2026-07-27T20:12:40.957351Z","end":"2026-07-27T20:12:41.220839Z","steps":["trace[653794690] 'agreement among raft nodes before linearized reading' (duration: 263.459287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.220935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.981409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-27T20:12:41.220984Z","caller":"traceutil/trace.go:171","msg":"trace[1459508396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"761.113052ms","start":"2026-07-27T20:12:40.459854Z","end":"2026-07-27T20:12:41.220967Z","steps":["trace[1459508396] 'agreement among raft nodes before linearized reading' (duration: 760.841926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.221021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:40.459835Z","time spent":"761.177173ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-27T20:12:41.221056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.603079ms","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-27T20:12:41.221081Z","caller":"traceutil/trace.go:171","msg":"trace[1376946972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"189.67188ms","start":"2026-07-27T20:12:41.031403Z","end":"2026-07-27T20:12:41.221074Z","steps":["trace[1376946972] 'agreement among raft nodes before linearized reading' (duration: 189.606609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.221078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.802013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-27T20:12:41.221103Z","caller":"traceutil/trace.go:171","msg":"trace[1550025696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"189.838965ms","start":"2026-07-27T20:12:41.031257Z","end":"2026-07-27T20:12:41.221096Z","steps":["trace[1550025696] 'agreement among raft nodes before linearized reading' (duration: 189.771963ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:41.220796Z","caller":"traceutil/trace.go:171","msg":"trace[284353484] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"560.744772ms","start":"2026-07-27T20:12:40.660043Z","end":"2026-07-27T20:12:41.220788Z","steps":["trace[284353484] 'agreement among raft nodes before linearized reading' (duration: 560.562247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.22118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:40.660023Z","time spent":"561.141391ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-27T20:12:41.221206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161621557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-27T20:12:41.221228Z","caller":"traceutil/trace.go:171","msg":"trace[1112465582] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"1.161673539s","start":"2026-07-27T20:12:40.059546Z","end":"2026-07-27T20:12:41.221219Z","steps":["trace[1112465582] 'agreement among raft nodes before linearized reading' (duration: 1.161614877s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.221248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.846484ms","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":"warn","ts":"2026-07-27T20:12:41.22125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:40.059528Z","time spent":"1.161716849s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-07-27T20:12:41.221267Z","caller":"traceutil/trace.go:171","msg":"trace[399747841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:659; }","duration":"189.905755ms","start":"2026-07-27T20:12:41.031355Z","end":"2026-07-27T20:12:41.221261Z","steps":["trace[399747841] 'agreement among raft nodes before linearized reading' (duration: 189.846254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.221346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.170008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-27T20:12:41.221371Z","caller":"traceutil/trace.go:171","msg":"trace[1151280823] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"962.22235ms","start":"2026-07-27T20:12:40.259139Z","end":"2026-07-27T20:12:41.221361Z","steps":["trace[1151280823] 'agreement among raft nodes before linearized reading' (duration: 962.157958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:41.221389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:40.25912Z","time spent":"962.2646ms","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-07-27T20:12:41.221405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.146557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-27T20:12:41.221447Z","caller":"traceutil/trace.go:171","msg":"trace[1621776963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"164.209948ms","start":"2026-07-27T20:12:41.057226Z","end":"2026-07-27T20:12:41.221436Z","steps":["trace[1621776963] 'agreement among raft nodes before linearized reading' (duration: 164.089716ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:41.959546Z","caller":"traceutil/trace.go:171","msg":"trace[1886135119] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"236.754697ms","start":"2026-07-27T20:12:41.722766Z","end":"2026-07-27T20:12:41.959521Z","steps":["trace[1886135119] 'process raft request' (duration: 236.486111ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:42.562869Z","caller":"traceutil/trace.go:171","msg":"trace[1613828437] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"439.41242ms","start":"2026-07-27T20:12:42.123407Z","end":"2026-07-27T20:12:42.56282Z","steps":["trace[1613828437] 'read index received' (duration: 384.694428ms)","trace[1613828437] 'applied index is now lower than readState.Index' (duration: 54.716992ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:42.562921Z","caller":"traceutil/trace.go:171","msg":"trace[1739020711] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"455.943014ms","start":"2026-07-27T20:12:42.106948Z","end":"2026-07-27T20:12:42.562891Z","steps":["trace[1739020711] 'process raft request' (duration: 400.812692ms)","trace[1739020711] 'compare' (duration: 54.919387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:42.563031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.596415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:12:42.563054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.106906Z","time spent":"456.074107ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4414,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:42.563089Z","caller":"traceutil/trace.go:171","msg":"trace[1897381256] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:670; }","duration":"439.688447ms","start":"2026-07-27T20:12:42.123376Z","end":"2026-07-27T20:12:42.563065Z","steps":["trace[1897381256] 'agreement among raft nodes before linearized reading' (duration: 439.594364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.563137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.12336Z","time spent":"439.767198ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-27T20:12:42.572997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.634154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:42.573073Z","caller":"traceutil/trace.go:171","msg":"trace[1473287985] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:671; }","duration":"448.736316ms","start":"2026-07-27T20:12:42.124321Z","end":"2026-07-27T20:12:42.573057Z","steps":["trace[1473287985] 'agreement among raft nodes before linearized reading' (duration: 448.598963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.573111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.124315Z","time spent":"448.783388ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:12:42.573314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.664892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:12:42.57338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.378696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:42.573397Z","caller":"traceutil/trace.go:171","msg":"trace[127244081] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:671; }","duration":"401.801195ms","start":"2026-07-27T20:12:42.171575Z","end":"2026-07-27T20:12:42.573376Z","steps":["trace[127244081] 'agreement among raft nodes before linearized reading' (duration: 401.646991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.573504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.793245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:12:42.573511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.171532Z","time spent":"401.967098ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"info","ts":"2026-07-27T20:12:42.57354Z","caller":"traceutil/trace.go:171","msg":"trace[1492453725] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"432.840166ms","start":"2026-07-27T20:12:42.140692Z","end":"2026-07-27T20:12:42.573532Z","steps":["trace[1492453725] 'agreement among raft nodes before linearized reading' (duration: 432.792015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.573573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.140688Z","time spent":"432.877838ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:12:42.573747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.325498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:42.573783Z","caller":"traceutil/trace.go:171","msg":"trace[1615410762] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"433.37761ms","start":"2026-07-27T20:12:42.140398Z","end":"2026-07-27T20:12:42.573775Z","steps":["trace[1615410762] 'agreement among raft nodes before linearized reading' (duration: 433.319408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.573808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.140392Z","time spent":"433.40948ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-07-27T20:12:42.573968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.414512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:42.573419Z","caller":"traceutil/trace.go:171","msg":"trace[1393762311] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"432.427987ms","start":"2026-07-27T20:12:42.140979Z","end":"2026-07-27T20:12:42.573407Z","steps":["trace[1393762311] 'agreement among raft nodes before linearized reading' (duration: 432.372856ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:42.574006Z","caller":"traceutil/trace.go:171","msg":"trace[1720332648] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:671; }","duration":"449.466714ms","start":"2026-07-27T20:12:42.124531Z","end":"2026-07-27T20:12:42.573997Z","steps":["trace[1720332648] 'agreement among raft nodes before linearized reading' (duration: 449.411772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.574038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.124526Z","time spent":"449.504335ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-07-27T20:12:42.574221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.140973Z","time spent":"433.046671ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-07-27T20:12:42.573972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.248056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:12:42.574355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.759934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:42.574384Z","caller":"traceutil/trace.go:171","msg":"trace[1275507074] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:671; }","duration":"402.679965ms","start":"2026-07-27T20:12:42.171692Z","end":"2026-07-27T20:12:42.574372Z","steps":["trace[1275507074] 'agreement among raft nodes before linearized reading' (duration: 402.223265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.574425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.171685Z","time spent":"402.723516ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:12:42.574425Z","caller":"traceutil/trace.go:171","msg":"trace[330503779] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:671; }","duration":"401.853856ms","start":"2026-07-27T20:12:42.172541Z","end":"2026-07-27T20:12:42.574395Z","steps":["trace[330503779] 'agreement among raft nodes before linearized reading' (duration: 401.783094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:42.574463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:42.172523Z","time spent":"401.929098ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-07-27T20:12:42.757512Z","caller":"traceutil/trace.go:171","msg":"trace[2031852486] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"132.116493ms","start":"2026-07-27T20:12:42.625362Z","end":"2026-07-27T20:12:42.757478Z","steps":["trace[2031852486] 'process raft request' (duration: 40.155434ms)","trace[2031852486] 'compare' (duration: 91.594251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:47.802183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147180859380157,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T20:12:48.263386Z","caller":"traceutil/trace.go:171","msg":"trace[1192079493] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"961.631206ms","start":"2026-07-27T20:12:47.301726Z","end":"2026-07-27T20:12:48.263358Z","steps":["trace[1192079493] 'read index received' (duration: 961.34717ms)","trace[1192079493] 'applied index is now lower than readState.Index' (duration: 282.726µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:48.263581Z","caller":"traceutil/trace.go:171","msg":"trace[1587497444] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.203931251s","start":"2026-07-27T20:12:47.059629Z","end":"2026-07-27T20:12:48.263561Z","steps":["trace[1587497444] 'process raft request' (duration: 1.203541652s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.263784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.058756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-27T20:12:48.26382Z","caller":"traceutil/trace.go:171","msg":"trace[1074856573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"962.138528ms","start":"2026-07-27T20:12:47.301671Z","end":"2026-07-27T20:12:48.26381Z","steps":["trace[1074856573] 'agreement among raft nodes before linearized reading' (duration: 962.010785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.263848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:47.301653Z","time spent":"962.187059ms","remote":"127.0.0.1:47894","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":"warn","ts":"2026-07-27T20:12:48.263888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.477734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-27T20:12:48.263905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.298127ms","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-27T20:12:48.263931Z","caller":"traceutil/trace.go:171","msg":"trace[938695521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"891.538896ms","start":"2026-07-27T20:12:47.37238Z","end":"2026-07-27T20:12:48.263918Z","steps":["trace[938695521] 'agreement among raft nodes before linearized reading' (duration: 891.365992ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:48.263941Z","caller":"traceutil/trace.go:171","msg":"trace[1018851622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"887.355529ms","start":"2026-07-27T20:12:47.376575Z","end":"2026-07-27T20:12:48.263931Z","steps":["trace[1018851622] 'agreement among raft nodes before linearized reading' (duration: 887.252306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.263961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:47.372367Z","time spent":"891.586347ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:12:48.263969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:47.376564Z","time spent":"887.398409ms","remote":"127.0.0.1:47894","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-27T20:12:48.263983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.648039ms","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-27T20:12:48.264074Z","caller":"traceutil/trace.go:171","msg":"trace[132722567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"891.791822ms","start":"2026-07-27T20:12:47.372258Z","end":"2026-07-27T20:12:48.26405Z","steps":["trace[132722567] 'agreement among raft nodes before linearized reading' (duration: 891.594338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.264118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:47.372243Z","time spent":"891.866614ms","remote":"127.0.0.1:47894","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-27T20:12:48.264252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:47.05961Z","time spent":"1.204012785s","remote":"127.0.0.1:47842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:48.264525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.257723ms","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-27T20:12:48.264566Z","caller":"traceutil/trace.go:171","msg":"trace[188927733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"895.365336ms","start":"2026-07-27T20:12:47.369189Z","end":"2026-07-27T20:12:48.264554Z","steps":["trace[188927733] 'agreement among raft nodes before linearized reading' (duration: 894.547136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.264592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:47.369168Z","time spent":"895.414057ms","remote":"127.0.0.1:47894","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-27T20:12:48.761142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.845286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:12:48.761255Z","caller":"traceutil/trace.go:171","msg":"trace[1492230045] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"492.479454ms","start":"2026-07-27T20:12:48.268755Z","end":"2026-07-27T20:12:48.761234Z","steps":["trace[1492230045] 'process raft request' (duration: 354.434104ms)","trace[1492230045] 'compare' (duration: 137.755344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:48.761345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:48.268737Z","time spent":"492.553406ms","remote":"127.0.0.1:47894","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-27T20:12:48.777161Z","caller":"traceutil/trace.go:171","msg":"trace[265779190] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"507.838072ms","start":"2026-07-27T20:12:48.269304Z","end":"2026-07-27T20:12:48.777142Z","steps":["trace[265779190] 'process raft request' (duration: 507.719859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.777248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:48.269293Z","time spent":"507.915904ms","remote":"127.0.0.1:47894","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-27T20:12:48.777436Z","caller":"traceutil/trace.go:171","msg":"trace[702897933] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"507.929334ms","start":"2026-07-27T20:12:48.269441Z","end":"2026-07-27T20:12:48.77737Z","steps":["trace[702897933] 'process raft request' (duration: 507.663378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.77763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:48.269432Z","time spent":"508.093098ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:48.777623Z","caller":"traceutil/trace.go:171","msg":"trace[1316128454] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"507.723919ms","start":"2026-07-27T20:12:48.269816Z","end":"2026-07-27T20:12:48.77754Z","steps":["trace[1316128454] 'process raft request' (duration: 507.488114ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:48.777567Z","caller":"traceutil/trace.go:171","msg":"trace[462679446] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"507.426662ms","start":"2026-07-27T20:12:48.270089Z","end":"2026-07-27T20:12:48.777515Z","steps":["trace[462679446] 'process raft request' (duration: 507.306339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:48.777842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:48.27008Z","time spent":"507.684978ms","remote":"127.0.0.1:47894","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-27T20:12:48.778183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:48.269807Z","time spent":"507.955685ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:12:49.575638Z","caller":"traceutil/trace.go:171","msg":"trace[1603562276] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"431.70298ms","start":"2026-07-27T20:12:49.143913Z","end":"2026-07-27T20:12:49.575616Z","steps":["trace[1603562276] 'process raft request' (duration: 431.564607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:49.575946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:49.143884Z","time spent":"431.908265ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:12:52.295963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360296829s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-27T20:12:52.296065Z","caller":"traceutil/trace.go:171","msg":"trace[2084023332] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"1.513720018s","start":"2026-07-27T20:12:50.782327Z","end":"2026-07-27T20:12:52.296047Z","steps":["trace[2084023332] 'read index received' (duration: 153.257505ms)","trace[2084023332] 'applied index is now lower than readState.Index' (duration: 1.360461233s)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:12:52.296724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340581191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:12:52.296762Z","caller":"traceutil/trace.go:171","msg":"trace[620461017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"1.340624522s","start":"2026-07-27T20:12:50.956127Z","end":"2026-07-27T20:12:52.296751Z","steps":["trace[620461017] 'agreement among raft nodes before linearized reading' (duration: 1.340568241s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:52.296793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:50.956077Z","time spent":"1.340709674s","remote":"127.0.0.1:47666","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-27T20:12:52.297051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514722301s","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-27T20:12:52.297094Z","caller":"traceutil/trace.go:171","msg":"trace[1894059536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"1.514800883s","start":"2026-07-27T20:12:50.782282Z","end":"2026-07-27T20:12:52.297083Z","steps":["trace[1894059536] 'agreement among raft nodes before linearized reading' (duration: 1.514163578s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:52.297122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:50.78226Z","time spent":"1.514854783s","remote":"127.0.0.1:47894","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-27T20:12:52.297265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514429933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-27T20:12:52.297346Z","caller":"traceutil/trace.go:171","msg":"trace[1611182172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"1.514569716s","start":"2026-07-27T20:12:50.782756Z","end":"2026-07-27T20:12:52.297326Z","steps":["trace[1611182172] 'agreement among raft nodes before linearized reading' (duration: 1.513827869s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:52.297386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:50.782747Z","time spent":"1.514631428s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:12:52.297387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509828047s","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-27T20:12:52.297532Z","caller":"traceutil/trace.go:171","msg":"trace[36829023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"1.509987881s","start":"2026-07-27T20:12:50.787527Z","end":"2026-07-27T20:12:52.297515Z","steps":["trace[36829023] 'agreement among raft nodes before linearized reading' (duration: 1.509160222s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:52.297629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:50.787522Z","time spent":"1.510093193s","remote":"127.0.0.1:47894","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-27T20:12:52.297266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509380826s","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-27T20:12:52.29774Z","caller":"traceutil/trace.go:171","msg":"trace[1350434473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"1.509882069s","start":"2026-07-27T20:12:50.787845Z","end":"2026-07-27T20:12:52.297728Z","steps":["trace[1350434473] 'agreement among raft nodes before linearized reading' (duration: 1.508830884s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:52.297779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:50.78784Z","time spent":"1.509926489s","remote":"127.0.0.1:47894","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-27T20:12:52.297438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514586187s","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-27T20:12:52.297878Z","caller":"traceutil/trace.go:171","msg":"trace[913033826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"1.515027186s","start":"2026-07-27T20:12:50.782827Z","end":"2026-07-27T20:12:52.297854Z","steps":["trace[913033826] 'agreement among raft nodes before linearized reading' (duration: 1.513896241s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:52.297979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:12:50.782821Z","time spent":"1.51513703s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-27T20:12:55.323296Z","caller":"traceutil/trace.go:171","msg":"trace[380481595] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"167.234259ms","start":"2026-07-27T20:12:55.156038Z","end":"2026-07-27T20:12:55.323272Z","steps":["trace[380481595] 'process raft request' (duration: 164.472164ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:00.391353Z","caller":"traceutil/trace.go:171","msg":"trace[1547090517] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"108.726208ms","start":"2026-07-27T20:13:00.282598Z","end":"2026-07-27T20:13:00.391324Z","steps":["trace[1547090517] 'process raft request' (duration: 101.679625ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:03.316913Z","caller":"traceutil/trace.go:171","msg":"trace[1383741034] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"137.879927ms","start":"2026-07-27T20:13:03.179004Z","end":"2026-07-27T20:13:03.316884Z","steps":["trace[1383741034] 'process raft request' (duration: 136.976585ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:03.464009Z","caller":"traceutil/trace.go:171","msg":"trace[2048613761] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"251.439577ms","start":"2026-07-27T20:13:03.212541Z","end":"2026-07-27T20:13:03.463981Z","steps":["trace[2048613761] 'process raft request' (duration: 232.009115ms)","trace[2048613761] 'compare' (duration: 19.202797ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:03.464571Z","caller":"traceutil/trace.go:171","msg":"trace[555156888] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"250.912796ms","start":"2026-07-27T20:13:03.213645Z","end":"2026-07-27T20:13:03.464558Z","steps":["trace[555156888] 'process raft request' (duration: 250.275471ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:03.464692Z","caller":"traceutil/trace.go:171","msg":"trace[77239742] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"250.493647ms","start":"2026-07-27T20:13:03.214128Z","end":"2026-07-27T20:13:03.464622Z","steps":["trace[77239742] 'process raft request' (duration: 250.153288ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:03.669839Z","caller":"traceutil/trace.go:171","msg":"trace[391180762] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"165.404657ms","start":"2026-07-27T20:13:03.504412Z","end":"2026-07-27T20:13:03.669817Z","steps":["trace[391180762] 'process raft request' (duration: 165.257314ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:03.881152Z","caller":"traceutil/trace.go:171","msg":"trace[9832359] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"178.127283ms","start":"2026-07-27T20:13:03.703004Z","end":"2026-07-27T20:13:03.881132Z","steps":["trace[9832359] 'read index received' (duration: 177.924838ms)","trace[9832359] 'applied index is now lower than readState.Index' (duration: 201.324µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:03.881306Z","caller":"traceutil/trace.go:171","msg":"trace[214688054] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"199.0959ms","start":"2026-07-27T20:13:03.682166Z","end":"2026-07-27T20:13:03.881262Z","steps":["trace[214688054] 'process raft request' (duration: 198.825174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:03.881355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.333717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-27T20:13:03.881393Z","caller":"traceutil/trace.go:171","msg":"trace[1799877885] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:789; }","duration":"178.425869ms","start":"2026-07-27T20:13:03.702957Z","end":"2026-07-27T20:13:03.881383Z","steps":["trace[1799877885] 'agreement among raft nodes before linearized reading' (duration: 178.288326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:03.881597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.539662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-07-27T20:13:03.881666Z","caller":"traceutil/trace.go:171","msg":"trace[2028726353] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:789; }","duration":"178.635874ms","start":"2026-07-27T20:13:03.703013Z","end":"2026-07-27T20:13:03.881649Z","steps":["trace[2028726353] 'agreement among raft nodes before linearized reading' (duration: 178.330837ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:09.339713Z","caller":"traceutil/trace.go:171","msg":"trace[2021097194] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"149.051147ms","start":"2026-07-27T20:13:09.190638Z","end":"2026-07-27T20:13:09.339689Z","steps":["trace[2021097194] 'process raft request' (duration: 148.909513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:10.203919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.638944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:13:10.204026Z","caller":"traceutil/trace.go:171","msg":"trace[1410347591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"230.809278ms","start":"2026-07-27T20:13:09.973192Z","end":"2026-07-27T20:13:10.204001Z","steps":["trace[1410347591] 'range keys from in-memory index tree' (duration: 230.543402ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:10.486835Z","caller":"traceutil/trace.go:171","msg":"trace[243013653] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"246.181777ms","start":"2026-07-27T20:13:10.24063Z","end":"2026-07-27T20:13:10.486811Z","steps":["trace[243013653] 'process raft request' (duration: 246.002403ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:44.716263Z","caller":"traceutil/trace.go:171","msg":"trace[46416076] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"299.96768ms","start":"2026-07-27T20:13:44.416271Z","end":"2026-07-27T20:13:44.716239Z","steps":["trace[46416076] 'process raft request' (duration: 299.831887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:44.71642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.416254Z","time spent":"300.087484ms","remote":"127.0.0.1:47894","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-27T20:13:45.429491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.058264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:13:45.429567Z","caller":"traceutil/trace.go:171","msg":"trace[780644371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"472.177957ms","start":"2026-07-27T20:13:44.957372Z","end":"2026-07-27T20:13:45.42955Z","steps":["trace[780644371] 'range keys from in-memory index tree' (duration: 471.968102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.429604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.957354Z","time spent":"472.242398ms","remote":"127.0.0.1:47666","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-27T20:13:45.429599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.20727ms","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-27T20:13:45.429654Z","caller":"traceutil/trace.go:171","msg":"trace[1045674822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"616.290941ms","start":"2026-07-27T20:13:44.813346Z","end":"2026-07-27T20:13:45.429637Z","steps":["trace[1045674822] 'range keys from in-memory index tree' (duration: 616.081627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.429682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.494365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-27T20:13:45.429695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.813332Z","time spent":"616.352433ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-27T20:13:45.429749Z","caller":"traceutil/trace.go:171","msg":"trace[1424078207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"638.591617ms","start":"2026-07-27T20:13:44.791138Z","end":"2026-07-27T20:13:45.42973Z","steps":["trace[1424078207] 'range keys from in-memory index tree' (duration: 638.339311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.429794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.791116Z","time spent":"638.664718ms","remote":"127.0.0.1:47894","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-27T20:13:45.429856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.223306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:13:45.42992Z","caller":"traceutil/trace.go:171","msg":"trace[862374867] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1043; }","duration":"411.321308ms","start":"2026-07-27T20:13:45.01859Z","end":"2026-07-27T20:13:45.429911Z","steps":["trace[862374867] 'count revisions from in-memory index tree' (duration: 411.162014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.429954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.018573Z","time spent":"411.372239ms","remote":"127.0.0.1:47910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:13:45.430002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.704814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-27T20:13:45.430045Z","caller":"traceutil/trace.go:171","msg":"trace[666946211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"617.775166ms","start":"2026-07-27T20:13:44.812258Z","end":"2026-07-27T20:13:45.430033Z","steps":["trace[666946211] 'range keys from in-memory index tree' (duration: 617.570752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.429495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.928126ms","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":"warn","ts":"2026-07-27T20:13:45.430081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.812238Z","time spent":"617.832937ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:13:45.430081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.290356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-27T20:13:45.430095Z","caller":"traceutil/trace.go:171","msg":"trace[53614887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"617.565991ms","start":"2026-07-27T20:13:44.812519Z","end":"2026-07-27T20:13:45.430085Z","steps":["trace[53614887] 'range keys from in-memory index tree' (duration: 616.743713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.430124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.812505Z","time spent":"617.611162ms","remote":"127.0.0.1:47894","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":"info","ts":"2026-07-27T20:13:45.430125Z","caller":"traceutil/trace.go:171","msg":"trace[671965716] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:1043; }","duration":"589.358729ms","start":"2026-07-27T20:13:44.840753Z","end":"2026-07-27T20:13:45.430112Z","steps":["trace[671965716] 'range keys from in-memory index tree' (duration: 589.181333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.4296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.816396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-27T20:13:45.430182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.84074Z","time spent":"589.43009ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.12\" "} {"level":"warn","ts":"2026-07-27T20:13:45.430394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.146615ms","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-27T20:13:45.430437Z","caller":"traceutil/trace.go:171","msg":"trace[1954324980] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1043; }","duration":"577.205787ms","start":"2026-07-27T20:13:44.853219Z","end":"2026-07-27T20:13:45.430425Z","steps":["trace[1954324980] 'count revisions from in-memory index tree' (duration: 577.110675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.430477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.853212Z","time spent":"577.252388ms","remote":"127.0.0.1:47788","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-27T20:13:45.430186Z","caller":"traceutil/trace.go:171","msg":"trace[963034597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"653.43546ms","start":"2026-07-27T20:13:44.776743Z","end":"2026-07-27T20:13:45.430179Z","steps":["trace[963034597] 'range keys from in-memory index tree' (duration: 652.656793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.430489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.433459ms","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-27T20:13:45.430539Z","caller":"traceutil/trace.go:171","msg":"trace[1700834105] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1043; }","duration":"506.522681ms","start":"2026-07-27T20:13:44.924004Z","end":"2026-07-27T20:13:45.430526Z","steps":["trace[1700834105] 'count revisions from in-memory index tree' (duration: 506.350387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:45.430553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.776727Z","time spent":"653.799568ms","remote":"127.0.0.1:47894","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-27T20:13:45.43058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:44.923987Z","time spent":"506.579072ms","remote":"127.0.0.1:47848","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-27T20:13:47.074623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.522864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:13:47.074789Z","caller":"traceutil/trace.go:171","msg":"trace[1872651308] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"1.64045137s","start":"2026-07-27T20:13:45.434315Z","end":"2026-07-27T20:13:47.074766Z","steps":["trace[1872651308] 'process raft request' (duration: 823.732361ms)","trace[1872651308] 'compare' (duration: 816.411402ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:13:47.074891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.434297Z","time spent":"1.640539883s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:13:47.074897Z","caller":"traceutil/trace.go:171","msg":"trace[958246885] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"353.07035ms","start":"2026-07-27T20:13:46.721814Z","end":"2026-07-27T20:13:47.074884Z","steps":["trace[958246885] 'process raft request' (duration: 353.042929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.074966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:46.721789Z","time spent":"353.132492ms","remote":"127.0.0.1:47894","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-27T20:13:47.075085Z","caller":"traceutil/trace.go:171","msg":"trace[818527749] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"1.64006476s","start":"2026-07-27T20:13:45.435013Z","end":"2026-07-27T20:13:47.075077Z","steps":["trace[818527749] 'process raft request' (duration: 1.639776804s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.075125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.434991Z","time spent":"1.640111522s","remote":"127.0.0.1:47894","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-27T20:13:47.075133Z","caller":"traceutil/trace.go:171","msg":"trace[1531949537] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.639075448s","start":"2026-07-27T20:13:45.436051Z","end":"2026-07-27T20:13:47.075126Z","steps":["trace[1531949537] 'process raft request' (duration: 1.638759671s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.075174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.436037Z","time spent":"1.639116779s","remote":"127.0.0.1:47894","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-27T20:13:47.075257Z","caller":"traceutil/trace.go:171","msg":"trace[51055189] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"1.64088766s","start":"2026-07-27T20:13:45.434362Z","end":"2026-07-27T20:13:47.07525Z","steps":["trace[51055189] 'process raft request' (duration: 1.640344128s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.075291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.434342Z","time spent":"1.640931362s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:13:47.075344Z","caller":"traceutil/trace.go:171","msg":"trace[490945898] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.611984981s","start":"2026-07-27T20:13:45.46335Z","end":"2026-07-27T20:13:47.075335Z","steps":["trace[490945898] 'process raft request' (duration: 1.61148244s)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:47.075377Z","caller":"traceutil/trace.go:171","msg":"trace[1163372932] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.64046532s","start":"2026-07-27T20:13:45.434905Z","end":"2026-07-27T20:13:47.07537Z","steps":["trace[1163372932] 'process raft request' (duration: 1.639864226s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.075384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.46333Z","time spent":"1.612033023s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:13:47.075408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:45.43489Z","time spent":"1.64050219s","remote":"127.0.0.1:47894","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-27T20:13:47.075482Z","caller":"traceutil/trace.go:171","msg":"trace[1849667163] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1078; }","duration":"662.790916ms","start":"2026-07-27T20:13:46.412684Z","end":"2026-07-27T20:13:47.075475Z","steps":["trace[1849667163] 'read index received' (duration: 56.261µs)","trace[1849667163] 'applied index is now lower than readState.Index' (duration: 662.733875ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:13:47.07568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.997261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:13:47.075704Z","caller":"traceutil/trace.go:171","msg":"trace[1430955606] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1050; }","duration":"663.057492ms","start":"2026-07-27T20:13:46.41264Z","end":"2026-07-27T20:13:47.075697Z","steps":["trace[1430955606] 'agreement among raft nodes before linearized reading' (duration: 662.989121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.075724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:13:46.412625Z","time spent":"663.094593ms","remote":"127.0.0.1:47970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:13:47.075859Z","caller":"traceutil/trace.go:171","msg":"trace[2057528622] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"197.483809ms","start":"2026-07-27T20:13:46.878352Z","end":"2026-07-27T20:13:47.075835Z","steps":["trace[2057528622] 'process raft request' (duration: 197.323946ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:11.687095Z","caller":"traceutil/trace.go:171","msg":"trace[2127232020] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"409.24194ms","start":"2026-07-27T20:14:11.277827Z","end":"2026-07-27T20:14:11.687069Z","steps":["trace[2127232020] 'read index received' (duration: 409.070527ms)","trace[2127232020] 'applied index is now lower than readState.Index' (duration: 169.673µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:11.687368Z","caller":"traceutil/trace.go:171","msg":"trace[1924834886] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"519.531832ms","start":"2026-07-27T20:14:11.167793Z","end":"2026-07-27T20:14:11.687324Z","steps":["trace[1924834886] 'process raft request' (duration: 519.117783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:11.687418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.528765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:14:11.687449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.581307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-27T20:14:11.687483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.145007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-27T20:14:11.687525Z","caller":"traceutil/trace.go:171","msg":"trace[882973484] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1124; }","duration":"386.675367ms","start":"2026-07-27T20:14:11.300826Z","end":"2026-07-27T20:14:11.687501Z","steps":["trace[882973484] 'agreement among raft nodes before linearized reading' (duration: 386.525544ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:11.687551Z","caller":"traceutil/trace.go:171","msg":"trace[541538462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"409.22224ms","start":"2026-07-27T20:14:11.278309Z","end":"2026-07-27T20:14:11.687531Z","steps":["trace[541538462] 'agreement among raft nodes before linearized reading' (duration: 409.018765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:11.687609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.278302Z","time spent":"409.291921ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-27T20:14:11.687606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.300814Z","time spent":"386.77157ms","remote":"127.0.0.1:56428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:14:11.687536Z","caller":"traceutil/trace.go:171","msg":"trace[265619389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"409.700981ms","start":"2026-07-27T20:14:11.277816Z","end":"2026-07-27T20:14:11.687517Z","steps":["trace[265619389] 'agreement among raft nodes before linearized reading' (duration: 409.473515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:11.687817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.277808Z","time spent":"409.996987ms","remote":"127.0.0.1:47894","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-27T20:14:11.68782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.167769Z","time spent":"519.782198ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:11.687903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.068859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-27T20:14:11.687925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.627249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-27T20:14:11.68799Z","caller":"traceutil/trace.go:171","msg":"trace[1560521755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"410.151941ms","start":"2026-07-27T20:14:11.277823Z","end":"2026-07-27T20:14:11.687974Z","steps":["trace[1560521755] 'agreement among raft nodes before linearized reading' (duration: 409.417614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:11.688046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.69925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-27T20:14:11.688079Z","caller":"traceutil/trace.go:171","msg":"trace[1224380804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1124; }","duration":"409.858254ms","start":"2026-07-27T20:14:11.278171Z","end":"2026-07-27T20:14:11.688029Z","steps":["trace[1224380804] 'agreement among raft nodes before linearized reading' (duration: 409.2437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:11.688093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.277791Z","time spent":"410.285033ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-27T20:14:11.688098Z","caller":"traceutil/trace.go:171","msg":"trace[313785409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"409.771152ms","start":"2026-07-27T20:14:11.278314Z","end":"2026-07-27T20:14:11.688085Z","steps":["trace[313785409] 'agreement among raft nodes before linearized reading' (duration: 408.998604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:11.688138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.27831Z","time spent":"409.820904ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-27T20:14:11.688177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.278156Z","time spent":"410.006587ms","remote":"127.0.0.1:47894","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":"warn","ts":"2026-07-27T20:14:12.195126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.856202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:14:12.195249Z","caller":"traceutil/trace.go:171","msg":"trace[654751554] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"501.72926ms","start":"2026-07-27T20:14:11.693501Z","end":"2026-07-27T20:14:12.19523Z","steps":["trace[654751554] 'process raft request' (duration: 340.582012ms)","trace[654751554] 'compare' (duration: 160.708608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:14:12.195308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.693475Z","time spent":"501.804772ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:14:12.211773Z","caller":"traceutil/trace.go:171","msg":"trace[192005105] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"517.127567ms","start":"2026-07-27T20:14:11.694624Z","end":"2026-07-27T20:14:12.211752Z","steps":["trace[192005105] 'process raft request' (duration: 516.980614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:12.211893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.694603Z","time spent":"517.235539ms","remote":"127.0.0.1:47894","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-27T20:14:12.21208Z","caller":"traceutil/trace.go:171","msg":"trace[202194756] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"517.073484ms","start":"2026-07-27T20:14:11.694991Z","end":"2026-07-27T20:14:12.212064Z","steps":["trace[202194756] 'process raft request' (duration: 516.941091ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:12.212127Z","caller":"traceutil/trace.go:171","msg":"trace[2105321047] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1164; }","duration":"207.562283ms","start":"2026-07-27T20:14:12.004539Z","end":"2026-07-27T20:14:12.212102Z","steps":["trace[2105321047] 'read index received' (duration: 29.699348ms)","trace[2105321047] 'applied index is now lower than readState.Index' (duration: 177.862165ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:12.212116Z","caller":"traceutil/trace.go:171","msg":"trace[2119946511] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"517.097245ms","start":"2026-07-27T20:14:11.694962Z","end":"2026-07-27T20:14:12.212059Z","steps":["trace[2119946511] 'process raft request' (duration: 516.742797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:12.212177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.694955Z","time spent":"517.184977ms","remote":"127.0.0.1:47894","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-27T20:14:12.212153Z","caller":"traceutil/trace.go:171","msg":"trace[1737583184] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"517.085765ms","start":"2026-07-27T20:14:11.695037Z","end":"2026-07-27T20:14:12.212123Z","steps":["trace[1737583184] 'process raft request' (duration: 516.992873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:12.212199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.662785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:12.212229Z","caller":"traceutil/trace.go:171","msg":"trace[1345811638] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1129; }","duration":"207.734217ms","start":"2026-07-27T20:14:12.004486Z","end":"2026-07-27T20:14:12.21222Z","steps":["trace[1345811638] 'agreement among raft nodes before linearized reading' (duration: 207.669775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:12.212317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.695003Z","time spent":"517.218538ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:12.212274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:11.694947Z","time spent":"517.249288ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:14:13.873991Z","caller":"traceutil/trace.go:171","msg":"trace[1760121] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"181.139981ms","start":"2026-07-27T20:14:13.69282Z","end":"2026-07-27T20:14:13.87396Z","steps":["trace[1760121] 'process raft request' (duration: 180.869905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:15.256964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:14.844094Z","time spent":"412.866193ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-27T20:14:15.407747Z","caller":"traceutil/trace.go:171","msg":"trace[1238097662] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"149.510259ms","start":"2026-07-27T20:14:15.258206Z","end":"2026-07-27T20:14:15.407716Z","steps":["trace[1238097662] 'process raft request' (duration: 97.541246ms)","trace[1238097662] 'compare' (duration: 51.818299ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:16.040105Z","caller":"traceutil/trace.go:171","msg":"trace[430363915] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"148.65918ms","start":"2026-07-27T20:14:15.891377Z","end":"2026-07-27T20:14:16.040036Z","steps":["trace[430363915] 'process raft request' (duration: 148.354713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:16.330021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.050855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-27T20:14:16.330053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.572629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-27T20:14:16.330108Z","caller":"traceutil/trace.go:171","msg":"trace[386975990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"100.170828ms","start":"2026-07-27T20:14:16.229918Z","end":"2026-07-27T20:14:16.330089Z","steps":["trace[386975990] 'range keys from in-memory index tree' (duration: 99.678947ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:16.330135Z","caller":"traceutil/trace.go:171","msg":"trace[264967756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1138; }","duration":"107.702832ms","start":"2026-07-27T20:14:16.222413Z","end":"2026-07-27T20:14:16.330116Z","steps":["trace[264967756] 'range keys from in-memory index tree' (duration: 107.147189ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:30.544029Z","caller":"traceutil/trace.go:171","msg":"trace[172316541] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"114.83014ms","start":"2026-07-27T20:14:30.429176Z","end":"2026-07-27T20:14:30.544007Z","steps":["trace[172316541] 'process raft request' (duration: 114.644625ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:30.583282Z","caller":"traceutil/trace.go:171","msg":"trace[1922313586] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"137.671266ms","start":"2026-07-27T20:14:30.445592Z","end":"2026-07-27T20:14:30.583263Z","steps":["trace[1922313586] 'read index received' (duration: 98.230406ms)","trace[1922313586] 'applied index is now lower than readState.Index' (duration: 39.43964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:14:30.583409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.805709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:30.583433Z","caller":"traceutil/trace.go:171","msg":"trace[1780003948] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1237; }","duration":"137.84796ms","start":"2026-07-27T20:14:30.445576Z","end":"2026-07-27T20:14:30.583424Z","steps":["trace[1780003948] 'agreement among raft nodes before linearized reading' (duration: 137.780758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:30.583742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.128501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-27T20:14:30.583758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.140973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-27T20:14:30.58377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.096647ms","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-27T20:14:30.583884Z","caller":"traceutil/trace.go:171","msg":"trace[1831595179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"119.238351ms","start":"2026-07-27T20:14:30.464624Z","end":"2026-07-27T20:14:30.583862Z","steps":["trace[1831595179] 'agreement among raft nodes before linearized reading' (duration: 118.958185ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:30.583811Z","caller":"traceutil/trace.go:171","msg":"trace[510660137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"111.209905ms","start":"2026-07-27T20:14:30.472587Z","end":"2026-07-27T20:14:30.583797Z","steps":["trace[510660137] 'agreement among raft nodes before linearized reading' (duration: 110.993081ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:30.58381Z","caller":"traceutil/trace.go:171","msg":"trace[900145353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1237; }","duration":"123.233793ms","start":"2026-07-27T20:14:30.46056Z","end":"2026-07-27T20:14:30.583794Z","steps":["trace[900145353] 'agreement among raft nodes before linearized reading' (duration: 123.055829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:30.584482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.922294ms","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-27T20:14:30.584539Z","caller":"traceutil/trace.go:171","msg":"trace[1139688907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"135.003996ms","start":"2026-07-27T20:14:30.449517Z","end":"2026-07-27T20:14:30.584521Z","steps":["trace[1139688907] 'agreement among raft nodes before linearized reading' (duration: 134.032923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:33.048717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147180859381398,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T20:14:33.054612Z","caller":"traceutil/trace.go:171","msg":"trace[1366684958] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"959.376304ms","start":"2026-07-27T20:14:32.095216Z","end":"2026-07-27T20:14:33.054592Z","steps":["trace[1366684958] 'process raft request' (duration: 959.243181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:33.05473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.095192Z","time spent":"959.473796ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:34.153467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.225567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:14:34.153586Z","caller":"traceutil/trace.go:171","msg":"trace[843290122] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"1.605766808s","start":"2026-07-27T20:14:32.547785Z","end":"2026-07-27T20:14:34.153552Z","steps":["trace[843290122] 'read index received' (duration: 506.659479ms)","trace[843290122] 'applied index is now lower than readState.Index' (duration: 1.099106359s)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:34.154019Z","caller":"traceutil/trace.go:171","msg":"trace[172984995] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"1.459612816s","start":"2026-07-27T20:14:32.694181Z","end":"2026-07-27T20:14:34.153794Z","steps":["trace[172984995] 'process raft request' (duration: 1.083974959s)","trace[172984995] 'compare' (duration: 375.083643ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:14:34.15412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.694162Z","time spent":"1.459911372s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:34.154243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520187973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-27T20:14:34.154318Z","caller":"traceutil/trace.go:171","msg":"trace[2092485599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"1.520311826s","start":"2026-07-27T20:14:32.633988Z","end":"2026-07-27T20:14:34.1543Z","steps":["trace[2092485599] 'agreement among raft nodes before linearized reading' (duration: 1.520010769s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:34.154336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514915722s","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":"warn","ts":"2026-07-27T20:14:34.154328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51486473s","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-27T20:14:34.15437Z","caller":"traceutil/trace.go:171","msg":"trace[641425426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"1.514971192s","start":"2026-07-27T20:14:32.639388Z","end":"2026-07-27T20:14:34.154359Z","steps":["trace[641425426] 'agreement among raft nodes before linearized reading' (duration: 1.5148524s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:34.154372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.63397Z","time spent":"1.520390588s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:14:34.154403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.63938Z","time spent":"1.515015844s","remote":"127.0.0.1:47894","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-27T20:14:34.154415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.660401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:34.154431Z","caller":"traceutil/trace.go:171","msg":"trace[1593785183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"197.702442ms","start":"2026-07-27T20:14:33.956724Z","end":"2026-07-27T20:14:34.154426Z","steps":["trace[1593785183] 'agreement among raft nodes before linearized reading' (duration: 197.677711ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:34.154382Z","caller":"traceutil/trace.go:171","msg":"trace[1181244050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"1.514932932s","start":"2026-07-27T20:14:32.639436Z","end":"2026-07-27T20:14:34.154369Z","steps":["trace[1181244050] 'agreement among raft nodes before linearized reading' (duration: 1.514748447s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:34.154542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.639398Z","time spent":"1.515115606s","remote":"127.0.0.1:47894","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-27T20:14:34.15457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516127649s","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-27T20:14:34.154655Z","caller":"traceutil/trace.go:171","msg":"trace[1324273945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"1.516251431s","start":"2026-07-27T20:14:32.638375Z","end":"2026-07-27T20:14:34.154626Z","steps":["trace[1324273945] 'agreement among raft nodes before linearized reading' (duration: 1.516028796s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:34.154723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.638355Z","time spent":"1.516351274s","remote":"127.0.0.1:47894","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-27T20:14:34.155312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607523878s","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-27T20:14:34.155352Z","caller":"traceutil/trace.go:171","msg":"trace[837766614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"1.60759156s","start":"2026-07-27T20:14:32.547748Z","end":"2026-07-27T20:14:34.15534Z","steps":["trace[837766614] 'agreement among raft nodes before linearized reading' (duration: 1.60716302s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:34.155384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:32.547732Z","time spent":"1.60764454s","remote":"127.0.0.1:47894","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-27T20:14:36.34935Z","caller":"traceutil/trace.go:171","msg":"trace[2059603178] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"179.774737ms","start":"2026-07-27T20:14:36.169555Z","end":"2026-07-27T20:14:36.34933Z","steps":["trace[2059603178] 'process raft request' (duration: 179.593573ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:36.349365Z","caller":"traceutil/trace.go:171","msg":"trace[1518237119] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"179.784438ms","start":"2026-07-27T20:14:36.169566Z","end":"2026-07-27T20:14:36.349351Z","steps":["trace[1518237119] 'process raft request' (duration: 179.685806ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:36.349346Z","caller":"traceutil/trace.go:171","msg":"trace[130257189] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"177.957745ms","start":"2026-07-27T20:14:36.171371Z","end":"2026-07-27T20:14:36.349329Z","steps":["trace[130257189] 'process raft request' (duration: 177.930605ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:36.349433Z","caller":"traceutil/trace.go:171","msg":"trace[1262295074] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"179.151914ms","start":"2026-07-27T20:14:36.170249Z","end":"2026-07-27T20:14:36.3494Z","steps":["trace[1262295074] 'process raft request' (duration: 179.028861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:45.090219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.975428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:45.090307Z","caller":"traceutil/trace.go:171","msg":"trace[2075493932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"133.109971ms","start":"2026-07-27T20:14:44.957177Z","end":"2026-07-27T20:14:45.090287Z","steps":["trace[2075493932] 'range keys from in-memory index tree' (duration: 132.902277ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:48.480949Z","caller":"traceutil/trace.go:171","msg":"trace[727216105] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"153.783478ms","start":"2026-07-27T20:14:48.327145Z","end":"2026-07-27T20:14:48.480929Z","steps":["trace[727216105] 'process raft request' (duration: 153.638455ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:48.593685Z","caller":"traceutil/trace.go:171","msg":"trace[2023744680] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"108.77933ms","start":"2026-07-27T20:14:48.484876Z","end":"2026-07-27T20:14:48.593655Z","steps":["trace[2023744680] 'process raft request' (duration: 85.067623ms)","trace[2023744680] 'compare' (duration: 23.606225ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:48.609296Z","caller":"traceutil/trace.go:171","msg":"trace[110777852] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"102.69338ms","start":"2026-07-27T20:14:48.506578Z","end":"2026-07-27T20:14:48.609271Z","steps":["trace[110777852] 'process raft request' (duration: 102.491485ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:48.609546Z","caller":"traceutil/trace.go:171","msg":"trace[157632035] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"101.326518ms","start":"2026-07-27T20:14:48.508209Z","end":"2026-07-27T20:14:48.609535Z","steps":["trace[157632035] 'process raft request' (duration: 100.99792ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:48.609773Z","caller":"traceutil/trace.go:171","msg":"trace[443616930] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"101.560923ms","start":"2026-07-27T20:14:48.5082Z","end":"2026-07-27T20:14:48.609761Z","steps":["trace[443616930] 'process raft request' (duration: 100.97128ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:49.185251Z","caller":"traceutil/trace.go:171","msg":"trace[1189667647] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"228.182083ms","start":"2026-07-27T20:14:48.957043Z","end":"2026-07-27T20:14:49.185225Z","steps":["trace[1189667647] 'read index received' (duration: 228.078731ms)","trace[1189667647] 'applied index is now lower than readState.Index' (duration: 102.382µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:49.185307Z","caller":"traceutil/trace.go:171","msg":"trace[2132145842] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"564.433712ms","start":"2026-07-27T20:14:48.620844Z","end":"2026-07-27T20:14:49.185278Z","steps":["trace[2132145842] 'process raft request' (duration: 564.229267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:49.185365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.306277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:49.185396Z","caller":"traceutil/trace.go:171","msg":"trace[1236153628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"228.379398ms","start":"2026-07-27T20:14:48.957008Z","end":"2026-07-27T20:14:49.185387Z","steps":["trace[1236153628] 'agreement among raft nodes before linearized reading' (duration: 228.309907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:49.185449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:48.620828Z","time spent":"564.540425ms","remote":"127.0.0.1:47842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:49.96454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.734273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:14:49.964631Z","caller":"traceutil/trace.go:171","msg":"trace[398999241] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"778.128253ms","start":"2026-07-27T20:14:49.186487Z","end":"2026-07-27T20:14:49.964615Z","steps":["trace[398999241] 'read index received' (duration: 391.237347ms)","trace[398999241] 'applied index is now lower than readState.Index' (duration: 386.889546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:14:49.964729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.239226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:49.964708Z","caller":"traceutil/trace.go:171","msg":"trace[2032527906] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"858.631971ms","start":"2026-07-27T20:14:49.106042Z","end":"2026-07-27T20:14:49.964674Z","steps":["trace[2032527906] 'process raft request' (duration: 471.682383ms)","trace[2032527906] 'compare' (duration: 386.61532ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:49.964761Z","caller":"traceutil/trace.go:171","msg":"trace[908354565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"778.294187ms","start":"2026-07-27T20:14:49.186457Z","end":"2026-07-27T20:14:49.964752Z","steps":["trace[908354565] 'agreement among raft nodes before linearized reading' (duration: 778.239096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:49.964796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.186444Z","time spent":"778.345388ms","remote":"127.0.0.1:47660","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-27T20:14:49.964835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.106014Z","time spent":"858.751764ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:14:49.964878Z","caller":"traceutil/trace.go:171","msg":"trace[1908681666] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"762.557365ms","start":"2026-07-27T20:14:49.202304Z","end":"2026-07-27T20:14:49.964862Z","steps":["trace[1908681666] 'process raft request' (duration: 762.525124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:49.964976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.202268Z","time spent":"762.661116ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:14:49.965098Z","caller":"traceutil/trace.go:171","msg":"trace[254726097] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"765.309147ms","start":"2026-07-27T20:14:49.199783Z","end":"2026-07-27T20:14:49.965093Z","steps":["trace[254726097] 'process raft request' (duration: 765.023571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:49.96513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.199759Z","time spent":"765.354989ms","remote":"127.0.0.1:47910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:14:49.965205Z","caller":"traceutil/trace.go:171","msg":"trace[639046568] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"765.738218ms","start":"2026-07-27T20:14:49.199458Z","end":"2026-07-27T20:14:49.965196Z","steps":["trace[639046568] 'process raft request' (duration: 765.288687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:49.965293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.199437Z","time spent":"765.80097ms","remote":"127.0.0.1:47816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:14:50.333073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.740825ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-27T20:14:50.33315Z","caller":"traceutil/trace.go:171","msg":"trace[1694442541] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"368.45307ms","start":"2026-07-27T20:14:49.964682Z","end":"2026-07-27T20:14:50.333136Z","steps":["trace[1694442541] 'read index received' (duration: 143.674344ms)","trace[1694442541] 'applied index is now lower than readState.Index' (duration: 224.777616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:14:50.333244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001831293s","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-27T20:14:50.33327Z","caller":"traceutil/trace.go:171","msg":"trace[736208378] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1316; }","duration":"1.001892785s","start":"2026-07-27T20:14:49.33137Z","end":"2026-07-27T20:14:50.333263Z","steps":["trace[736208378] 'agreement among raft nodes before linearized reading' (duration: 1.001833594s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:50.333281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.888726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:14:50.333296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.331357Z","time spent":"1.001932175s","remote":"127.0.0.1:47788","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-27T20:14:50.333311Z","caller":"traceutil/trace.go:171","msg":"trace[1630422240] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1316; }","duration":"536.956758ms","start":"2026-07-27T20:14:49.796346Z","end":"2026-07-27T20:14:50.333302Z","steps":["trace[1630422240] 'agreement among raft nodes before linearized reading' (duration: 536.876716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:50.33333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.796325Z","time spent":"537.000208ms","remote":"127.0.0.1:40212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:14:50.333306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.059983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:14:50.33329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.017729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:50.333374Z","caller":"traceutil/trace.go:171","msg":"trace[1736913795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"362.157245ms","start":"2026-07-27T20:14:49.9712Z","end":"2026-07-27T20:14:50.333357Z","steps":["trace[1736913795] 'agreement among raft nodes before linearized reading' (duration: 362.038652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:50.333423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.971189Z","time spent":"362.227286ms","remote":"127.0.0.1:47666","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-27T20:14:50.333429Z","caller":"traceutil/trace.go:171","msg":"trace[176295336] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1316; }","duration":"833.190482ms","start":"2026-07-27T20:14:49.500212Z","end":"2026-07-27T20:14:50.333402Z","steps":["trace[176295336] 'agreement among raft nodes before linearized reading' (duration: 832.987147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:50.333476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:49.500196Z","time spent":"833.269184ms","remote":"127.0.0.1:47750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:14:50.749634Z","caller":"traceutil/trace.go:171","msg":"trace[1815371431] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"124.965994ms","start":"2026-07-27T20:14:50.624634Z","end":"2026-07-27T20:14:50.7496Z","steps":["trace[1815371431] 'process raft request' (duration: 60.692761ms)","trace[1815371431] 'compare' (duration: 64.035957ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:50.749677Z","caller":"traceutil/trace.go:171","msg":"trace[1257123550] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"123.930649ms","start":"2026-07-27T20:14:50.625712Z","end":"2026-07-27T20:14:50.749643Z","steps":["trace[1257123550] 'process raft request' (duration: 123.812256ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:52.427557Z","caller":"traceutil/trace.go:171","msg":"trace[359103070] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"458.587761ms","start":"2026-07-27T20:14:51.968942Z","end":"2026-07-27T20:14:52.42753Z","steps":["trace[359103070] 'process raft request' (duration: 458.389756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:52.427706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:51.968922Z","time spent":"458.707023ms","remote":"127.0.0.1:47894","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-27T20:14:52.633552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.670024ms","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-27T20:14:52.633647Z","caller":"traceutil/trace.go:171","msg":"trace[1041068728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"107.814437ms","start":"2026-07-27T20:14:52.525812Z","end":"2026-07-27T20:14:52.633626Z","steps":["trace[1041068728] 'range keys from in-memory index tree' (duration: 107.462729ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:52.810216Z","caller":"traceutil/trace.go:171","msg":"trace[283726759] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"172.642793ms","start":"2026-07-27T20:14:52.637521Z","end":"2026-07-27T20:14:52.810164Z","steps":["trace[283726759] 'process raft request' (duration: 172.469849ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:53.058167Z","caller":"traceutil/trace.go:171","msg":"trace[1598017155] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"396.55787ms","start":"2026-07-27T20:14:52.661588Z","end":"2026-07-27T20:14:53.058145Z","steps":["trace[1598017155] 'read index received' (duration: 148.879185ms)","trace[1598017155] 'applied index is now lower than readState.Index' (duration: 247.677715ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:14:53.058356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.403206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:53.058408Z","caller":"traceutil/trace.go:171","msg":"trace[259606681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"103.490507ms","start":"2026-07-27T20:14:52.954905Z","end":"2026-07-27T20:14:53.058396Z","steps":["trace[259606681] 'agreement among raft nodes before linearized reading' (duration: 103.403776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:53.058382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.854156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:14:53.058452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.444824ms","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-27T20:14:53.058471Z","caller":"traceutil/trace.go:171","msg":"trace[1337585345] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1325; }","duration":"318.98432ms","start":"2026-07-27T20:14:52.739457Z","end":"2026-07-27T20:14:53.058442Z","steps":["trace[1337585345] 'agreement among raft nodes before linearized reading' (duration: 318.845316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:53.058447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.845646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-27T20:14:53.05849Z","caller":"traceutil/trace.go:171","msg":"trace[1106137616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"304.506576ms","start":"2026-07-27T20:14:52.753973Z","end":"2026-07-27T20:14:53.058479Z","steps":["trace[1106137616] 'agreement among raft nodes before linearized reading' (duration: 304.356783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:53.058532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:52.753968Z","time spent":"304.556017ms","remote":"127.0.0.1:47894","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-27T20:14:53.058529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:52.739442Z","time spent":"319.072471ms","remote":"127.0.0.1:56444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:14:53.058526Z","caller":"traceutil/trace.go:171","msg":"trace[1128671221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"396.955788ms","start":"2026-07-27T20:14:52.661558Z","end":"2026-07-27T20:14:53.058514Z","steps":["trace[1128671221] 'agreement among raft nodes before linearized reading' (duration: 396.699702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:53.058575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:52.661546Z","time spent":"397.01923ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:14:53.058607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.615908ms","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-27T20:14:53.058209Z","caller":"traceutil/trace.go:171","msg":"trace[1653996927] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"420.4524ms","start":"2026-07-27T20:14:52.637724Z","end":"2026-07-27T20:14:53.058177Z","steps":["trace[1653996927] 'process raft request' (duration: 420.233146ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:53.058703Z","caller":"traceutil/trace.go:171","msg":"trace[205561940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"304.762042ms","start":"2026-07-27T20:14:52.753922Z","end":"2026-07-27T20:14:53.058684Z","steps":["trace[205561940] 'agreement among raft nodes before linearized reading' (duration: 304.466805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:53.058753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:52.753879Z","time spent":"304.863954ms","remote":"127.0.0.1:47894","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-27T20:14:53.058786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:52.637715Z","time spent":"421.016903ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:14:53.058902Z","caller":"traceutil/trace.go:171","msg":"trace[1672791341] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"340.290882ms","start":"2026-07-27T20:14:52.717956Z","end":"2026-07-27T20:14:53.058247Z","steps":["trace[1672791341] 'process raft request' (duration: 340.144399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:53.059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:14:52.717936Z","time spent":"341.012738ms","remote":"127.0.0.1:47746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:14:53.213818Z","caller":"traceutil/trace.go:171","msg":"trace[1141061908] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"150.624404ms","start":"2026-07-27T20:14:53.063173Z","end":"2026-07-27T20:14:53.213797Z","steps":["trace[1141061908] 'process raft request' (duration: 80.812985ms)","trace[1141061908] 'compare' (duration: 69.682576ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:14:53.214019Z","caller":"traceutil/trace.go:171","msg":"trace[748815969] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"150.733118ms","start":"2026-07-27T20:14:53.063257Z","end":"2026-07-27T20:14:53.21399Z","steps":["trace[748815969] 'process raft request' (duration: 150.511172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:14:54.09658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.401623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:14:54.096667Z","caller":"traceutil/trace.go:171","msg":"trace[813771220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"141.533826ms","start":"2026-07-27T20:14:53.955115Z","end":"2026-07-27T20:14:54.096649Z","steps":["trace[813771220] 'range keys from in-memory index tree' (duration: 141.278401ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:54.609528Z","caller":"traceutil/trace.go:171","msg":"trace[1990366378] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"177.445584ms","start":"2026-07-27T20:14:54.432056Z","end":"2026-07-27T20:14:54.609501Z","steps":["trace[1990366378] 'process raft request' (duration: 177.291451ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:54.719435Z","caller":"traceutil/trace.go:171","msg":"trace[1212322421] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"204.423127ms","start":"2026-07-27T20:14:54.514986Z","end":"2026-07-27T20:14:54.719409Z","steps":["trace[1212322421] 'process raft request' (duration: 200.071986ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:56.715194Z","caller":"traceutil/trace.go:171","msg":"trace[615604201] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"101.560673ms","start":"2026-07-27T20:14:56.61361Z","end":"2026-07-27T20:14:56.71517Z","steps":["trace[615604201] 'process raft request' (duration: 101.385939ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:15:03.226132Z","caller":"traceutil/trace.go:171","msg":"trace[1108880744] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"367.127342ms","start":"2026-07-27T20:15:02.858982Z","end":"2026-07-27T20:15:03.22611Z","steps":["trace[1108880744] 'read index received' (duration: 366.882895ms)","trace[1108880744] 'applied index is now lower than readState.Index' (duration: 243.277µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:15:03.226296Z","caller":"traceutil/trace.go:171","msg":"trace[529948272] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"492.574525ms","start":"2026-07-27T20:15:02.733652Z","end":"2026-07-27T20:15:03.226227Z","steps":["trace[529948272] 'process raft request' (duration: 492.318859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:03.226461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.709077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-27T20:15:03.226515Z","caller":"traceutil/trace.go:171","msg":"trace[819973467] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1400; }","duration":"329.813819ms","start":"2026-07-27T20:15:02.896685Z","end":"2026-07-27T20:15:03.226499Z","steps":["trace[819973467] 'agreement among raft nodes before linearized reading' (duration: 329.607985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:03.226498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.841474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-27T20:15:03.226554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:02.896662Z","time spent":"329.883142ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-27T20:15:03.226577Z","caller":"traceutil/trace.go:171","msg":"trace[1727742107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1400; }","duration":"125.964346ms","start":"2026-07-27T20:15:03.100591Z","end":"2026-07-27T20:15:03.226555Z","steps":["trace[1727742107] 'agreement among raft nodes before linearized reading' (duration: 125.750811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:03.226546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:02.733625Z","time spent":"492.81069ms","remote":"127.0.0.1:47894","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-27T20:15:03.226678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.272956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:15:03.226705Z","caller":"traceutil/trace.go:171","msg":"trace[363679183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"270.323807ms","start":"2026-07-27T20:15:02.956372Z","end":"2026-07-27T20:15:03.226696Z","steps":["trace[363679183] 'agreement among raft nodes before linearized reading' (duration: 270.285886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:03.227007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.024202ms","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-27T20:15:03.22708Z","caller":"traceutil/trace.go:171","msg":"trace[1162992746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1400; }","duration":"368.131744ms","start":"2026-07-27T20:15:02.858931Z","end":"2026-07-27T20:15:03.227063Z","steps":["trace[1162992746] 'agreement among raft nodes before linearized reading' (duration: 367.306076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:03.227126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:02.85891Z","time spent":"368.203075ms","remote":"127.0.0.1:47894","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-27T20:15:03.764381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147180859381740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T20:15:04.115431Z","caller":"traceutil/trace.go:171","msg":"trace[1369307474] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"883.513714ms","start":"2026-07-27T20:15:03.231871Z","end":"2026-07-27T20:15:04.115385Z","steps":["trace[1369307474] 'process raft request' (duration: 871.136659ms)","trace[1369307474] 'compare' (duration: 11.944756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:15:04.115577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.231856Z","time spent":"883.653477ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:15:04.340372Z","caller":"traceutil/trace.go:171","msg":"trace[1217657362] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1452; }","duration":"1.07621888s","start":"2026-07-27T20:15:03.264113Z","end":"2026-07-27T20:15:04.340331Z","steps":["trace[1217657362] 'read index received' (duration: 838.908406ms)","trace[1217657362] 'applied index is now lower than readState.Index' (duration: 237.309564ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:15:04.340404Z","caller":"traceutil/trace.go:171","msg":"trace[413599093] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"1.108464934s","start":"2026-07-27T20:15:03.231906Z","end":"2026-07-27T20:15:04.340371Z","steps":["trace[413599093] 'process raft request' (duration: 1.108207708s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.34054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.231888Z","time spent":"1.108588416s","remote":"127.0.0.1:47894","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-27T20:15:04.340569Z","caller":"traceutil/trace.go:171","msg":"trace[522893648] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"1.108030803s","start":"2026-07-27T20:15:03.232449Z","end":"2026-07-27T20:15:04.340479Z","steps":["trace[522893648] 'process raft request' (duration: 1.107829049s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.340699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070489278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-27T20:15:04.340736Z","caller":"traceutil/trace.go:171","msg":"trace[1250255965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1403; }","duration":"1.070543779s","start":"2026-07-27T20:15:03.27018Z","end":"2026-07-27T20:15:04.340723Z","steps":["trace[1250255965] 'agreement among raft nodes before linearized reading' (duration: 1.070461797s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.340743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.232428Z","time spent":"1.108224208s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:15:04.34077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.270167Z","time spent":"1.070595151s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-27T20:15:04.340612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076479156s","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-27T20:15:04.340868Z","caller":"traceutil/trace.go:171","msg":"trace[1693228126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"1.076778663s","start":"2026-07-27T20:15:03.26408Z","end":"2026-07-27T20:15:04.340859Z","steps":["trace[1693228126] 'agreement among raft nodes before linearized reading' (duration: 1.076393024s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.340914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.264068Z","time spent":"1.076836674s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:15:04.341045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064985152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-27T20:15:04.341076Z","caller":"traceutil/trace.go:171","msg":"trace[293115827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1403; }","duration":"1.065044004s","start":"2026-07-27T20:15:03.276023Z","end":"2026-07-27T20:15:04.341067Z","steps":["trace[293115827] 'agreement among raft nodes before linearized reading' (duration: 1.064935571s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.341308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.275991Z","time spent":"1.065302699s","remote":"127.0.0.1:47894","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":"warn","ts":"2026-07-27T20:15:04.341104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.06429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-27T20:15:04.341869Z","caller":"traceutil/trace.go:171","msg":"trace[2125537831] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1403; }","duration":"553.887749ms","start":"2026-07-27T20:15:03.787959Z","end":"2026-07-27T20:15:04.341847Z","steps":["trace[2125537831] 'agreement among raft nodes before linearized reading' (duration: 552.960677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.342033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.78794Z","time spent":"554.025613ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-27T20:15:04.341153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.760881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:15:04.342179Z","caller":"traceutil/trace.go:171","msg":"trace[27274911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1403; }","duration":"383.818746ms","start":"2026-07-27T20:15:03.958342Z","end":"2026-07-27T20:15:04.342161Z","steps":["trace[27274911] 'agreement among raft nodes before linearized reading' (duration: 382.789682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:15:04.342227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:15:03.958321Z","time spent":"383.891847ms","remote":"127.0.0.1:47666","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-27T20:16:39.244892Z","caller":"traceutil/trace.go:171","msg":"trace[2003499360] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"391.537454ms","start":"2026-07-27T20:16:38.853328Z","end":"2026-07-27T20:16:39.244865Z","steps":["trace[2003499360] 'read index received' (duration: 391.315849ms)","trace[2003499360] 'applied index is now lower than readState.Index' (duration: 220.635µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:39.244978Z","caller":"traceutil/trace.go:171","msg":"trace[230708154] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"424.132376ms","start":"2026-07-27T20:16:38.820798Z","end":"2026-07-27T20:16:39.24493Z","steps":["trace[230708154] 'process raft request' (duration: 423.89299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:39.24513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.020933ms","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-27T20:16:39.245145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:16:38.82078Z","time spent":"424.280639ms","remote":"127.0.0.1:47894","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-27T20:16:39.245174Z","caller":"traceutil/trace.go:171","msg":"trace[497101499] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1837; }","duration":"388.103435ms","start":"2026-07-27T20:16:38.857061Z","end":"2026-07-27T20:16:39.245164Z","steps":["trace[497101499] 'agreement among raft nodes before linearized reading' (duration: 387.957732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:39.24521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:16:38.85705Z","time spent":"388.151866ms","remote":"127.0.0.1:47894","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-27T20:16:39.245283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.058234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-27T20:16:39.245449Z","caller":"traceutil/trace.go:171","msg":"trace[190479195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1837; }","duration":"388.217558ms","start":"2026-07-27T20:16:38.857196Z","end":"2026-07-27T20:16:39.245413Z","steps":["trace[190479195] 'agreement among raft nodes before linearized reading' (duration: 387.957212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:39.245359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.057494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-27T20:16:39.245506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:16:38.85716Z","time spent":"388.33371ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-27T20:16:39.245511Z","caller":"traceutil/trace.go:171","msg":"trace[1784303824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1837; }","duration":"388.281139ms","start":"2026-07-27T20:16:38.857214Z","end":"2026-07-27T20:16:39.245495Z","steps":["trace[1784303824] 'agreement among raft nodes before linearized reading' (duration: 387.930731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:39.245551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:16:38.857194Z","time spent":"388.346561ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-27T20:16:39.245338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.991605ms","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-27T20:16:39.245593Z","caller":"traceutil/trace.go:171","msg":"trace[1920304225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1837; }","duration":"392.266661ms","start":"2026-07-27T20:16:38.853311Z","end":"2026-07-27T20:16:39.245578Z","steps":["trace[1920304225] 'agreement among raft nodes before linearized reading' (duration: 391.684068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:39.24565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:16:38.853248Z","time spent":"392.390134ms","remote":"127.0.0.1:47894","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":"info","ts":"2026-07-27T20:19:25.75544Z","caller":"traceutil/trace.go:171","msg":"trace[1597530975] linearizableReadLoop","detail":"{readStateIndex:2734; appliedIndex:2733; }","duration":"307.190523ms","start":"2026-07-27T20:19:25.448216Z","end":"2026-07-27T20:19:25.755406Z","steps":["trace[1597530975] 'read index received' (duration: 306.900437ms)","trace[1597530975] 'applied index is now lower than readState.Index' (duration: 288.736µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:19:25.755599Z","caller":"traceutil/trace.go:171","msg":"trace[65757957] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"413.314249ms","start":"2026-07-27T20:19:25.342245Z","end":"2026-07-27T20:19:25.75556Z","steps":["trace[65757957] 'process raft request' (duration: 412.965991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:19:25.755772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.517921ms","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-27T20:19:25.755827Z","caller":"traceutil/trace.go:171","msg":"trace[712080869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2628; }","duration":"307.605433ms","start":"2026-07-27T20:19:25.448205Z","end":"2026-07-27T20:19:25.755811Z","steps":["trace[712080869] 'agreement among raft nodes before linearized reading' (duration: 307.372737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:19:25.755817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:19:25.34223Z","time spent":"413.478402ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:19:25.75589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:19:25.448163Z","time spent":"307.708485ms","remote":"127.0.0.1:47894","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\" "}