{"level":"warn","ts":"2026-07-09T13:10:06.911247Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T13:10:06.911365Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.23: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.23:2380","--initial-cluster=instance=https://199.19.213.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.23:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T13:10:06.911638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T13:10:06.911661Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-07-09T13:10:06.911756Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T13:10:06.912659Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"]} {"level":"info","ts":"2026-07-09T13:10:06.912805Z","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.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.23:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T13:10:06.918985Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.722801ms"} {"level":"info","ts":"2026-07-09T13:10:06.928137Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1812db8446d3ac67","cluster-id":"57ff06cb872baa33"} {"level":"info","ts":"2026-07-09T13:10:06.928247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T13:10:06.928284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 0"} {"level":"info","ts":"2026-07-09T13:10:06.928457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1812db8446d3ac67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T13:10:06.928468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 1"} {"level":"info","ts":"2026-07-09T13:10:06.928586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"warn","ts":"2026-07-09T13:10:06.936402Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T13:10:06.939302Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T13:10:06.940687Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T13:10:06.943318Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1812db8446d3ac67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T13:10:06.943442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1812db8446d3ac67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T13:10:06.943712Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T13:10:06.945362Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T13:10:06.945394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T13:10:06.950062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"info","ts":"2026-07-09T13:10:06.950528Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","added-peer-id":"1812db8446d3ac67","added-peer-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-07-09T13:10:06.952413Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T13:10:06.952511Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-07-09T13:10:06.952557Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-07-09T13:10:06.952597Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1812db8446d3ac67","initial-advertise-peer-urls":["https://199.19.213.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T13:10:06.952619Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T13:10:07.429593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T13:10:07.429666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T13:10:07.429704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgPreVoteResp from 1812db8446d3ac67 at term 1"} {"level":"info","ts":"2026-07-09T13:10:07.429726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became candidate at term 2"} {"level":"info","ts":"2026-07-09T13:10:07.429738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgVoteResp from 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-07-09T13:10:07.429754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became leader at term 2"} {"level":"info","ts":"2026-07-09T13:10:07.429777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1812db8446d3ac67 elected leader 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-07-09T13:10:07.430529Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:10:07.434342Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:10:07.434358Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1812db8446d3ac67","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.23:2379]}","request-path":"/0/members/1812db8446d3ac67/attributes","cluster-id":"57ff06cb872baa33","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T13:10:07.434583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:10:07.434789Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:10:07.434941Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:10:07.434988Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:10:07.43626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T13:10:07.438578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.23:2379"} {"level":"info","ts":"2026-07-09T13:10:07.438835Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T13:10:07.43886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T13:10:19.652212Z","caller":"traceutil/trace.go:171","msg":"trace[590102412] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"119.336348ms","start":"2026-07-09T13:10:19.532849Z","end":"2026-07-09T13:10:19.652186Z","steps":["trace[590102412] 'process raft request' (duration: 119.161283ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:26.514502Z","caller":"traceutil/trace.go:171","msg":"trace[637275706] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"235.583164ms","start":"2026-07-09T13:10:26.278898Z","end":"2026-07-09T13:10:26.514481Z","steps":["trace[637275706] 'process raft request' (duration: 235.44544ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:29.961364Z","caller":"traceutil/trace.go:171","msg":"trace[264070514] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"196.578309ms","start":"2026-07-09T13:10:29.76476Z","end":"2026-07-09T13:10:29.961338Z","steps":["trace[264070514] 'process raft request' (duration: 196.416844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:33.353019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196495772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:10:33.353115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.156047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-07-09T13:10:33.353125Z","caller":"traceutil/trace.go:171","msg":"trace[2143337840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"1.196682677s","start":"2026-07-09T13:10:32.156421Z","end":"2026-07-09T13:10:33.353104Z","steps":["trace[2143337840] 'range keys from in-memory index tree' (duration: 1.196412089s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:33.353148Z","caller":"traceutil/trace.go:171","msg":"trace[495696163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:432; }","duration":"976.218979ms","start":"2026-07-09T13:10:32.376915Z","end":"2026-07-09T13:10:33.353134Z","steps":["trace[495696163] 'range keys from in-memory index tree' (duration: 976.056375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:33.353179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:32.376891Z","time spent":"976.281831ms","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4390,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-09T13:10:33.353054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.554614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:10:33.353239Z","caller":"traceutil/trace.go:171","msg":"trace[1349824283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:432; }","duration":"818.78578ms","start":"2026-07-09T13:10:32.534441Z","end":"2026-07-09T13:10:33.353227Z","steps":["trace[1349824283] 'range keys from in-memory index tree' (duration: 818.475741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:33.353266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:32.534425Z","time spent":"818.834301ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:10:33.35317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:32.1564Z","time spent":"1.196759499s","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T13:10:33.353264Z","caller":"traceutil/trace.go:171","msg":"trace[1894477039] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"593.4921ms","start":"2026-07-09T13:10:32.759739Z","end":"2026-07-09T13:10:33.353231Z","steps":["trace[1894477039] 'process raft request' (duration: 529.398954ms)","trace[1894477039] 'compare' (duration: 63.626193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:33.359593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:32.75971Z","time spent":"593.699145ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:10:35.05944Z","caller":"traceutil/trace.go:171","msg":"trace[2103797753] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"140.171014ms","start":"2026-07-09T13:10:34.919252Z","end":"2026-07-09T13:10:35.059423Z","steps":["trace[2103797753] 'process raft request' (duration: 140.04573ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:35.209962Z","caller":"traceutil/trace.go:171","msg":"trace[1547253238] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"144.674757ms","start":"2026-07-09T13:10:35.065252Z","end":"2026-07-09T13:10:35.209927Z","steps":["trace[1547253238] 'process raft request' (duration: 49.439362ms)","trace[1547253238] 'compare' (duration: 94.890076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:51.953448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415466677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T13:10:52.269789Z","caller":"traceutil/trace.go:171","msg":"trace[1033562446] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"826.416281ms","start":"2026-07-09T13:10:51.443348Z","end":"2026-07-09T13:10:52.269764Z","steps":["trace[1033562446] 'process raft request' (duration: 826.274358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.269934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.443327Z","time spent":"826.539074ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:52.28047Z","caller":"traceutil/trace.go:171","msg":"trace[2061287830] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"833.738124ms","start":"2026-07-09T13:10:51.44669Z","end":"2026-07-09T13:10:52.280428Z","steps":["trace[2061287830] 'process raft request' (duration: 833.536778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.280653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.446662Z","time spent":"833.889668ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:52.280711Z","caller":"traceutil/trace.go:171","msg":"trace[908315754] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"824.063047ms","start":"2026-07-09T13:10:51.456636Z","end":"2026-07-09T13:10:52.280699Z","steps":["trace[908315754] 'process raft request' (duration: 823.760339ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:52.280499Z","caller":"traceutil/trace.go:171","msg":"trace[253415329] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"825.803225ms","start":"2026-07-09T13:10:51.454676Z","end":"2026-07-09T13:10:52.28048Z","steps":["trace[253415329] 'process raft request' (duration: 825.680802ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:52.280694Z","caller":"traceutil/trace.go:171","msg":"trace[1847145441] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"827.403009ms","start":"2026-07-09T13:10:51.453094Z","end":"2026-07-09T13:10:52.280497Z","steps":["trace[1847145441] 'process raft request' (duration: 827.222774ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:52.280613Z","caller":"traceutil/trace.go:171","msg":"trace[389197795] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"819.8069ms","start":"2026-07-09T13:10:51.460745Z","end":"2026-07-09T13:10:52.280552Z","steps":["trace[389197795] 'process raft request' (duration: 819.685287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.28083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.460734Z","time spent":"820.072057ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:52.280837Z","caller":"traceutil/trace.go:171","msg":"trace[1717301999] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:557; }","duration":"827.775118ms","start":"2026-07-09T13:10:51.453049Z","end":"2026-07-09T13:10:52.280824Z","steps":["trace[1717301999] 'read index received' (duration: 816.736984ms)","trace[1717301999] 'applied index is now lower than readState.Index' (duration: 11.037584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:52.280852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.456611Z","time spent":"824.154849ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:52.280835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.454668Z","time spent":"826.115763ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:52.280935Z","caller":"traceutil/trace.go:171","msg":"trace[949063261] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"777.607816ms","start":"2026-07-09T13:10:51.503317Z","end":"2026-07-09T13:10:52.280925Z","steps":["trace[949063261] 'process raft request' (duration: 777.39362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.281021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.503296Z","time spent":"777.703289ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:52.280956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.453086Z","time spent":"827.691766ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:52.28103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.973014ms","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-09T13:10:52.281057Z","caller":"traceutil/trace.go:171","msg":"trace[1787181431] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"808.422345ms","start":"2026-07-09T13:10:51.472622Z","end":"2026-07-09T13:10:52.281045Z","steps":["trace[1787181431] 'process raft request' (duration: 807.937242ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:52.281077Z","caller":"traceutil/trace.go:171","msg":"trace[257543180] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"820.248472ms","start":"2026-07-09T13:10:51.46082Z","end":"2026-07-09T13:10:52.281068Z","steps":["trace[257543180] 'process raft request' (duration: 819.654356ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:52.281097Z","caller":"traceutil/trace.go:171","msg":"trace[65113729] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"803.301765ms","start":"2026-07-09T13:10:51.477785Z","end":"2026-07-09T13:10:52.281087Z","steps":["trace[65113729] 'process raft request' (duration: 802.838502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.28111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.460813Z","time spent":"820.281573ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:52.280737Z","caller":"traceutil/trace.go:171","msg":"trace[757498009] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"818.145423ms","start":"2026-07-09T13:10:51.462529Z","end":"2026-07-09T13:10:52.280675Z","steps":["trace[757498009] 'process raft request' (duration: 817.979069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.281153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.477772Z","time spent":"803.362536ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:52.281069Z","caller":"traceutil/trace.go:171","msg":"trace[1883417561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"828.037427ms","start":"2026-07-09T13:10:51.453022Z","end":"2026-07-09T13:10:52.281059Z","steps":["trace[1883417561] 'agreement among raft nodes before linearized reading' (duration: 827.951024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.281123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.472602Z","time spent":"808.492648ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:52.281234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.462521Z","time spent":"818.678838ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:52.281281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.453011Z","time spent":"828.248032ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T13:10:52.280952Z","caller":"traceutil/trace.go:171","msg":"trace[904148129] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"660.949951ms","start":"2026-07-09T13:10:51.619994Z","end":"2026-07-09T13:10:52.280943Z","steps":["trace[904148129] 'process raft request' (duration: 660.774426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.281361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.619973Z","time spent":"661.364122ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:52.299389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.198087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:10:52.29944Z","caller":"traceutil/trace.go:171","msg":"trace[1929919942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"846.266919ms","start":"2026-07-09T13:10:51.453162Z","end":"2026-07-09T13:10:52.299429Z","steps":["trace[1929919942] 'agreement among raft nodes before linearized reading' (duration: 846.167286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.299462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.453156Z","time spent":"846.301049ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:10:52.29987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.441082ms","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-09T13:10:52.299896Z","caller":"traceutil/trace.go:171","msg":"trace[115190347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"756.493723ms","start":"2026-07-09T13:10:51.543395Z","end":"2026-07-09T13:10:52.299889Z","steps":["trace[115190347] 'agreement among raft nodes before linearized reading' (duration: 756.446982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.29997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.182254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:52.299988Z","caller":"traceutil/trace.go:171","msg":"trace[1322308265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"144.252627ms","start":"2026-07-09T13:10:52.155731Z","end":"2026-07-09T13:10:52.299983Z","steps":["trace[1322308265] 'agreement among raft nodes before linearized reading' (duration: 144.226556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:52.3001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:51.543125Z","time spent":"756.783252ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:10:52.300093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.716811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T13:10:52.300186Z","caller":"traceutil/trace.go:171","msg":"trace[1161657559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"242.839145ms","start":"2026-07-09T13:10:52.057291Z","end":"2026-07-09T13:10:52.30013Z","steps":["trace[1161657559] 'agreement among raft nodes before linearized reading' (duration: 242.703791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:55.538786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.064319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:55.538902Z","caller":"traceutil/trace.go:171","msg":"trace[1946313054] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:624; }","duration":"141.231263ms","start":"2026-07-09T13:10:55.397641Z","end":"2026-07-09T13:10:55.538872Z","steps":["trace[1946313054] 'range keys from in-memory index tree' (duration: 140.956085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:56.654462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415466811,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T13:10:56.820699Z","caller":"traceutil/trace.go:171","msg":"trace[1929226105] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"769.362101ms","start":"2026-07-09T13:10:56.051305Z","end":"2026-07-09T13:10:56.820668Z","steps":["trace[1929226105] 'process raft request' (duration: 745.78542ms)","trace[1929226105] 'compare' (duration: 23.288502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:56.820841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:56.051284Z","time spent":"769.495274ms","remote":"127.0.0.1:51386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T13:10:57.028227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.378035ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-09T13:10:57.028382Z","caller":"traceutil/trace.go:171","msg":"trace[1655886343] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"874.461986ms","start":"2026-07-09T13:10:56.153895Z","end":"2026-07-09T13:10:57.028357Z","steps":["trace[1655886343] 'read index received' (duration: 643.211641ms)","trace[1655886343] 'applied index is now lower than readState.Index' (duration: 231.248275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:57.028567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.651202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:57.028598Z","caller":"traceutil/trace.go:171","msg":"trace[1355019714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"874.724943ms","start":"2026-07-09T13:10:56.15386Z","end":"2026-07-09T13:10:57.028585Z","steps":["trace[1355019714] 'agreement among raft nodes before linearized reading' (duration: 874.638011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:57.028641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:56.153838Z","time spent":"874.793045ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:10:57.028834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.509006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T13:10:57.028901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.015103ms","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-09T13:10:57.028881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.880962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:57.028958Z","caller":"traceutil/trace.go:171","msg":"trace[510970858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"715.65783ms","start":"2026-07-09T13:10:56.313252Z","end":"2026-07-09T13:10:57.02891Z","steps":["trace[510970858] 'agreement among raft nodes before linearized reading' (duration: 715.369732ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:57.028987Z","caller":"traceutil/trace.go:171","msg":"trace[1116966032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"669.150806ms","start":"2026-07-09T13:10:56.359817Z","end":"2026-07-09T13:10:57.028968Z","steps":["trace[1116966032] 'agreement among raft nodes before linearized reading' (duration: 668.91742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:57.029018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:56.313232Z","time spent":"715.774942ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:10:57.029032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:56.359799Z","time spent":"669.223778ms","remote":"127.0.0.1:51440","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-09T13:10:57.029024Z","caller":"traceutil/trace.go:171","msg":"trace[27247995] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:626; }","duration":"195.067587ms","start":"2026-07-09T13:10:56.833924Z","end":"2026-07-09T13:10:57.028992Z","steps":["trace[27247995] 'agreement among raft nodes before linearized reading' (duration: 194.788879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:57.029446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.945235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T13:10:57.029452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.92132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:10:57.029497Z","caller":"traceutil/trace.go:171","msg":"trace[100450617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"667.018027ms","start":"2026-07-09T13:10:56.362466Z","end":"2026-07-09T13:10:57.029485Z","steps":["trace[100450617] 'agreement among raft nodes before linearized reading' (duration: 666.218676ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:57.029515Z","caller":"traceutil/trace.go:171","msg":"trace[1280752090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"669.005833ms","start":"2026-07-09T13:10:56.36049Z","end":"2026-07-09T13:10:57.029495Z","steps":["trace[1280752090] 'agreement among raft nodes before linearized reading' (duration: 668.18538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:57.029579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:56.360472Z","time spent":"669.092285ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:10:57.029529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:56.362455Z","time spent":"667.067408ms","remote":"127.0.0.1:51440","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":"info","ts":"2026-07-09T13:10:57.216963Z","caller":"traceutil/trace.go:171","msg":"trace[1489512126] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"182.714496ms","start":"2026-07-09T13:10:57.034222Z","end":"2026-07-09T13:10:57.216936Z","steps":["trace[1489512126] 'process raft request' (duration: 177.541174ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:57.217205Z","caller":"traceutil/trace.go:171","msg":"trace[1431125686] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"181.942725ms","start":"2026-07-09T13:10:57.035215Z","end":"2026-07-09T13:10:57.217194Z","steps":["trace[1431125686] 'process raft request' (duration: 181.646477ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:57.217468Z","caller":"traceutil/trace.go:171","msg":"trace[1249344396] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"181.994147ms","start":"2026-07-09T13:10:57.035448Z","end":"2026-07-09T13:10:57.217442Z","steps":["trace[1249344396] 'process raft request' (duration: 181.710319ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:58.590621Z","caller":"traceutil/trace.go:171","msg":"trace[1912846832] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"102.380881ms","start":"2026-07-09T13:10:58.488212Z","end":"2026-07-09T13:10:58.590593Z","steps":["trace[1912846832] 'process raft request' (duration: 101.863617ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:00.486099Z","caller":"traceutil/trace.go:171","msg":"trace[39553045] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"333.175554ms","start":"2026-07-09T13:11:00.152895Z","end":"2026-07-09T13:11:00.48607Z","steps":["trace[39553045] 'read index received' (duration: 331.131348ms)","trace[39553045] 'applied index is now lower than readState.Index' (duration: 2.042876ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:00.486476Z","caller":"traceutil/trace.go:171","msg":"trace[1767266040] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"354.963795ms","start":"2026-07-09T13:11:00.131199Z","end":"2026-07-09T13:11:00.486163Z","steps":["trace[1767266040] 'process raft request' (duration: 353.431012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:00.486742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.340489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:11:00.487461Z","caller":"traceutil/trace.go:171","msg":"trace[554077258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"334.601273ms","start":"2026-07-09T13:11:00.152833Z","end":"2026-07-09T13:11:00.487435Z","steps":["trace[554077258] 'agreement among raft nodes before linearized reading' (duration: 333.358249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:00.487563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:00.152809Z","time spent":"334.720388ms","remote":"127.0.0.1:51176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:11:00.487653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:00.131168Z","time spent":"356.25901ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:00.575658Z","caller":"traceutil/trace.go:171","msg":"trace[1230315323] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"331.91356ms","start":"2026-07-09T13:11:00.243692Z","end":"2026-07-09T13:11:00.575605Z","steps":["trace[1230315323] 'process raft request' (duration: 268.43172ms)","trace[1230315323] 'compare' (duration: 63.233413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:11:00.57586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:00.243663Z","time spent":"332.095384ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:00.730768Z","caller":"traceutil/trace.go:171","msg":"trace[1587997373] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"184.943199ms","start":"2026-07-09T13:11:00.545779Z","end":"2026-07-09T13:11:00.730722Z","steps":["trace[1587997373] 'process raft request' (duration: 160.054572ms)","trace[1587997373] 'compare' (duration: 24.745563ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:00.773452Z","caller":"traceutil/trace.go:171","msg":"trace[1492449604] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"182.323087ms","start":"2026-07-09T13:11:00.591092Z","end":"2026-07-09T13:11:00.773415Z","steps":["trace[1492449604] 'process raft request' (duration: 182.07698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:01.360884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415466862,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:11:01.676976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.421511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:11:01.677069Z","caller":"traceutil/trace.go:171","msg":"trace[1220536656] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"817.230436ms","start":"2026-07-09T13:11:00.859823Z","end":"2026-07-09T13:11:01.677054Z","steps":["trace[1220536656] 'read index received' (duration: 520.61321ms)","trace[1220536656] 'applied index is now lower than readState.Index' (duration: 296.616126ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:11:01.677176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.35707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-09T13:11:01.677196Z","caller":"traceutil/trace.go:171","msg":"trace[1751913684] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:652; }","duration":"817.380011ms","start":"2026-07-09T13:11:00.859809Z","end":"2026-07-09T13:11:01.677189Z","steps":["trace[1751913684] 'agreement among raft nodes before linearized reading' (duration: 817.309079ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.677169Z","caller":"traceutil/trace.go:171","msg":"trace[2134115457] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"886.875296ms","start":"2026-07-09T13:11:00.790238Z","end":"2026-07-09T13:11:01.677113Z","steps":["trace[2134115457] 'process raft request' (duration: 590.26512ms)","trace[2134115457] 'compare' (duration: 296.319218ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:11:01.677222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:00.859744Z","time spent":"817.472992ms","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.23\" "} {"level":"warn","ts":"2026-07-09T13:11:01.677291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.897569ms","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-09T13:11:01.677309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:00.790225Z","time spent":"887.018889ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:11:01.677357Z","caller":"traceutil/trace.go:171","msg":"trace[1090714834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"541.027593ms","start":"2026-07-09T13:11:01.136312Z","end":"2026-07-09T13:11:01.67734Z","steps":["trace[1090714834] 'agreement among raft nodes before linearized reading' (duration: 540.848988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:01.677392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:01.136289Z","time spent":"541.096184ms","remote":"127.0.0.1:51440","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-09T13:11:01.677505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.25497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:11:01.677534Z","caller":"traceutil/trace.go:171","msg":"trace[354905069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"438.284071ms","start":"2026-07-09T13:11:01.239242Z","end":"2026-07-09T13:11:01.677526Z","steps":["trace[354905069] 'agreement among raft nodes before linearized reading' (duration: 438.193168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:01.677553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:01.239158Z","time spent":"438.390363ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:11:01.677557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.334062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T13:11:01.677578Z","caller":"traceutil/trace.go:171","msg":"trace[1809256359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"438.391934ms","start":"2026-07-09T13:11:01.239181Z","end":"2026-07-09T13:11:01.677573Z","steps":["trace[1809256359] 'agreement among raft nodes before linearized reading' (duration: 438.326672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:01.67756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.635835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:11:01.677597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:01.239162Z","time spent":"438.431335ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:11:01.677552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.282801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T13:11:01.677623Z","caller":"traceutil/trace.go:171","msg":"trace[98661836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"522.737098ms","start":"2026-07-09T13:11:01.154872Z","end":"2026-07-09T13:11:01.677609Z","steps":["trace[98661836] 'agreement among raft nodes before linearized reading' (duration: 522.639375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:01.677668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:01.154827Z","time spent":"522.83321ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T13:11:01.677677Z","caller":"traceutil/trace.go:171","msg":"trace[1402436165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"438.434945ms","start":"2026-07-09T13:11:01.239219Z","end":"2026-07-09T13:11:01.677654Z","steps":["trace[1402436165] 'agreement among raft nodes before linearized reading' (duration: 438.140087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:01.677715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:01.239206Z","time spent":"438.501037ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T13:11:01.933823Z","caller":"traceutil/trace.go:171","msg":"trace[2135448200] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"251.304768ms","start":"2026-07-09T13:11:01.682476Z","end":"2026-07-09T13:11:01.933781Z","steps":["trace[2135448200] 'process raft request' (duration: 213.956288ms)","trace[2135448200] 'compare' (duration: 36.908878ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:01.933907Z","caller":"traceutil/trace.go:171","msg":"trace[143420289] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"251.385991ms","start":"2026-07-09T13:11:01.68249Z","end":"2026-07-09T13:11:01.933876Z","steps":["trace[143420289] 'process raft request' (duration: 251.133874ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.934045Z","caller":"traceutil/trace.go:171","msg":"trace[373522808] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"251.267907ms","start":"2026-07-09T13:11:01.68273Z","end":"2026-07-09T13:11:01.933998Z","steps":["trace[373522808] 'process raft request' (duration: 250.962659ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.934552Z","caller":"traceutil/trace.go:171","msg":"trace[14030446] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"251.74968ms","start":"2026-07-09T13:11:01.682774Z","end":"2026-07-09T13:11:01.934523Z","steps":["trace[14030446] 'process raft request' (duration: 251.071102ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.934792Z","caller":"traceutil/trace.go:171","msg":"trace[315110527] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"251.835763ms","start":"2026-07-09T13:11:01.682933Z","end":"2026-07-09T13:11:01.934768Z","steps":["trace[315110527] 'process raft request' (duration: 251.501654ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.935058Z","caller":"traceutil/trace.go:171","msg":"trace[866637017] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"252.050528ms","start":"2026-07-09T13:11:01.682982Z","end":"2026-07-09T13:11:01.935033Z","steps":["trace[866637017] 'process raft request' (duration: 251.714219ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.93509Z","caller":"traceutil/trace.go:171","msg":"trace[712123972] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"170.229573ms","start":"2026-07-09T13:11:01.764843Z","end":"2026-07-09T13:11:01.935073Z","steps":["trace[712123972] 'process raft request' (duration: 170.182382ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.935209Z","caller":"traceutil/trace.go:171","msg":"trace[41929443] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"250.761192ms","start":"2026-07-09T13:11:01.684434Z","end":"2026-07-09T13:11:01.935195Z","steps":["trace[41929443] 'process raft request' (duration: 250.546187ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:01.93527Z","caller":"traceutil/trace.go:171","msg":"trace[1874754141] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"251.120712ms","start":"2026-07-09T13:11:01.684135Z","end":"2026-07-09T13:11:01.935256Z","steps":["trace[1874754141] 'process raft request' (duration: 250.762152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:02.516853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.60465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:11:02.516926Z","caller":"traceutil/trace.go:171","msg":"trace[889497673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"360.715063ms","start":"2026-07-09T13:11:02.156193Z","end":"2026-07-09T13:11:02.516908Z","steps":["trace[889497673] 'range keys from in-memory index tree' (duration: 360.507417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:02.516963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:02.156177Z","time spent":"360.778594ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:11:04.656698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415466886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:11:05.156793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415466886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:11:05.243674Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.300509981s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T13:11:05.244141Z","caller":"traceutil/trace.go:171","msg":"trace[1793194132] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.301054976s","start":"2026-07-09T13:11:03.943061Z","end":"2026-07-09T13:11:05.244116Z","steps":["trace[1793194132] 'process raft request' (duration: 1.300793449s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:05.244408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:03.943049Z","time spent":"1.301204101s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:05.310793Z","caller":"traceutil/trace.go:171","msg":"trace[2141151748] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.367606551s","start":"2026-07-09T13:11:03.943154Z","end":"2026-07-09T13:11:05.310761Z","steps":["trace[2141151748] 'process raft request' (duration: 1.367357244s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:05.310995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:03.943131Z","time spent":"1.367742865s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:05.310818Z","caller":"traceutil/trace.go:171","msg":"trace[860554294] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:686; }","duration":"1.154916528s","start":"2026-07-09T13:11:04.155878Z","end":"2026-07-09T13:11:05.310794Z","steps":["trace[860554294] 'read index received' (duration: 1.087976922s)","trace[860554294] 'applied index is now lower than readState.Index' (duration: 66.938596ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:05.3113Z","caller":"traceutil/trace.go:171","msg":"trace[440496511] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"990.35248ms","start":"2026-07-09T13:11:04.320924Z","end":"2026-07-09T13:11:05.311277Z","steps":["trace[440496511] 'process raft request' (duration: 989.784135ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:05.311539Z","caller":"traceutil/trace.go:171","msg":"trace[1228930250] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.366825081s","start":"2026-07-09T13:11:03.944206Z","end":"2026-07-09T13:11:05.311031Z","steps":["trace[1228930250] 'process raft request' (duration: 1.366471981s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:05.312254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:03.944199Z","time spent":"1.367990513s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:11:05.311374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155486213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:11:05.313251Z","caller":"traceutil/trace.go:171","msg":"trace[886076991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.157378995s","start":"2026-07-09T13:11:04.155859Z","end":"2026-07-09T13:11:05.313238Z","steps":["trace[886076991] 'agreement among raft nodes before linearized reading' (duration: 1.155458352s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:05.311459Z","caller":"traceutil/trace.go:171","msg":"trace[630337733] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.367653692s","start":"2026-07-09T13:11:03.943778Z","end":"2026-07-09T13:11:05.311431Z","steps":["trace[630337733] 'process raft request' (duration: 1.366862991s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:05.312789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:04.320891Z","time spent":"991.003859ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:11:05.313833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:03.943766Z","time spent":"1.370011048s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:11:05.313699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:04.155789Z","time spent":"1.15789805s","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T13:11:05.717041Z","caller":"traceutil/trace.go:171","msg":"trace[926971901] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"133.186101ms","start":"2026-07-09T13:11:05.583838Z","end":"2026-07-09T13:11:05.717024Z","steps":["trace[926971901] 'process raft request' (duration: 133.040757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:09.055782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.813319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:11:09.055895Z","caller":"traceutil/trace.go:171","msg":"trace[1038793042] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"356.015174ms","start":"2026-07-09T13:11:08.699856Z","end":"2026-07-09T13:11:09.055871Z","steps":["trace[1038793042] 'process raft request' (duration: 13.04205ms)","trace[1038793042] 'compare' (duration: 342.644385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:11:09.055969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:08.69983Z","time spent":"356.109076ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:11:09.125435Z","caller":"traceutil/trace.go:171","msg":"trace[85944855] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"225.374334ms","start":"2026-07-09T13:11:08.900043Z","end":"2026-07-09T13:11:09.125418Z","steps":["trace[85944855] 'process raft request' (duration: 225.281311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:21.798685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.533734ms","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-09T13:11:21.798775Z","caller":"traceutil/trace.go:171","msg":"trace[1562979724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"411.655957ms","start":"2026-07-09T13:11:21.387097Z","end":"2026-07-09T13:11:21.798753Z","steps":["trace[1562979724] 'range keys from in-memory index tree' (duration: 411.396251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:21.798774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.069135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T13:11:21.798818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.387089Z","time spent":"411.720259ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:11:21.798865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.221619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:11:21.798886Z","caller":"traceutil/trace.go:171","msg":"trace[503043409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"401.252251ms","start":"2026-07-09T13:11:21.39763Z","end":"2026-07-09T13:11:21.798882Z","steps":["trace[503043409] 'range keys from in-memory index tree' (duration: 401.056045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:21.798904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.397623Z","time spent":"401.275921ms","remote":"127.0.0.1:51440","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":"info","ts":"2026-07-09T13:11:21.798827Z","caller":"traceutil/trace.go:171","msg":"trace[1297438701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"401.166908ms","start":"2026-07-09T13:11:21.397648Z","end":"2026-07-09T13:11:21.798815Z","steps":["trace[1297438701] 'range keys from in-memory index tree' (duration: 400.897441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:21.799144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.397619Z","time spent":"401.518098ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:11:21.799232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.578309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:11:21.799281Z","caller":"traceutil/trace.go:171","msg":"trace[1002448659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:819; }","duration":"455.662431ms","start":"2026-07-09T13:11:21.343608Z","end":"2026-07-09T13:11:21.799271Z","steps":["trace[1002448659] 'range keys from in-memory index tree' (duration: 455.487396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:21.799327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.34359Z","time spent":"455.729703ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:11:21.79927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.457358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:11:21.800056Z","caller":"traceutil/trace.go:171","msg":"trace[1385701499] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:819; }","duration":"318.804637ms","start":"2026-07-09T13:11:21.480648Z","end":"2026-07-09T13:11:21.799452Z","steps":["trace[1385701499] 'count revisions from in-memory index tree' (duration: 318.327645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:21.800153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.480625Z","time spent":"319.510907ms","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:11:22.872986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415467286,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:11:22.908389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.224452843s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T13:11:22.908761Z","caller":"traceutil/trace.go:171","msg":"trace[138071078] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"1.224957046s","start":"2026-07-09T13:11:21.683774Z","end":"2026-07-09T13:11:22.908731Z","steps":["trace[138071078] 'process raft request' (duration: 1.224783471s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:22.908921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.683748Z","time spent":"1.22509729s","remote":"127.0.0.1:51418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:22.914101Z","caller":"traceutil/trace.go:171","msg":"trace[1865988316] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.111427857s","start":"2026-07-09T13:11:21.802652Z","end":"2026-07-09T13:11:22.914079Z","steps":["trace[1865988316] 'process raft request' (duration: 1.111250203s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:22.914299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.802635Z","time spent":"1.1115261s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:22.914566Z","caller":"traceutil/trace.go:171","msg":"trace[824229677] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"1.111271712s","start":"2026-07-09T13:11:21.803261Z","end":"2026-07-09T13:11:22.914533Z","steps":["trace[824229677] 'process raft request' (duration: 1.110780618s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:22.914724Z","caller":"traceutil/trace.go:171","msg":"trace[585582601] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"1.111424346s","start":"2026-07-09T13:11:21.803288Z","end":"2026-07-09T13:11:22.914712Z","steps":["trace[585582601] 'process raft request' (duration: 1.111133998s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:22.914734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.80324Z","time spent":"1.111405797s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:11:22.914763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.103402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:11:22.91479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.803265Z","time spent":"1.111489718s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:22.914805Z","caller":"traceutil/trace.go:171","msg":"trace[1184298075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"542.196745ms","start":"2026-07-09T13:11:22.372598Z","end":"2026-07-09T13:11:22.914795Z","steps":["trace[1184298075] 'agreement among raft nodes before linearized reading' (duration: 542.02857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:22.91484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:22.372579Z","time spent":"542.253517ms","remote":"127.0.0.1:51440","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-09T13:11:22.914573Z","caller":"traceutil/trace.go:171","msg":"trace[877150357] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:850; }","duration":"541.909167ms","start":"2026-07-09T13:11:22.37264Z","end":"2026-07-09T13:11:22.914549Z","steps":["trace[877150357] 'read index received' (duration: 535.983054ms)","trace[877150357] 'applied index is now lower than readState.Index' (duration: 5.925193ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:22.914968Z","caller":"traceutil/trace.go:171","msg":"trace[485203801] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"1.111110567s","start":"2026-07-09T13:11:21.803848Z","end":"2026-07-09T13:11:22.914958Z","steps":["trace[485203801] 'process raft request' (duration: 1.110661235s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:22.915059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:21.803836Z","time spent":"1.111154618s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:11:58.210656Z","caller":"traceutil/trace.go:171","msg":"trace[1951386986] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"300.797785ms","start":"2026-07-09T13:11:57.909832Z","end":"2026-07-09T13:11:58.21063Z","steps":["trace[1951386986] 'read index received' (duration: 300.693262ms)","trace[1951386986] 'applied index is now lower than readState.Index' (duration: 103.003µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:11:58.21133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.478183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:11:58.211389Z","caller":"traceutil/trace.go:171","msg":"trace[967948573] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1037; }","duration":"301.600066ms","start":"2026-07-09T13:11:57.909775Z","end":"2026-07-09T13:11:58.211375Z","steps":["trace[967948573] 'agreement among raft nodes before linearized reading' (duration: 301.456802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:58.211458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:57.909746Z","time spent":"301.701259ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:11:58.211444Z","caller":"traceutil/trace.go:171","msg":"trace[1073217919] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"325.411477ms","start":"2026-07-09T13:11:57.885885Z","end":"2026-07-09T13:11:58.211297Z","steps":["trace[1073217919] 'process raft request' (duration: 324.577315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:58.211742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:57.885848Z","time spent":"325.729445ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:11:59.593755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415467733,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T13:11:59.883067Z","caller":"traceutil/trace.go:171","msg":"trace[774109016] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"789.845545ms","start":"2026-07-09T13:11:59.093186Z","end":"2026-07-09T13:11:59.883032Z","steps":["trace[774109016] 'read index received' (duration: 789.63078ms)","trace[774109016] 'applied index is now lower than readState.Index' (duration: 214.015µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:59.88339Z","caller":"traceutil/trace.go:171","msg":"trace[1850791981] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"792.367713ms","start":"2026-07-09T13:11:59.09101Z","end":"2026-07-09T13:11:59.883377Z","steps":["trace[1850791981] 'process raft request' (duration: 791.903141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.883423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.203769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:11:59.883465Z","caller":"traceutil/trace.go:171","msg":"trace[1133684561] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1038; }","duration":"342.298851ms","start":"2026-07-09T13:11:59.541155Z","end":"2026-07-09T13:11:59.883454Z","steps":["trace[1133684561] 'agreement among raft nodes before linearized reading' (duration: 342.207939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.883489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.090987Z","time spent":"792.446415ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:11:59.883493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.741238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T13:11:59.883552Z","caller":"traceutil/trace.go:171","msg":"trace[1248230094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"784.81211ms","start":"2026-07-09T13:11:59.098721Z","end":"2026-07-09T13:11:59.883533Z","steps":["trace[1248230094] 'agreement among raft nodes before linearized reading' (duration: 784.625005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.883574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.390799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T13:11:59.883598Z","caller":"traceutil/trace.go:171","msg":"trace[1606180181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"790.444511ms","start":"2026-07-09T13:11:59.093146Z","end":"2026-07-09T13:11:59.88359Z","steps":["trace[1606180181] 'agreement among raft nodes before linearized reading' (duration: 790.366339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.883596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.098711Z","time spent":"784.876111ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:11:59.88362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.093129Z","time spent":"790.484942ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:11:59.883494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.541136Z","time spent":"342.350992ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:11:59.883729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.299629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:11:59.88375Z","caller":"traceutil/trace.go:171","msg":"trace[1422741242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"728.34926ms","start":"2026-07-09T13:11:59.155394Z","end":"2026-07-09T13:11:59.883743Z","steps":["trace[1422741242] 'agreement among raft nodes before linearized reading' (duration: 728.316529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.88376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.5688ms","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-09T13:11:59.883772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.155379Z","time spent":"728.385851ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T13:11:59.883795Z","caller":"traceutil/trace.go:171","msg":"trace[131189669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1038; }","duration":"769.648792ms","start":"2026-07-09T13:11:59.114137Z","end":"2026-07-09T13:11:59.883785Z","steps":["trace[131189669] 'agreement among raft nodes before linearized reading' (duration: 769.488688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.883821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.114114Z","time spent":"769.699823ms","remote":"127.0.0.1:51440","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-09T13:11:59.883862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.074346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T13:11:59.883978Z","caller":"traceutil/trace.go:171","msg":"trace[1058030802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"785.243791ms","start":"2026-07-09T13:11:59.098723Z","end":"2026-07-09T13:11:59.883967Z","steps":["trace[1058030802] 'agreement among raft nodes before linearized reading' (duration: 784.945663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:59.88401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.098708Z","time spent":"785.292102ms","remote":"127.0.0.1:51440","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-09T13:12:00.656713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415467737,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:12:00.853159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.618197ms","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-09T13:12:00.853296Z","caller":"traceutil/trace.go:171","msg":"trace[1072243400] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1038; }","duration":"246.776821ms","start":"2026-07-09T13:12:00.606501Z","end":"2026-07-09T13:12:00.853278Z","steps":["trace[1072243400] 'range keys from in-memory index tree' (duration: 246.595896ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:00.853363Z","caller":"traceutil/trace.go:171","msg":"trace[274125123] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"965.544343ms","start":"2026-07-09T13:11:59.887774Z","end":"2026-07-09T13:12:00.853319Z","steps":["trace[274125123] 'process raft request' (duration: 964.057244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:00.853489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.88776Z","time spent":"965.659036ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:00.859159Z","caller":"traceutil/trace.go:171","msg":"trace[258338844] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"971.345069ms","start":"2026-07-09T13:11:59.8878Z","end":"2026-07-09T13:12:00.859145Z","steps":["trace[258338844] 'process raft request' (duration: 971.01587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:00.859262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.88779Z","time spent":"971.415972ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:00.860364Z","caller":"traceutil/trace.go:171","msg":"trace[1881458865] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1077; }","duration":"704.512029ms","start":"2026-07-09T13:12:00.155826Z","end":"2026-07-09T13:12:00.860339Z","steps":["trace[1881458865] 'read index received' (duration: 695.915386ms)","trace[1881458865] 'applied index is now lower than readState.Index' (duration: 8.595803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:00.860469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.626712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:12:00.860152Z","caller":"traceutil/trace.go:171","msg":"trace[1682388347] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"972.11428ms","start":"2026-07-09T13:11:59.888022Z","end":"2026-07-09T13:12:00.860136Z","steps":["trace[1682388347] 'process raft request' (duration: 970.889947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:00.860572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.888015Z","time spent":"972.512692ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:00.860638Z","caller":"traceutil/trace.go:171","msg":"trace[330112123] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"972.420018ms","start":"2026-07-09T13:11:59.88821Z","end":"2026-07-09T13:12:00.86063Z","steps":["trace[330112123] 'process raft request' (duration: 971.381049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:00.860712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:11:59.888202Z","time spent":"972.46407ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:00.860496Z","caller":"traceutil/trace.go:171","msg":"trace[1631968199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"704.695444ms","start":"2026-07-09T13:12:00.155794Z","end":"2026-07-09T13:12:00.86049Z","steps":["trace[1631968199] 'agreement among raft nodes before linearized reading' (duration: 704.620762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:00.860752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:00.155777Z","time spent":"704.966162ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T13:12:00.860842Z","caller":"traceutil/trace.go:171","msg":"trace[1898932887] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"642.342156ms","start":"2026-07-09T13:12:00.218494Z","end":"2026-07-09T13:12:00.860836Z","steps":["trace[1898932887] 'process raft request' (duration: 641.803672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:00.860881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:00.21847Z","time spent":"642.388618ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:12:22.486071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415467925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:12:22.987104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415467925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:12:23.025021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143638426s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T13:12:23.025379Z","caller":"traceutil/trace.go:171","msg":"trace[647918132] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"1.144086959s","start":"2026-07-09T13:12:21.88127Z","end":"2026-07-09T13:12:23.025357Z","steps":["trace[647918132] 'process raft request' (duration: 1.143955475s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:23.025498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:21.881247Z","time spent":"1.144179431s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:23.17251Z","caller":"traceutil/trace.go:171","msg":"trace[1080845577] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1154; }","duration":"1.186528779s","start":"2026-07-09T13:12:21.985956Z","end":"2026-07-09T13:12:23.172484Z","steps":["trace[1080845577] 'read index received' (duration: 1.039261797s)","trace[1080845577] 'applied index is now lower than readState.Index' (duration: 147.265972ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:23.172596Z","caller":"traceutil/trace.go:171","msg":"trace[2025557997] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"230.347039ms","start":"2026-07-09T13:12:22.942226Z","end":"2026-07-09T13:12:23.172573Z","steps":["trace[2025557997] 'process raft request' (duration: 132.593208ms)","trace[2025557997] 'compare' (duration: 97.557175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:23.172902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186913099s","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-09T13:12:23.17295Z","caller":"traceutil/trace.go:171","msg":"trace[910958506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"1.187032752s","start":"2026-07-09T13:12:21.985906Z","end":"2026-07-09T13:12:23.172938Z","steps":["trace[910958506] 'agreement among raft nodes before linearized reading' (duration: 1.186710884s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:23.17298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.766324ms","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-09T13:12:23.172984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:21.985876Z","time spent":"1.187098684s","remote":"127.0.0.1:51440","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-09T13:12:23.173029Z","caller":"traceutil/trace.go:171","msg":"trace[633307303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"222.817375ms","start":"2026-07-09T13:12:22.950185Z","end":"2026-07-09T13:12:23.173002Z","steps":["trace[633307303] 'agreement among raft nodes before linearized reading' (duration: 222.716732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:23.173112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.31396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T13:12:23.17322Z","caller":"traceutil/trace.go:171","msg":"trace[45560576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"203.455795ms","start":"2026-07-09T13:12:22.96972Z","end":"2026-07-09T13:12:23.173176Z","steps":["trace[45560576] 'agreement among raft nodes before linearized reading' (duration: 203.221608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:23.173366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:12:23.173459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.262752ms","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-09T13:12:23.173513Z","caller":"traceutil/trace.go:171","msg":"trace[1941025898] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1112; }","duration":"222.410016ms","start":"2026-07-09T13:12:22.951044Z","end":"2026-07-09T13:12:23.173454Z","steps":["trace[1941025898] 'agreement among raft nodes before linearized reading' (duration: 221.83615ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:23.173554Z","caller":"traceutil/trace.go:171","msg":"trace[677026935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1112; }","duration":"222.374164ms","start":"2026-07-09T13:12:22.951167Z","end":"2026-07-09T13:12:23.173542Z","steps":["trace[677026935] 'agreement among raft nodes before linearized reading' (duration: 221.670065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:23.174127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.722377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:12:23.174208Z","caller":"traceutil/trace.go:171","msg":"trace[1941673128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1112; }","duration":"224.84335ms","start":"2026-07-09T13:12:22.949342Z","end":"2026-07-09T13:12:23.174185Z","steps":["trace[1941673128] 'agreement among raft nodes before linearized reading' (duration: 223.431332ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:23.357136Z","caller":"traceutil/trace.go:171","msg":"trace[1515682996] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"179.181301ms","start":"2026-07-09T13:12:23.177915Z","end":"2026-07-09T13:12:23.357097Z","steps":["trace[1515682996] 'process raft request' (duration: 123.02088ms)","trace[1515682996] 'compare' (duration: 55.74297ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:23.357353Z","caller":"traceutil/trace.go:171","msg":"trace[1380599706] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"178.682869ms","start":"2026-07-09T13:12:23.178656Z","end":"2026-07-09T13:12:23.357339Z","steps":["trace[1380599706] 'process raft request' (duration: 178.311259ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:23.357523Z","caller":"traceutil/trace.go:171","msg":"trace[1671991197] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"178.484352ms","start":"2026-07-09T13:12:23.178979Z","end":"2026-07-09T13:12:23.357463Z","steps":["trace[1671991197] 'process raft request' (duration: 178.0476ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:23.357176Z","caller":"traceutil/trace.go:171","msg":"trace[681389597] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"177.782324ms","start":"2026-07-09T13:12:23.179366Z","end":"2026-07-09T13:12:23.357148Z","steps":["trace[681389597] 'process raft request' (duration: 177.700341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.693777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415467937,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T13:12:25.859102Z","caller":"traceutil/trace.go:171","msg":"trace[1562978597] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"666.008212ms","start":"2026-07-09T13:12:25.193065Z","end":"2026-07-09T13:12:25.859073Z","steps":["trace[1562978597] 'read index received' (duration: 665.908239ms)","trace[1562978597] 'applied index is now lower than readState.Index' (duration: 99.133µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:25.859344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.698803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:12:25.859387Z","caller":"traceutil/trace.go:171","msg":"trace[1054389684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"497.792035ms","start":"2026-07-09T13:12:25.361582Z","end":"2026-07-09T13:12:25.859374Z","steps":["trace[1054389684] 'agreement among raft nodes before linearized reading' (duration: 497.640611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.859422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.361561Z","time spent":"497.852707ms","remote":"127.0.0.1:51440","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-09T13:12:25.859452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.355121ms","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-09T13:12:25.859573Z","caller":"traceutil/trace.go:171","msg":"trace[1263087562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"666.517706ms","start":"2026-07-09T13:12:25.19303Z","end":"2026-07-09T13:12:25.859548Z","steps":["trace[1263087562] 'agreement among raft nodes before linearized reading' (duration: 666.200877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.859614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.656082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:12:25.859644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.193015Z","time spent":"666.614578ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T13:12:25.859702Z","caller":"traceutil/trace.go:171","msg":"trace[17256116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"497.775324ms","start":"2026-07-09T13:12:25.361908Z","end":"2026-07-09T13:12:25.859683Z","steps":["trace[17256116] 'agreement among raft nodes before linearized reading' (duration: 497.546978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.85975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.3619Z","time spent":"497.839288ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T13:12:25.859915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.164596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:12:25.859957Z","caller":"traceutil/trace.go:171","msg":"trace[647142688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"498.248458ms","start":"2026-07-09T13:12:25.361699Z","end":"2026-07-09T13:12:25.859947Z","steps":["trace[647142688] 'agreement among raft nodes before linearized reading' (duration: 497.946839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.859899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.154709ms","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-09T13:12:25.860042Z","caller":"traceutil/trace.go:171","msg":"trace[2007892224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"497.310333ms","start":"2026-07-09T13:12:25.362712Z","end":"2026-07-09T13:12:25.860022Z","steps":["trace[2007892224] 'agreement among raft nodes before linearized reading' (duration: 496.820369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.860122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.362637Z","time spent":"497.472298ms","remote":"127.0.0.1:51440","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-09T13:12:25.860133Z","caller":"traceutil/trace.go:171","msg":"trace[339106259] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"682.175969ms","start":"2026-07-09T13:12:25.177939Z","end":"2026-07-09T13:12:25.860115Z","steps":["trace[339106259] 'process raft request' (duration: 680.982886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:25.859987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.361678Z","time spent":"498.302769ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:12:25.861304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.177919Z","time spent":"682.283911ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:26.18755Z","caller":"traceutil/trace.go:171","msg":"trace[1828686960] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"322.070597ms","start":"2026-07-09T13:12:25.865452Z","end":"2026-07-09T13:12:26.187523Z","steps":["trace[1828686960] 'process raft request' (duration: 289.946832ms)","trace[1828686960] 'compare' (duration: 31.903128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:26.187748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.865418Z","time spent":"322.251482ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:26.187881Z","caller":"traceutil/trace.go:171","msg":"trace[481748894] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"321.464381ms","start":"2026-07-09T13:12:25.866386Z","end":"2026-07-09T13:12:26.187851Z","steps":["trace[481748894] 'process raft request' (duration: 321.091691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:26.188079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.866375Z","time spent":"321.587594ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:26.188393Z","caller":"traceutil/trace.go:171","msg":"trace[458413660] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"321.907292ms","start":"2026-07-09T13:12:25.866468Z","end":"2026-07-09T13:12:26.188375Z","steps":["trace[458413660] 'process raft request' (duration: 321.82907ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:26.188383Z","caller":"traceutil/trace.go:171","msg":"trace[130310534] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"321.914153ms","start":"2026-07-09T13:12:25.866451Z","end":"2026-07-09T13:12:26.188366Z","steps":["trace[130310534] 'process raft request' (duration: 321.304667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:26.188507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.866462Z","time spent":"321.987715ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:12:26.188776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:25.866441Z","time spent":"322.019035ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:26.288049Z","caller":"traceutil/trace.go:171","msg":"trace[461113925] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1163; }","duration":"133.028429ms","start":"2026-07-09T13:12:26.154992Z","end":"2026-07-09T13:12:26.288021Z","steps":["trace[461113925] 'read index received' (duration: 407.531µs)","trace[461113925] 'applied index is now lower than readState.Index' (duration: 132.619398ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:26.288194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.187094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:12:26.288228Z","caller":"traceutil/trace.go:171","msg":"trace[1850845030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"133.279156ms","start":"2026-07-09T13:12:26.154939Z","end":"2026-07-09T13:12:26.288218Z","steps":["trace[1850845030] 'agreement among raft nodes before linearized reading' (duration: 133.192254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.339448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.945469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:12:28.339547Z","caller":"traceutil/trace.go:171","msg":"trace[816806203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1124; }","duration":"589.066202ms","start":"2026-07-09T13:12:27.750463Z","end":"2026-07-09T13:12:28.339529Z","steps":["trace[816806203] 'count revisions from in-memory index tree' (duration: 588.860047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.339468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.721411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T13:12:28.339593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:27.750402Z","time spent":"589.177926ms","remote":"127.0.0.1:36998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:12:28.339671Z","caller":"traceutil/trace.go:171","msg":"trace[642227324] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1124; }","duration":"909.942036ms","start":"2026-07-09T13:12:27.429666Z","end":"2026-07-09T13:12:28.339608Z","steps":["trace[642227324] 'count revisions from in-memory index tree' (duration: 909.636308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.836659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:12:28.340505Z","caller":"traceutil/trace.go:171","msg":"trace[857811791] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"438.030207ms","start":"2026-07-09T13:12:27.902457Z","end":"2026-07-09T13:12:28.340488Z","steps":["trace[857811791] 'read index received' (duration: 260.956262ms)","trace[857811791] 'applied index is now lower than readState.Index' (duration: 177.072555ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:28.340525Z","caller":"traceutil/trace.go:171","msg":"trace[1428168776] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"473.544713ms","start":"2026-07-09T13:12:27.86696Z","end":"2026-07-09T13:12:28.340505Z","steps":["trace[1428168776] 'process raft request' (duration: 296.511769ms)","trace[1428168776] 'compare' (duration: 176.520121ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:28.340636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:27.866937Z","time spent":"473.631735ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:12:28.340666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.206081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:12:28.340694Z","caller":"traceutil/trace.go:171","msg":"trace[1636495607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"438.269703ms","start":"2026-07-09T13:12:27.902414Z","end":"2026-07-09T13:12:28.340684Z","steps":["trace[1636495607] 'agreement among raft nodes before linearized reading' (duration: 438.15151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.451357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:12:28.340721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:27.429645Z","time spent":"910.0595ms","remote":"127.0.0.1:51638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-07-09T13:12:28.340763Z","caller":"traceutil/trace.go:171","msg":"trace[2122355570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"186.53557ms","start":"2026-07-09T13:12:28.15421Z","end":"2026-07-09T13:12:28.340746Z","steps":["trace[2122355570] 'agreement among raft nodes before linearized reading' (duration: 186.448947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.211379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T13:12:28.340853Z","caller":"traceutil/trace.go:171","msg":"trace[1576027037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"148.255449ms","start":"2026-07-09T13:12:28.19259Z","end":"2026-07-09T13:12:28.340845Z","steps":["trace[1576027037] 'agreement among raft nodes before linearized reading' (duration: 148.164807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.560242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:12:28.340956Z","caller":"traceutil/trace.go:171","msg":"trace[119763687] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1125; }","duration":"124.647714ms","start":"2026-07-09T13:12:28.21629Z","end":"2026-07-09T13:12:28.340937Z","steps":["trace[119763687] 'agreement among raft nodes before linearized reading' (duration: 124.555051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.650431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T13:12:28.340997Z","caller":"traceutil/trace.go:171","msg":"trace[698676165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"148.692141ms","start":"2026-07-09T13:12:28.192298Z","end":"2026-07-09T13:12:28.34099Z","steps":["trace[698676165] 'agreement among raft nodes before linearized reading' (duration: 148.621799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.689171ms","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-09T13:12:28.341055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.806235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:12:28.341074Z","caller":"traceutil/trace.go:171","msg":"trace[1277178277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1125; }","duration":"148.840625ms","start":"2026-07-09T13:12:28.192215Z","end":"2026-07-09T13:12:28.341055Z","steps":["trace[1277178277] 'agreement among raft nodes before linearized reading' (duration: 148.542627ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:28.341109Z","caller":"traceutil/trace.go:171","msg":"trace[457579368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1125; }","duration":"148.889947ms","start":"2026-07-09T13:12:28.192205Z","end":"2026-07-09T13:12:28.341095Z","steps":["trace[457579368] 'agreement among raft nodes before linearized reading' (duration: 148.755433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:28.340724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:27.902397Z","time spent":"438.319415ms","remote":"127.0.0.1:51440","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":"info","ts":"2026-07-09T13:12:28.454901Z","caller":"traceutil/trace.go:171","msg":"trace[1161854527] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"107.482742ms","start":"2026-07-09T13:12:28.347397Z","end":"2026-07-09T13:12:28.45488Z","steps":["trace[1161854527] 'process raft request' (duration: 107.448831ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:28.454929Z","caller":"traceutil/trace.go:171","msg":"trace[1909024996] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"108.09182ms","start":"2026-07-09T13:12:28.346802Z","end":"2026-07-09T13:12:28.454893Z","steps":["trace[1909024996] 'process raft request' (duration: 92.53104ms)","trace[1909024996] 'compare' (duration: 15.395265ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:28.455237Z","caller":"traceutil/trace.go:171","msg":"trace[2091516971] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"108.087819ms","start":"2026-07-09T13:12:28.347116Z","end":"2026-07-09T13:12:28.455204Z","steps":["trace[2091516971] 'process raft request' (duration: 107.694969ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:36.299725Z","caller":"traceutil/trace.go:171","msg":"trace[771577770] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"144.10576ms","start":"2026-07-09T13:12:36.155588Z","end":"2026-07-09T13:12:36.299694Z","steps":["trace[771577770] 'read index received' (duration: 80.075267ms)","trace[771577770] 'applied index is now lower than readState.Index' (duration: 64.028023ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:36.299901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.276924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:12:36.299988Z","caller":"traceutil/trace.go:171","msg":"trace[1507125693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1150; }","duration":"144.411868ms","start":"2026-07-09T13:12:36.155557Z","end":"2026-07-09T13:12:36.299969Z","steps":["trace[1507125693] 'agreement among raft nodes before linearized reading' (duration: 144.246954ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:37.092563Z","caller":"traceutil/trace.go:171","msg":"trace[961912974] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"530.512988ms","start":"2026-07-09T13:12:36.561987Z","end":"2026-07-09T13:12:37.0925Z","steps":["trace[961912974] 'process raft request' (duration: 530.295292ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:37.092619Z","caller":"traceutil/trace.go:171","msg":"trace[270203027] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"530.507667ms","start":"2026-07-09T13:12:36.562083Z","end":"2026-07-09T13:12:37.092591Z","steps":["trace[270203027] 'process raft request' (duration: 530.340293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:37.093044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:36.562078Z","time spent":"530.894168ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:12:37.092933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:36.561969Z","time spent":"530.846626ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:38.645841Z","caller":"traceutil/trace.go:171","msg":"trace[1103716947] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"120.168148ms","start":"2026-07-09T13:12:38.525647Z","end":"2026-07-09T13:12:38.645815Z","steps":["trace[1103716947] 'process raft request' (duration: 30.463086ms)","trace[1103716947] 'compare' (duration: 89.459235ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:39.319557Z","caller":"traceutil/trace.go:171","msg":"trace[793982766] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"218.022326ms","start":"2026-07-09T13:12:39.101504Z","end":"2026-07-09T13:12:39.319527Z","steps":["trace[793982766] 'process raft request' (duration: 217.82445ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:39.320413Z","caller":"traceutil/trace.go:171","msg":"trace[136862577] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"218.007005ms","start":"2026-07-09T13:12:39.10238Z","end":"2026-07-09T13:12:39.320387Z","steps":["trace[136862577] 'process raft request' (duration: 217.810369ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:45.753885Z","caller":"traceutil/trace.go:171","msg":"trace[959252089] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"397.927383ms","start":"2026-07-09T13:12:45.355929Z","end":"2026-07-09T13:12:45.753857Z","steps":["trace[959252089] 'read index received' (duration: 397.673326ms)","trace[959252089] 'applied index is now lower than readState.Index' (duration: 252.697µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:45.754179Z","caller":"traceutil/trace.go:171","msg":"trace[290260495] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"408.275206ms","start":"2026-07-09T13:12:45.34589Z","end":"2026-07-09T13:12:45.754165Z","steps":["trace[290260495] 'process raft request' (duration: 407.810344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:45.754335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:45.345881Z","time spent":"408.338037ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:12:45.754581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.650941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:12:45.754638Z","caller":"traceutil/trace.go:171","msg":"trace[1301405209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"398.727124ms","start":"2026-07-09T13:12:45.355894Z","end":"2026-07-09T13:12:45.754621Z","steps":["trace[1301405209] 'agreement among raft nodes before linearized reading' (duration: 398.57399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:45.754681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:45.355882Z","time spent":"398.787345ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:12:48.217078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415468215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:12:48.717619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415468215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:12:49.167942Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.555138011s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T13:12:49.168326Z","caller":"traceutil/trace.go:171","msg":"trace[944308868] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"1.555555282s","start":"2026-07-09T13:12:47.612736Z","end":"2026-07-09T13:12:49.168291Z","steps":["trace[944308868] 'process raft request' (duration: 1.555410348s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.168558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:47.61272Z","time spent":"1.555677845s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:49.181571Z","caller":"traceutil/trace.go:171","msg":"trace[1930506059] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"1.464728441s","start":"2026-07-09T13:12:47.716811Z","end":"2026-07-09T13:12:49.181539Z","steps":["trace[1930506059] 'read index received' (duration: 1.451364477s)","trace[1930506059] 'applied index is now lower than readState.Index' (duration: 13.362774ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:49.181741Z","caller":"traceutil/trace.go:171","msg":"trace[357667693] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"630.065391ms","start":"2026-07-09T13:12:48.551664Z","end":"2026-07-09T13:12:49.181729Z","steps":["trace[357667693] 'process raft request' (duration: 629.67567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.181904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:48.551643Z","time spent":"630.125412ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:12:49.181957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42311515s","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-09T13:12:49.182017Z","caller":"traceutil/trace.go:171","msg":"trace[1203307535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"1.423209783s","start":"2026-07-09T13:12:47.758789Z","end":"2026-07-09T13:12:49.181999Z","steps":["trace[1203307535] 'agreement among raft nodes before linearized reading' (duration: 1.423052409s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.182108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:47.758772Z","time spent":"1.423268194s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:12:49.182231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465423758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:12:49.182268Z","caller":"traceutil/trace.go:171","msg":"trace[1543755594] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1240; }","duration":"1.46549524s","start":"2026-07-09T13:12:47.716761Z","end":"2026-07-09T13:12:49.182256Z","steps":["trace[1543755594] 'agreement among raft nodes before linearized reading' (duration: 1.465443149s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.182362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:47.71674Z","time spent":"1.465613094s","remote":"127.0.0.1:36866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:12:49.182547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026152563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:12:49.182578Z","caller":"traceutil/trace.go:171","msg":"trace[1470906960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"1.026182664s","start":"2026-07-09T13:12:48.156388Z","end":"2026-07-09T13:12:49.18257Z","steps":["trace[1470906960] 'agreement among raft nodes before linearized reading' (duration: 1.026136832s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.182607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:48.156331Z","time spent":"1.026266856s","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:12:49.182601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.280695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:12:49.182664Z","caller":"traceutil/trace.go:171","msg":"trace[124015882] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1240; }","duration":"931.384598ms","start":"2026-07-09T13:12:48.251264Z","end":"2026-07-09T13:12:49.182649Z","steps":["trace[124015882] 'agreement among raft nodes before linearized reading' (duration: 931.256744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.182709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:48.251243Z","time spent":"931.452229ms","remote":"127.0.0.1:51574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:12:49.182757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420411058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:12:49.182783Z","caller":"traceutil/trace.go:171","msg":"trace[42546689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"1.42046694s","start":"2026-07-09T13:12:47.762309Z","end":"2026-07-09T13:12:49.182776Z","steps":["trace[42546689] 'agreement among raft nodes before linearized reading' (duration: 1.420403968s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.182807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:47.762298Z","time spent":"1.420504051s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:12:49.183014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.085766ms","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-09T13:12:49.183063Z","caller":"traceutil/trace.go:171","msg":"trace[878121361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"485.159848ms","start":"2026-07-09T13:12:48.697893Z","end":"2026-07-09T13:12:49.183053Z","steps":["trace[878121361] 'agreement among raft nodes before linearized reading' (duration: 484.995234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.18309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:48.697879Z","time spent":"485.20392ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T13:12:49.183102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.661394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T13:12:49.183088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.458235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T13:12:49.183152Z","caller":"traceutil/trace.go:171","msg":"trace[2128103655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"476.734435ms","start":"2026-07-09T13:12:48.706404Z","end":"2026-07-09T13:12:49.183139Z","steps":["trace[2128103655] 'agreement among raft nodes before linearized reading' (duration: 476.599762ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:49.183175Z","caller":"traceutil/trace.go:171","msg":"trace[329278946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"477.586678ms","start":"2026-07-09T13:12:48.705568Z","end":"2026-07-09T13:12:49.183154Z","steps":["trace[329278946] 'agreement among raft nodes before linearized reading' (duration: 477.266719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:49.183195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:48.706394Z","time spent":"476.793738ms","remote":"127.0.0.1:51440","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-09T13:12:49.183221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:48.705492Z","time spent":"477.718841ms","remote":"127.0.0.1:51440","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-09T13:13:03.549947Z","caller":"traceutil/trace.go:171","msg":"trace[1363431085] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"135.900954ms","start":"2026-07-09T13:13:03.414023Z","end":"2026-07-09T13:13:03.549924Z","steps":["trace[1363431085] 'process raft request' (duration: 135.708729ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:03.614829Z","caller":"traceutil/trace.go:171","msg":"trace[1258598645] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"197.082712ms","start":"2026-07-09T13:13:03.41772Z","end":"2026-07-09T13:13:03.614803Z","steps":["trace[1258598645] 'process raft request' (duration: 197.031591ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:03.615397Z","caller":"traceutil/trace.go:171","msg":"trace[420236852] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"199.502375ms","start":"2026-07-09T13:13:03.415878Z","end":"2026-07-09T13:13:03.615381Z","steps":["trace[420236852] 'process raft request' (duration: 198.703224ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:04.808905Z","caller":"traceutil/trace.go:171","msg":"trace[1649192058] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"435.628329ms","start":"2026-07-09T13:13:04.373257Z","end":"2026-07-09T13:13:04.808885Z","steps":["trace[1649192058] 'process raft request' (duration: 435.520116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:04.809462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:04.373235Z","time spent":"436.172203ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:13:05.431351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.5614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:13:05.431445Z","caller":"traceutil/trace.go:171","msg":"trace[581723848] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1292; }","duration":"260.673062ms","start":"2026-07-09T13:13:05.17075Z","end":"2026-07-09T13:13:05.431423Z","steps":["trace[581723848] 'count revisions from in-memory index tree' (duration: 260.460767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:05.431758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.633854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:13:05.431812Z","caller":"traceutil/trace.go:171","msg":"trace[786063933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"276.719616ms","start":"2026-07-09T13:13:05.155079Z","end":"2026-07-09T13:13:05.431799Z","steps":["trace[786063933] 'range keys from in-memory index tree' (duration: 276.574152ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:05.432187Z","caller":"traceutil/trace.go:171","msg":"trace[134255460] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"141.82353ms","start":"2026-07-09T13:13:05.29035Z","end":"2026-07-09T13:13:05.432173Z","steps":["trace[134255460] 'read index received' (duration: 108.001175ms)","trace[134255460] 'applied index is now lower than readState.Index' (duration: 33.821245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:13:05.432473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.126288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:13:05.432507Z","caller":"traceutil/trace.go:171","msg":"trace[466810051] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1293; }","duration":"142.20003ms","start":"2026-07-09T13:13:05.290297Z","end":"2026-07-09T13:13:05.432497Z","steps":["trace[466810051] 'agreement among raft nodes before linearized reading' (duration: 142.140749ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:05.432506Z","caller":"traceutil/trace.go:171","msg":"trace[1134184304] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"152.38967ms","start":"2026-07-09T13:13:05.2801Z","end":"2026-07-09T13:13:05.43249Z","steps":["trace[1134184304] 'process raft request' (duration: 118.34607ms)","trace[1134184304] 'compare' (duration: 33.63978ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:13:05.607959Z","caller":"traceutil/trace.go:171","msg":"trace[1767762] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"172.08857ms","start":"2026-07-09T13:13:05.435842Z","end":"2026-07-09T13:13:05.60793Z","steps":["trace[1767762] 'process raft request' (duration: 104.038501ms)","trace[1767762] 'compare' (duration: 67.858444ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:13:05.608184Z","caller":"traceutil/trace.go:171","msg":"trace[346944632] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"171.261348ms","start":"2026-07-09T13:13:05.436907Z","end":"2026-07-09T13:13:05.608169Z","steps":["trace[346944632] 'process raft request' (duration: 170.93504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:06.508425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.583221ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-09T13:13:06.509117Z","caller":"traceutil/trace.go:171","msg":"trace[1551716668] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"271.366656ms","start":"2026-07-09T13:13:06.237724Z","end":"2026-07-09T13:13:06.509091Z","steps":["trace[1551716668] 'read index received' (duration: 20.153423ms)","trace[1551716668] 'applied index is now lower than readState.Index' (duration: 251.211243ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:13:06.509204Z","caller":"traceutil/trace.go:171","msg":"trace[755628300] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"282.419279ms","start":"2026-07-09T13:13:06.226777Z","end":"2026-07-09T13:13:06.509197Z","steps":["trace[755628300] 'process raft request' (duration: 281.791442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:07.993898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756153126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-07-09T13:13:07.993981Z","caller":"traceutil/trace.go:171","msg":"trace[444176944] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1301; }","duration":"1.756269639s","start":"2026-07-09T13:13:06.23769Z","end":"2026-07-09T13:13:07.99396Z","steps":["trace[444176944] 'agreement among raft nodes before linearized reading' (duration: 271.482829ms)","trace[444176944] 'range keys from in-memory index tree' (duration: 1.484586375s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:13:07.994022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:06.23767Z","time spent":"1.756342931s","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5352,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-09T13:13:07.994558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227585429s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:13:07.994697Z","caller":"traceutil/trace.go:171","msg":"trace[1630597361] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"1.482416748s","start":"2026-07-09T13:13:06.51225Z","end":"2026-07-09T13:13:07.994667Z","steps":["trace[1630597361] 'process raft request' (duration: 254.671074ms)","trace[1630597361] 'compare' (duration: 1.227024345s)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:13:07.994762Z","caller":"traceutil/trace.go:171","msg":"trace[1701787159] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"837.854614ms","start":"2026-07-09T13:13:07.156895Z","end":"2026-07-09T13:13:07.99475Z","steps":["trace[1701787159] 'read index received' (duration: 43.781µs)","trace[1701787159] 'applied index is now lower than readState.Index' (duration: 837.809663ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:13:07.994889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.996288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:13:07.994902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:06.512221Z","time spent":"1.482536541s","remote":"127.0.0.1:51228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:13:07.99494Z","caller":"traceutil/trace.go:171","msg":"trace[123490656] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1302; }","duration":"838.05196ms","start":"2026-07-09T13:13:07.156879Z","end":"2026-07-09T13:13:07.994931Z","steps":["trace[123490656] 'agreement among raft nodes before linearized reading' (duration: 837.954107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:07.994977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:07.156821Z","time spent":"838.146882ms","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:13:07.995465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.385649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:13:07.995506Z","caller":"traceutil/trace.go:171","msg":"trace[244859345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"838.44526ms","start":"2026-07-09T13:13:07.157051Z","end":"2026-07-09T13:13:07.995496Z","steps":["trace[244859345] 'agreement among raft nodes before linearized reading' (duration: 838.372529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:07.995533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:07.157036Z","time spent":"838.490972ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:13:07.995553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.92409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:13:07.995568Z","caller":"traceutil/trace.go:171","msg":"trace[6961221] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"557.475731ms","start":"2026-07-09T13:13:07.438082Z","end":"2026-07-09T13:13:07.995558Z","steps":["trace[6961221] 'process raft request' (duration: 556.540526ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:07.995584Z","caller":"traceutil/trace.go:171","msg":"trace[1749603818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"295.001332ms","start":"2026-07-09T13:13:07.700574Z","end":"2026-07-09T13:13:07.995575Z","steps":["trace[1749603818] 'agreement among raft nodes before linearized reading' (duration: 294.869868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:07.995621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:07.438053Z","time spent":"557.536972ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:13:07.995608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.096594ms","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-09T13:13:07.995624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.700519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:13:07.99567Z","caller":"traceutil/trace.go:171","msg":"trace[1298470872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"382.155825ms","start":"2026-07-09T13:13:07.613493Z","end":"2026-07-09T13:13:07.995649Z","steps":["trace[1298470872] 'agreement among raft nodes before linearized reading' (duration: 381.998591ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:07.995679Z","caller":"traceutil/trace.go:171","msg":"trace[1090394805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"382.788342ms","start":"2026-07-09T13:13:07.612871Z","end":"2026-07-09T13:13:07.99566Z","steps":["trace[1090394805] 'agreement among raft nodes before linearized reading' (duration: 382.658728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:07.995717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:07.613466Z","time spent":"382.239278ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:13:07.995724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:07.612841Z","time spent":"382.872364ms","remote":"127.0.0.1:51440","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-09T13:13:07.995751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.351015ms","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-09T13:13:07.995774Z","caller":"traceutil/trace.go:171","msg":"trace[1358967550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"330.399157ms","start":"2026-07-09T13:13:07.665369Z","end":"2026-07-09T13:13:07.995768Z","steps":["trace[1358967550] 'agreement among raft nodes before linearized reading' (duration: 330.339085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:07.995747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.76617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:13:07.995792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:07.665354Z","time spent":"330.433997ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T13:13:07.995821Z","caller":"traceutil/trace.go:171","msg":"trace[809007847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"225.886223ms","start":"2026-07-09T13:13:07.769918Z","end":"2026-07-09T13:13:07.995804Z","steps":["trace[809007847] 'agreement among raft nodes before linearized reading' (duration: 225.659928ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:08.298405Z","caller":"traceutil/trace.go:171","msg":"trace[1397780113] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"298.740739ms","start":"2026-07-09T13:13:07.99964Z","end":"2026-07-09T13:13:08.29838Z","steps":["trace[1397780113] 'process raft request' (duration: 298.508033ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:08.309589Z","caller":"traceutil/trace.go:171","msg":"trace[1040765250] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"309.477834ms","start":"2026-07-09T13:13:08.000092Z","end":"2026-07-09T13:13:08.30957Z","steps":["trace[1040765250] 'process raft request' (duration: 309.267838ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:08.309597Z","caller":"traceutil/trace.go:171","msg":"trace[338599863] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"309.424162ms","start":"2026-07-09T13:13:08.000159Z","end":"2026-07-09T13:13:08.309583Z","steps":["trace[338599863] 'process raft request' (duration: 309.306949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:08.309689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.557672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:13:08.309717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:08.000155Z","time spent":"309.493674ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:13:08.309713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:08.000079Z","time spent":"309.561376ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:13:08.309739Z","caller":"traceutil/trace.go:171","msg":"trace[1222154423] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1308; }","duration":"251.618163ms","start":"2026-07-09T13:13:08.05811Z","end":"2026-07-09T13:13:08.309729Z","steps":["trace[1222154423] 'agreement among raft nodes before linearized reading' (duration: 251.51599ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:08.309704Z","caller":"traceutil/trace.go:171","msg":"trace[1433797222] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"308.662812ms","start":"2026-07-09T13:13:08.001005Z","end":"2026-07-09T13:13:08.309668Z","steps":["trace[1433797222] 'process raft request' (duration: 308.512938ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:08.309588Z","caller":"traceutil/trace.go:171","msg":"trace[138669353] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1358; }","duration":"251.455179ms","start":"2026-07-09T13:13:08.058116Z","end":"2026-07-09T13:13:08.309571Z","steps":["trace[138669353] 'read index received' (duration: 239.996656ms)","trace[138669353] 'applied index is now lower than readState.Index' (duration: 11.457613ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:13:08.309881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:08.000997Z","time spent":"308.801665ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:13:08.3099Z","caller":"traceutil/trace.go:171","msg":"trace[628831730] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"307.498641ms","start":"2026-07-09T13:13:08.002366Z","end":"2026-07-09T13:13:08.309865Z","steps":["trace[628831730] 'process raft request' (duration: 307.176662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:08.309987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.105384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:13:08.310015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:08.00235Z","time spent":"307.604414ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:13:08.310028Z","caller":"traceutil/trace.go:171","msg":"trace[1137016231] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1308; }","duration":"129.174316ms","start":"2026-07-09T13:13:08.180838Z","end":"2026-07-09T13:13:08.310013Z","steps":["trace[1137016231] 'agreement among raft nodes before linearized reading' (duration: 129.110974ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:09.723036Z","caller":"traceutil/trace.go:171","msg":"trace[671732972] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"114.776065ms","start":"2026-07-09T13:13:09.608238Z","end":"2026-07-09T13:13:09.723014Z","steps":["trace[671732972] 'process raft request' (duration: 114.638021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:21.413254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.110802ms","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-09T13:13:21.413373Z","caller":"traceutil/trace.go:171","msg":"trace[1080682931] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1408; }","duration":"807.242515ms","start":"2026-07-09T13:13:20.606115Z","end":"2026-07-09T13:13:21.413357Z","steps":["trace[1080682931] 'range keys from in-memory index tree' (duration: 807.093561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:21.413577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.071871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:13:21.413565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.301692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-09T13:13:21.413613Z","caller":"traceutil/trace.go:171","msg":"trace[2081852175] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1408; }","duration":"430.136333ms","start":"2026-07-09T13:13:20.983464Z","end":"2026-07-09T13:13:21.413601Z","steps":["trace[2081852175] 'count revisions from in-memory index tree' (duration: 429.984759ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:21.413637Z","caller":"traceutil/trace.go:171","msg":"trace[1435799807] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1408; }","duration":"617.431826ms","start":"2026-07-09T13:13:20.796186Z","end":"2026-07-09T13:13:21.413617Z","steps":["trace[1435799807] 'range keys from in-memory index tree' (duration: 617.114607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:21.413667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:20.983449Z","time spent":"430.208225ms","remote":"127.0.0.1:36810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:13:21.413678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:20.796158Z","time spent":"617.510827ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T13:13:21.413981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.793225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-09T13:13:21.414026Z","caller":"traceutil/trace.go:171","msg":"trace[120960941] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:1408; }","duration":"547.880717ms","start":"2026-07-09T13:13:20.866135Z","end":"2026-07-09T13:13:21.414016Z","steps":["trace[120960941] 'range keys from in-memory index tree' (duration: 547.6302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:21.414051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:20.866111Z","time spent":"547.933919ms","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.23\" "} {"level":"warn","ts":"2026-07-09T13:13:21.974179Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415468570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:13:22.474661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415468570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:13:22.97546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12423073224415468570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T13:13:23.012955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.597748065s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T13:13:23.013327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:21.415061Z","time spent":"1.598262439s","remote":"127.0.0.1:51188","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T13:13:23.020322Z","caller":"traceutil/trace.go:171","msg":"trace[1244309657] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"1.601723031s","start":"2026-07-09T13:13:21.41857Z","end":"2026-07-09T13:13:23.020293Z","steps":["trace[1244309657] 'process raft request' (duration: 1.601477714s)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:23.020439Z","caller":"traceutil/trace.go:171","msg":"trace[2021693061] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1465; }","duration":"1.546812888s","start":"2026-07-09T13:13:21.473613Z","end":"2026-07-09T13:13:23.020426Z","steps":["trace[2021693061] 'read index received' (duration: 1.539639348s)","trace[2021693061] 'applied index is now lower than readState.Index' (duration: 7.17203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:13:23.02046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:21.418557Z","time spent":"1.601825563s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:13:23.020524Z","caller":"traceutil/trace.go:171","msg":"trace[999068481] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"965.665892ms","start":"2026-07-09T13:13:22.054842Z","end":"2026-07-09T13:13:23.020508Z","steps":["trace[999068481] 'process raft request' (duration: 965.338104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.020598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.054814Z","time spent":"965.747884ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T13:13:23.020684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547066074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-09T13:13:23.020741Z","caller":"traceutil/trace.go:171","msg":"trace[1946742674] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1410; }","duration":"1.547162687s","start":"2026-07-09T13:13:21.473564Z","end":"2026-07-09T13:13:23.020727Z","steps":["trace[1946742674] 'agreement among raft nodes before linearized reading' (duration: 1.546967972s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.020794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:21.473542Z","time spent":"1.547241919s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-09T13:13:23.029415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.359181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:13:23.029485Z","caller":"traceutil/trace.go:171","msg":"trace[198722947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1411; }","duration":"873.480025ms","start":"2026-07-09T13:13:22.155988Z","end":"2026-07-09T13:13:23.029468Z","steps":["trace[198722947] 'agreement among raft nodes before linearized reading' (duration: 873.330791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.029521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.155968Z","time spent":"873.544476ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:13:23.029528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.668528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T13:13:23.029627Z","caller":"traceutil/trace.go:171","msg":"trace[1806791183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1411; }","duration":"618.851774ms","start":"2026-07-09T13:13:22.410764Z","end":"2026-07-09T13:13:23.029616Z","steps":["trace[1806791183] 'agreement among raft nodes before linearized reading' (duration: 618.561966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.029705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.410753Z","time spent":"618.939886ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T13:13:23.029765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.674684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T13:13:23.029778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.056911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-09T13:13:23.029803Z","caller":"traceutil/trace.go:171","msg":"trace[365832444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1411; }","duration":"633.750296ms","start":"2026-07-09T13:13:22.396044Z","end":"2026-07-09T13:13:23.029795Z","steps":["trace[365832444] 'agreement among raft nodes before linearized reading' (duration: 633.655024ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:23.029813Z","caller":"traceutil/trace.go:171","msg":"trace[351983361] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1411; }","duration":"323.144843ms","start":"2026-07-09T13:13:22.706657Z","end":"2026-07-09T13:13:23.029802Z","steps":["trace[351983361] 'agreement among raft nodes before linearized reading' (duration: 322.976088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.029834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.396024Z","time spent":"633.801988ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T13:13:23.029836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.706636Z","time spent":"323.193574ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T13:13:23.029856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.138397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T13:13:23.029899Z","caller":"traceutil/trace.go:171","msg":"trace[2020999122] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1411; }","duration":"547.234639ms","start":"2026-07-09T13:13:22.482654Z","end":"2026-07-09T13:13:23.029889Z","steps":["trace[2020999122] 'agreement among raft nodes before linearized reading' (duration: 547.051164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.029939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.482629Z","time spent":"547.301331ms","remote":"127.0.0.1:51658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":159,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-09T13:13:23.030037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.848853ms","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-09T13:13:23.030077Z","caller":"traceutil/trace.go:171","msg":"trace[820519697] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1411; }","duration":"693.912475ms","start":"2026-07-09T13:13:22.336152Z","end":"2026-07-09T13:13:23.030065Z","steps":["trace[820519697] 'agreement among raft nodes before linearized reading' (duration: 693.821893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.030108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.336137Z","time spent":"693.961947ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T13:13:23.03033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.295071ms","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-09T13:13:23.030371Z","caller":"traceutil/trace.go:171","msg":"trace[678763740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1411; }","duration":"620.679942ms","start":"2026-07-09T13:13:22.40968Z","end":"2026-07-09T13:13:23.03036Z","steps":["trace[678763740] 'agreement among raft nodes before linearized reading' (duration: 620.24617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.0304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.40966Z","time spent":"620.730993ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T13:13:23.030354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.025377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T13:13:23.030591Z","caller":"traceutil/trace.go:171","msg":"trace[2112600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1411; }","duration":"630.283765ms","start":"2026-07-09T13:13:22.400296Z","end":"2026-07-09T13:13:23.03058Z","steps":["trace[2112600] 'agreement among raft nodes before linearized reading' (duration: 629.948475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.03069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.400286Z","time spent":"630.392247ms","remote":"127.0.0.1:51440","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-09T13:13:23.030531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.552119ms","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-09T13:13:23.030794Z","caller":"traceutil/trace.go:171","msg":"trace[524599973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1411; }","duration":"631.844656ms","start":"2026-07-09T13:13:22.398939Z","end":"2026-07-09T13:13:23.030784Z","steps":["trace[524599973] 'agreement among raft nodes before linearized reading' (duration: 631.503477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.030847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:13:22.398928Z","time spent":"631.893478ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T13:15:53.445056Z","caller":"traceutil/trace.go:171","msg":"trace[161580236] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2189; }","duration":"107.875253ms","start":"2026-07-09T13:15:53.337161Z","end":"2026-07-09T13:15:53.445037Z","steps":["trace[161580236] 'read index received' (duration: 54.432314ms)","trace[161580236] 'applied index is now lower than readState.Index' (duration: 53.442329ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:53.445234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.058558ms","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-09T13:15:53.44527Z","caller":"traceutil/trace.go:171","msg":"trace[388030830] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2103; }","duration":"108.118059ms","start":"2026-07-09T13:15:53.337139Z","end":"2026-07-09T13:15:53.445257Z","steps":["trace[388030830] 'agreement among raft nodes before linearized reading' (duration: 107.981955ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:53.445333Z","caller":"traceutil/trace.go:171","msg":"trace[53972430] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"111.59851ms","start":"2026-07-09T13:15:53.333697Z","end":"2026-07-09T13:15:53.445295Z","steps":["trace[53972430] 'process raft request' (duration: 111.24461ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:11.968866Z","caller":"traceutil/trace.go:171","msg":"trace[1383617733] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"104.313861ms","start":"2026-07-09T13:17:11.864533Z","end":"2026-07-09T13:17:11.968847Z","steps":["trace[1383617733] 'process raft request' (duration: 104.184628ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:18.087789Z","caller":"traceutil/trace.go:171","msg":"trace[155140044] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"102.236876ms","start":"2026-07-09T13:17:17.985524Z","end":"2026-07-09T13:17:18.08776Z","steps":["trace[155140044] 'process raft request' (duration: 102.045021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:36.759466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.047029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:17:36.759643Z","caller":"traceutil/trace.go:171","msg":"trace[802666966] linearizableReadLoop","detail":"{readStateIndex:2723; appliedIndex:2722; }","duration":"164.9809ms","start":"2026-07-09T13:17:36.59464Z","end":"2026-07-09T13:17:36.759621Z","steps":["trace[802666966] 'read index received' (duration: 54.212µs)","trace[802666966] 'applied index is now lower than readState.Index' (duration: 164.924707ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:36.759739Z","caller":"traceutil/trace.go:171","msg":"trace[2124044230] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"239.960142ms","start":"2026-07-09T13:17:36.519756Z","end":"2026-07-09T13:17:36.759716Z","steps":["trace[2124044230] 'process raft request' (duration: 58.575834ms)","trace[2124044230] 'compare' (duration: 180.948827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:17:36.759847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.206515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-72dp4\" ","response":"range_response_count:1 size:5486"} {"level":"info","ts":"2026-07-09T13:17:36.759882Z","caller":"traceutil/trace.go:171","msg":"trace[1201713534] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-72dp4; range_end:; response_count:1; response_revision:2613; }","duration":"165.278917ms","start":"2026-07-09T13:17:36.594591Z","end":"2026-07-09T13:17:36.75987Z","steps":["trace[1201713534] 'agreement among raft nodes before linearized reading' (duration: 165.143693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:46.686744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.983405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363694"} {"level":"info","ts":"2026-07-09T13:17:46.686804Z","caller":"traceutil/trace.go:171","msg":"trace[2121325200] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2657; }","duration":"114.104728ms","start":"2026-07-09T13:17:46.572686Z","end":"2026-07-09T13:17:46.686791Z","steps":["trace[2121325200] 'range keys from in-memory index tree' (duration: 113.689416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:17:46.686849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.871149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-09T13:17:46.686727Z","caller":"traceutil/trace.go:171","msg":"trace[350754523] linearizableReadLoop","detail":"{readStateIndex:2770; appliedIndex:2769; }","duration":"111.711595ms","start":"2026-07-09T13:17:46.574977Z","end":"2026-07-09T13:17:46.686689Z","steps":["trace[350754523] 'read index received' (duration: 90.15883ms)","trace[350754523] 'applied index is now lower than readState.Index' (duration: 21.551535ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:17:46.686938Z","caller":"traceutil/trace.go:171","msg":"trace[2101080732] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:2658; }","duration":"111.997462ms","start":"2026-07-09T13:17:46.574928Z","end":"2026-07-09T13:17:46.686926Z","steps":["trace[2101080732] 'agreement among raft nodes before linearized reading' (duration: 111.855258ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:17:46.686883Z","caller":"traceutil/trace.go:171","msg":"trace[1465500079] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"113.717118ms","start":"2026-07-09T13:17:46.573148Z","end":"2026-07-09T13:17:46.686865Z","steps":["trace[1465500079] 'process raft request' (duration: 92.05906ms)","trace[1465500079] 'compare' (duration: 21.35619ms)"],"step_count":2}