{"level":"warn","ts":"2026-04-13T17:06:30.899242Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:06:30.899349Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.103: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.103:2380","--initial-cluster=instance=https://199.19.213.103:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.103:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.103: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-04-13T17:06:30.899448Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:06:30.899461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-04-13T17:06:30.899484Z","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-04-13T17:06:30.900174Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"]} {"level":"info","ts":"2026-04-13T17:06:30.900256Z","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.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.103: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-04-13T17:06:30.938285Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"37.79541ms"} {"level":"info","ts":"2026-04-13T17:06:30.959016Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d82047944f922143","cluster-id":"223a09c0da413f53"} {"level":"info","ts":"2026-04-13T17:06:30.959149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:06:30.95969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 0"} {"level":"info","ts":"2026-04-13T17:06:30.959928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d82047944f922143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:06:30.960108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 1"} {"level":"info","ts":"2026-04-13T17:06:30.960239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"warn","ts":"2026-04-13T17:06:30.976041Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:06:30.97786Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:06:30.979309Z","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-04-13T17:06:30.982748Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d82047944f922143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:06:30.986337Z","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-04-13T17:06:30.986479Z","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-04-13T17:06:30.986493Z","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-04-13T17:06:30.986992Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d82047944f922143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:06:30.987121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"info","ts":"2026-04-13T17:06:30.987362Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","added-peer-id":"d82047944f922143","added-peer-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-04-13T17:06:30.991358Z","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-04-13T17:06:30.991536Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-04-13T17:06:30.991629Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-04-13T17:06:30.992334Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d82047944f922143","initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:06:30.992416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:06:31.6609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:06:31.660977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:06:31.661027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgPreVoteResp from d82047944f922143 at term 1"} {"level":"info","ts":"2026-04-13T17:06:31.661046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:06:31.661057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgVoteResp from d82047944f922143 at term 2"} {"level":"info","ts":"2026-04-13T17:06:31.661072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became leader at term 2"} {"level":"info","ts":"2026-04-13T17:06:31.661084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d82047944f922143 elected leader d82047944f922143 at term 2"} {"level":"info","ts":"2026-04-13T17:06:31.662336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d82047944f922143","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.103:2379]}","request-path":"/0/members/d82047944f922143/attributes","cluster-id":"223a09c0da413f53","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:06:31.662513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:06:31.662594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:06:31.662879Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:06:31.662893Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:06:31.662943Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:06:31.665718Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.103:2379"} {"level":"info","ts":"2026-04-13T17:06:31.666302Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:06:31.666301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:06:31.666375Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:06:31.666398Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-13T17:06:36.1618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.576078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:06:36.161943Z","caller":"traceutil/trace.go:171","msg":"trace[1076041691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:245; }","duration":"522.833391ms","start":"2026-04-13T17:06:35.639081Z","end":"2026-04-13T17:06:36.161915Z","steps":["trace[1076041691] 'range keys from in-memory index tree' (duration: 522.404705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:36.162011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:35.639051Z","time spent":"522.947616ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:06:36.1618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.858158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-04-13T17:06:36.162085Z","caller":"traceutil/trace.go:171","msg":"trace[1943690643] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:245; }","duration":"333.214937ms","start":"2026-04-13T17:06:35.828847Z","end":"2026-04-13T17:06:36.162062Z","steps":["trace[1943690643] 'range keys from in-memory index tree' (duration: 332.675713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:36.16219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:35.828807Z","time spent":"333.317592ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-13T17:06:36.307924Z","caller":"traceutil/trace.go:171","msg":"trace[833210273] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"123.425895ms","start":"2026-04-13T17:06:36.184444Z","end":"2026-04-13T17:06:36.307869Z","steps":["trace[833210273] 'read index received' (duration: 98.805224ms)","trace[833210273] 'applied index is now lower than readState.Index' (duration: 24.614021ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:06:36.30795Z","caller":"traceutil/trace.go:171","msg":"trace[1977452137] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"137.700745ms","start":"2026-04-13T17:06:36.170183Z","end":"2026-04-13T17:06:36.307884Z","steps":["trace[1977452137] 'process raft request' (duration: 113.092843ms)","trace[1977452137] 'compare' (duration: 24.400568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:36.308216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.742774ms","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-04-13T17:06:36.308322Z","caller":"traceutil/trace.go:171","msg":"trace[511404126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:247; }","duration":"123.918339ms","start":"2026-04-13T17:06:36.184382Z","end":"2026-04-13T17:06:36.3083Z","steps":["trace[511404126] 'agreement among raft nodes before linearized reading' (duration: 123.701066ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.518129Z","caller":"traceutil/trace.go:171","msg":"trace[923384843] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"110.668978ms","start":"2026-04-13T17:06:37.407403Z","end":"2026-04-13T17:06:37.518072Z","steps":["trace[923384843] 'process raft request' (duration: 106.552749ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.718282Z","caller":"traceutil/trace.go:171","msg":"trace[516045355] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"169.1676ms","start":"2026-04-13T17:06:37.549076Z","end":"2026-04-13T17:06:37.718244Z","steps":["trace[516045355] 'process raft request' (duration: 153.192453ms)","trace[516045355] 'compare' (duration: 15.293849ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:06:37.718444Z","caller":"traceutil/trace.go:171","msg":"trace[1171491219] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"129.239881ms","start":"2026-04-13T17:06:37.58918Z","end":"2026-04-13T17:06:37.71842Z","steps":["trace[1171491219] 'process raft request' (duration: 128.705789ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.718515Z","caller":"traceutil/trace.go:171","msg":"trace[1931354896] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:296; }","duration":"100.998738ms","start":"2026-04-13T17:06:37.617458Z","end":"2026-04-13T17:06:37.718457Z","steps":["trace[1931354896] 'read index received' (duration: 84.784979ms)","trace[1931354896] 'applied index is now lower than readState.Index' (duration: 16.202829ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:06:37.718572Z","caller":"traceutil/trace.go:171","msg":"trace[776601577] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"129.759296ms","start":"2026-04-13T17:06:37.588775Z","end":"2026-04-13T17:06:37.718534Z","steps":["trace[776601577] 'process raft request' (duration: 129.081688ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.718604Z","caller":"traceutil/trace.go:171","msg":"trace[1519715252] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"129.0352ms","start":"2026-04-13T17:06:37.589552Z","end":"2026-04-13T17:06:37.718587Z","steps":["trace[1519715252] 'process raft request' (duration: 128.40132ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.718774Z","caller":"traceutil/trace.go:171","msg":"trace[1056920725] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"129.989118ms","start":"2026-04-13T17:06:37.588775Z","end":"2026-04-13T17:06:37.718764Z","steps":["trace[1056920725] 'process raft request' (duration: 129.046379ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.718782Z","caller":"traceutil/trace.go:171","msg":"trace[757578576] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"155.47555ms","start":"2026-04-13T17:06:37.563278Z","end":"2026-04-13T17:06:37.718753Z","steps":["trace[757578576] 'process raft request' (duration: 154.476382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.718816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.90733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-13T17:06:37.718819Z","caller":"traceutil/trace.go:171","msg":"trace[1929136318] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"129.130575ms","start":"2026-04-13T17:06:37.589667Z","end":"2026-04-13T17:06:37.718797Z","steps":["trace[1929136318] 'process raft request' (duration: 128.32477ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.718865Z","caller":"traceutil/trace.go:171","msg":"trace[1763018919] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"192.014877ms","start":"2026-04-13T17:06:37.526835Z","end":"2026-04-13T17:06:37.71885Z","steps":["trace[1763018919] 'agreement among raft nodes before linearized reading' (duration: 191.807023ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:42.979105Z","caller":"traceutil/trace.go:171","msg":"trace[497492602] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"137.882575ms","start":"2026-04-13T17:06:42.841199Z","end":"2026-04-13T17:06:42.979081Z","steps":["trace[497492602] 'process raft request' (duration: 137.723218ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:45.705522Z","caller":"traceutil/trace.go:171","msg":"trace[60027280] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"149.754074ms","start":"2026-04-13T17:06:45.555739Z","end":"2026-04-13T17:06:45.705493Z","steps":["trace[60027280] 'read index received' (duration: 144.345174ms)","trace[60027280] 'applied index is now lower than readState.Index' (duration: 5.40794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:45.705662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.90509ms","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-04-13T17:06:45.705702Z","caller":"traceutil/trace.go:171","msg":"trace[1996389793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"149.993389ms","start":"2026-04-13T17:06:45.555696Z","end":"2026-04-13T17:06:45.70569Z","steps":["trace[1996389793] 'agreement among raft nodes before linearized reading' (duration: 149.900241ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:45.705899Z","caller":"traceutil/trace.go:171","msg":"trace[1052614620] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"171.284254ms","start":"2026-04-13T17:06:45.534263Z","end":"2026-04-13T17:06:45.705548Z","steps":["trace[1052614620] 'process raft request' (duration: 165.740037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:51.539466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.825859ms","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-04-13T17:06:51.53959Z","caller":"traceutil/trace.go:171","msg":"trace[87668859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:450; }","duration":"281.075346ms","start":"2026-04-13T17:06:51.258486Z","end":"2026-04-13T17:06:51.539561Z","steps":["trace[87668859] 'range keys from in-memory index tree' (duration: 280.67061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:57.043277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.010249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:06:57.043409Z","caller":"traceutil/trace.go:171","msg":"trace[73171543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"311.236778ms","start":"2026-04-13T17:06:56.732149Z","end":"2026-04-13T17:06:57.043386Z","steps":["trace[73171543] 'range keys from in-memory index tree' (duration: 310.87474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:57.043681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:56.732123Z","time spent":"311.546176ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:07:02.170084Z","caller":"traceutil/trace.go:171","msg":"trace[1995852273] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"435.632513ms","start":"2026-04-13T17:07:01.734421Z","end":"2026-04-13T17:07:02.170054Z","steps":["trace[1995852273] 'read index received' (duration: 435.431293ms)","trace[1995852273] 'applied index is now lower than readState.Index' (duration: 199.32µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:02.170244Z","caller":"traceutil/trace.go:171","msg":"trace[1517737549] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"569.527593ms","start":"2026-04-13T17:07:01.600638Z","end":"2026-04-13T17:07:02.170165Z","steps":["trace[1517737549] 'process raft request' (duration: 569.234354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:02.170314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.875572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:02.170409Z","caller":"traceutil/trace.go:171","msg":"trace[295089351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"436.043223ms","start":"2026-04-13T17:07:01.734341Z","end":"2026-04-13T17:07:02.170384Z","steps":["trace[295089351] 'agreement among raft nodes before linearized reading' (duration: 435.855434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:02.170461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:01.734305Z","time spent":"436.143972ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:07:02.170461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.367846ms","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-04-13T17:07:02.170526Z","caller":"traceutil/trace.go:171","msg":"trace[1394234029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"356.531326ms","start":"2026-04-13T17:07:01.813984Z","end":"2026-04-13T17:07:02.170516Z","steps":["trace[1394234029] 'agreement among raft nodes before linearized reading' (duration: 356.339305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:02.170586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:01.813947Z","time spent":"356.629885ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:07:02.173546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:01.600601Z","time spent":"569.797014ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:07.363354Z","caller":"traceutil/trace.go:171","msg":"trace[1375847422] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"200.669535ms","start":"2026-04-13T17:07:07.162642Z","end":"2026-04-13T17:07:07.363312Z","steps":["trace[1375847422] 'process raft request' (duration: 116.46264ms)","trace[1375847422] 'compare' (duration: 84.010814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:07:07.812223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.193228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:07:07.812584Z","caller":"traceutil/trace.go:171","msg":"trace[908193147] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"641.652262ms","start":"2026-04-13T17:07:07.170906Z","end":"2026-04-13T17:07:07.812558Z","steps":["trace[908193147] 'process raft request' (duration: 308.860801ms)","trace[908193147] 'compare' (duration: 332.007838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:07:07.812738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.170868Z","time spent":"641.818153ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:07.812825Z","caller":"traceutil/trace.go:171","msg":"trace[1897482985] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"596.375474ms","start":"2026-04-13T17:07:07.216396Z","end":"2026-04-13T17:07:07.812772Z","steps":["trace[1897482985] 'process raft request' (duration: 595.956842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:07.813087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.216367Z","time spent":"596.607845ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:07:07.813612Z","caller":"traceutil/trace.go:171","msg":"trace[1012432453] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:610; }","duration":"225.785712ms","start":"2026-04-13T17:07:07.587811Z","end":"2026-04-13T17:07:07.813597Z","steps":["trace[1012432453] 'read index received' (duration: 197.81797ms)","trace[1012432453] 'applied index is now lower than readState.Index' (duration: 27.966762ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:07.813698Z","caller":"traceutil/trace.go:171","msg":"trace[1504595588] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"402.754535ms","start":"2026-04-13T17:07:07.410935Z","end":"2026-04-13T17:07:07.813689Z","steps":["trace[1504595588] 'process raft request' (duration: 402.628224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:07.813759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.410889Z","time spent":"402.842756ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:07:07.813804Z","caller":"traceutil/trace.go:171","msg":"trace[1790391120] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"468.356867ms","start":"2026-04-13T17:07:07.345418Z","end":"2026-04-13T17:07:07.813775Z","steps":["trace[1790391120] 'process raft request' (duration: 467.421303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:07.813832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.015183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-mwkzz\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-04-13T17:07:07.813885Z","caller":"traceutil/trace.go:171","msg":"trace[1791020900] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-mwkzz; range_end:; response_count:1; response_revision:595; }","duration":"226.110512ms","start":"2026-04-13T17:07:07.587761Z","end":"2026-04-13T17:07:07.813872Z","steps":["trace[1791020900] 'agreement among raft nodes before linearized reading' (duration: 225.971241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:07.813918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.345369Z","time spent":"468.503138ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:07:07.813663Z","caller":"traceutil/trace.go:171","msg":"trace[1027945309] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"440.117535ms","start":"2026-04-13T17:07:07.373505Z","end":"2026-04-13T17:07:07.813622Z","steps":["trace[1027945309] 'process raft request' (duration: 440.007924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:07.814218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.373466Z","time spent":"440.695017ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:07.867993Z","caller":"traceutil/trace.go:171","msg":"trace[258045517] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"262.407027ms","start":"2026-04-13T17:07:07.605552Z","end":"2026-04-13T17:07:07.867959Z","steps":["trace[258045517] 'process raft request' (duration: 262.240866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.10597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.140943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:07:08.106344Z","caller":"traceutil/trace.go:171","msg":"trace[1362657338] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"300.503198ms","start":"2026-04-13T17:07:07.805775Z","end":"2026-04-13T17:07:08.106278Z","steps":["trace[1362657338] 'process raft request' (duration: 199.876772ms)","trace[1362657338] 'compare' (duration: 99.870161ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:08.106398Z","caller":"traceutil/trace.go:171","msg":"trace[828341241] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"283.11208ms","start":"2026-04-13T17:07:07.823256Z","end":"2026-04-13T17:07:08.106368Z","steps":["trace[828341241] 'process raft request' (duration: 282.843369ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:08.106633Z","caller":"traceutil/trace.go:171","msg":"trace[2015125243] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"275.054039ms","start":"2026-04-13T17:07:07.831523Z","end":"2026-04-13T17:07:08.106577Z","steps":["trace[2015125243] 'process raft request' (duration: 274.700328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.106629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.805742Z","time spent":"300.75756ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:07:08.106971Z","caller":"traceutil/trace.go:171","msg":"trace[642212860] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"270.774159ms","start":"2026-04-13T17:07:07.836165Z","end":"2026-04-13T17:07:08.106939Z","steps":["trace[642212860] 'process raft request' (duration: 270.122305ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:08.107625Z","caller":"traceutil/trace.go:171","msg":"trace[382937201] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"192.194902ms","start":"2026-04-13T17:07:07.915421Z","end":"2026-04-13T17:07:08.107616Z","steps":["trace[382937201] 'process raft request' (duration: 192.151062ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:08.107742Z","caller":"traceutil/trace.go:171","msg":"trace[354461210] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"294.076688ms","start":"2026-04-13T17:07:07.813657Z","end":"2026-04-13T17:07:08.107733Z","steps":["trace[354461210] 'read index received' (duration: 54.222955ms)","trace[354461210] 'applied index is now lower than readState.Index' (duration: 239.853083ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:08.10776Z","caller":"traceutil/trace.go:171","msg":"trace[486061622] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"267.045621ms","start":"2026-04-13T17:07:07.840677Z","end":"2026-04-13T17:07:08.107722Z","steps":["trace[486061622] 'process raft request' (duration: 266.809769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.107822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.075376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:08.10802Z","caller":"traceutil/trace.go:171","msg":"trace[1179881734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"373.170386ms","start":"2026-04-13T17:07:07.734675Z","end":"2026-04-13T17:07:08.107846Z","steps":["trace[1179881734] 'agreement among raft nodes before linearized reading' (duration: 373.088535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.108057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:07.734647Z","time spent":"373.401318ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:07:08.121269Z","caller":"traceutil/trace.go:171","msg":"trace[697529081] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"114.868421ms","start":"2026-04-13T17:07:08.006388Z","end":"2026-04-13T17:07:08.121256Z","steps":["trace[697529081] 'process raft request' (duration: 114.609589ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:08.751554Z","caller":"traceutil/trace.go:171","msg":"trace[1634752405] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"343.799948ms","start":"2026-04-13T17:07:08.407721Z","end":"2026-04-13T17:07:08.751521Z","steps":["trace[1634752405] 'process raft request' (duration: 343.474186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.751868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:08.407678Z","time spent":"343.97955ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:08.76795Z","caller":"traceutil/trace.go:171","msg":"trace[2034504365] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"358.386135ms","start":"2026-04-13T17:07:08.409336Z","end":"2026-04-13T17:07:08.767722Z","steps":["trace[2034504365] 'process raft request' (duration: 357.63366ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:08.767972Z","caller":"traceutil/trace.go:171","msg":"trace[766522059] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"341.192012ms","start":"2026-04-13T17:07:08.426489Z","end":"2026-04-13T17:07:08.767681Z","steps":["trace[766522059] 'process raft request' (duration: 340.607299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.768205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:08.409308Z","time spent":"358.785459ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:07:08.768511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:08.426468Z","time spent":"341.649365ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4604,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:08.776155Z","caller":"traceutil/trace.go:171","msg":"trace[1956867221] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:636; }","duration":"345.779291ms","start":"2026-04-13T17:07:08.430287Z","end":"2026-04-13T17:07:08.776066Z","steps":["trace[1956867221] 'read index received' (duration: 320.978632ms)","trace[1956867221] 'applied index is now lower than readState.Index' (duration: 24.800009ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:08.776336Z","caller":"traceutil/trace.go:171","msg":"trace[1962117630] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"234.247659ms","start":"2026-04-13T17:07:08.542077Z","end":"2026-04-13T17:07:08.776324Z","steps":["trace[1962117630] 'process raft request' (duration: 226.13238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.776824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.628623ms","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-04-13T17:07:08.776862Z","caller":"traceutil/trace.go:171","msg":"trace[89494710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"206.729393ms","start":"2026-04-13T17:07:08.570122Z","end":"2026-04-13T17:07:08.776851Z","steps":["trace[89494710] 'agreement among raft nodes before linearized reading' (duration: 206.561753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.777367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.072238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-13T17:07:08.7774Z","caller":"traceutil/trace.go:171","msg":"trace[1539576591] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:622; }","duration":"347.146718ms","start":"2026-04-13T17:07:08.430243Z","end":"2026-04-13T17:07:08.77739Z","steps":["trace[1539576591] 'agreement among raft nodes before linearized reading' (duration: 346.234943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:08.77742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:08.430215Z","time spent":"347.197919ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80726,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-13T17:07:08.777739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.458411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T17:07:08.777767Z","caller":"traceutil/trace.go:171","msg":"trace[1222383443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"126.540851ms","start":"2026-04-13T17:07:08.651219Z","end":"2026-04-13T17:07:08.77776Z","steps":["trace[1222383443] 'agreement among raft nodes before linearized reading' (duration: 126.47523ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:08.7766Z","caller":"traceutil/trace.go:171","msg":"trace[795708573] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"170.850708ms","start":"2026-04-13T17:07:08.605696Z","end":"2026-04-13T17:07:08.776547Z","steps":["trace[795708573] 'process raft request' (duration: 170.299845ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:09.321744Z","caller":"traceutil/trace.go:171","msg":"trace[98824114] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"248.924544ms","start":"2026-04-13T17:07:09.072792Z","end":"2026-04-13T17:07:09.321717Z","steps":["trace[98824114] 'read index received' (duration: 248.290429ms)","trace[98824114] 'applied index is now lower than readState.Index' (duration: 633.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:09.321855Z","caller":"traceutil/trace.go:171","msg":"trace[2050546453] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"253.662766ms","start":"2026-04-13T17:07:09.068119Z","end":"2026-04-13T17:07:09.321782Z","steps":["trace[2050546453] 'process raft request' (duration: 252.949691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:09.322151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.314436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-04-13T17:07:09.322298Z","caller":"traceutil/trace.go:171","msg":"trace[923540266] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:638; }","duration":"249.522588ms","start":"2026-04-13T17:07:09.072743Z","end":"2026-04-13T17:07:09.322266Z","steps":["trace[923540266] 'agreement among raft nodes before linearized reading' (duration: 249.114164ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:09.330254Z","caller":"traceutil/trace.go:171","msg":"trace[633411480] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"247.662925ms","start":"2026-04-13T17:07:09.08257Z","end":"2026-04-13T17:07:09.330233Z","steps":["trace[633411480] 'process raft request' (duration: 247.556474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:09.330883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.37253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-13T17:07:09.331038Z","caller":"traceutil/trace.go:171","msg":"trace[1585514807] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"126.503981ms","start":"2026-04-13T17:07:09.204419Z","end":"2026-04-13T17:07:09.330923Z","steps":["trace[1585514807] 'agreement among raft nodes before linearized reading' (duration: 126.31653ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:15.195876Z","caller":"traceutil/trace.go:171","msg":"trace[1070140271] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"115.622617ms","start":"2026-04-13T17:07:15.080209Z","end":"2026-04-13T17:07:15.195831Z","steps":["trace[1070140271] 'process raft request' (duration: 111.565256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:16.233587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932633312212485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:07:16.683481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055417676s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:07:16.684147Z","caller":"traceutil/trace.go:171","msg":"trace[2036282092] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"951.084988ms","start":"2026-04-13T17:07:15.733026Z","end":"2026-04-13T17:07:16.684111Z","steps":["trace[2036282092] 'read index received' (duration: 950.703744ms)","trace[2036282092] 'applied index is now lower than readState.Index' (duration: 379.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:16.684447Z","caller":"traceutil/trace.go:171","msg":"trace[1555294628] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.05649139s","start":"2026-04-13T17:07:15.62791Z","end":"2026-04-13T17:07:16.684401Z","steps":["trace[1555294628] 'process raft request' (duration: 1.056003714s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:16.684564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.456394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:16.684745Z","caller":"traceutil/trace.go:171","msg":"trace[1757362359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"951.691058ms","start":"2026-04-13T17:07:15.732984Z","end":"2026-04-13T17:07:16.684675Z","steps":["trace[1757362359] 'agreement among raft nodes before linearized reading' (duration: 951.318292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:16.68478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.491122ms","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-04-13T17:07:16.684841Z","caller":"traceutil/trace.go:171","msg":"trace[1636120420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"385.627304ms","start":"2026-04-13T17:07:16.299195Z","end":"2026-04-13T17:07:16.684823Z","steps":["trace[1636120420] 'agreement among raft nodes before linearized reading' (duration: 385.411421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:16.684845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:15.732967Z","time spent":"951.86546ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:07:16.685002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.299174Z","time spent":"385.725535ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:07:16.68534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.186519ms","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-04-13T17:07:16.685406Z","caller":"traceutil/trace.go:171","msg":"trace[1795452731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"357.351041ms","start":"2026-04-13T17:07:16.328035Z","end":"2026-04-13T17:07:16.685386Z","steps":["trace[1795452731] 'agreement among raft nodes before linearized reading' (duration: 357.111128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:16.685443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.328012Z","time spent":"357.422063ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:07:16.685632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:15.627885Z","time spent":"1.056691072s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:17.214804Z","caller":"traceutil/trace.go:171","msg":"trace[797360942] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"482.147682ms","start":"2026-04-13T17:07:16.732616Z","end":"2026-04-13T17:07:17.214764Z","steps":["trace[797360942] 'read index received' (duration: 406.355549ms)","trace[797360942] 'applied index is now lower than readState.Index' (duration: 75.790363ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:17.215013Z","caller":"traceutil/trace.go:171","msg":"trace[1136928886] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"524.773544ms","start":"2026-04-13T17:07:16.690222Z","end":"2026-04-13T17:07:17.214995Z","steps":["trace[1136928886] 'process raft request' (duration: 524.426359ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:17.215007Z","caller":"traceutil/trace.go:171","msg":"trace[528007898] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"524.869485ms","start":"2026-04-13T17:07:16.690106Z","end":"2026-04-13T17:07:17.214975Z","steps":["trace[528007898] 'process raft request' (duration: 448.845529ms)","trace[528007898] 'compare' (duration: 75.410917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:07:17.215178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.690145Z","time spent":"524.913126ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:07:17.215566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.178661ms","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-04-13T17:07:17.21562Z","caller":"traceutil/trace.go:171","msg":"trace[880815292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"362.294654ms","start":"2026-04-13T17:07:16.853306Z","end":"2026-04-13T17:07:17.215601Z","steps":["trace[880815292] 'agreement among raft nodes before linearized reading' (duration: 362.104251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:17.215665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.85326Z","time spent":"362.393704ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:07:17.215687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.690071Z","time spent":"524.992716ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:07:17.215324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.004177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T17:07:17.215904Z","caller":"traceutil/trace.go:171","msg":"trace[841921263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"374.619644ms","start":"2026-04-13T17:07:16.841255Z","end":"2026-04-13T17:07:17.215875Z","steps":["trace[841921263] 'agreement among raft nodes before linearized reading' (duration: 373.883944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:17.216061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.841226Z","time spent":"374.813519ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:07:17.215386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.774571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:17.216277Z","caller":"traceutil/trace.go:171","msg":"trace[1007945610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"483.684164ms","start":"2026-04-13T17:07:16.732562Z","end":"2026-04-13T17:07:17.216246Z","steps":["trace[1007945610] 'agreement among raft nodes before linearized reading' (duration: 482.795411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:17.216376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:16.73253Z","time spent":"483.817696ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:07:19.721782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932633312212497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:07:19.831244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.09573203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:19.831627Z","caller":"traceutil/trace.go:171","msg":"trace[2120762247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"2.096211348s","start":"2026-04-13T17:07:17.735389Z","end":"2026-04-13T17:07:19.8316Z","steps":["trace[2120762247] 'range keys from in-memory index tree' (duration: 2.095608589s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:19.831687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:17.735366Z","time spent":"2.096307569s","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:07:19.83173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.436114862s","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-04-13T17:07:19.831857Z","caller":"traceutil/trace.go:171","msg":"trace[1095595444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:696; }","duration":"2.436283975s","start":"2026-04-13T17:07:17.395546Z","end":"2026-04-13T17:07:19.83183Z","steps":["trace[1095595444] 'range keys from in-memory index tree' (duration: 2.435587155s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:19.83195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:17.39553Z","time spent":"2.436404077s","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T17:07:20.222872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932633312212497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:07:20.265968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.12946464s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:07:20.266792Z","caller":"traceutil/trace.go:171","msg":"trace[351787963] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"2.130360565s","start":"2026-04-13T17:07:18.136325Z","end":"2026-04-13T17:07:20.266686Z","steps":["trace[351787963] 'process raft request' (duration: 2.129959188s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:20.267088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:18.136289Z","time spent":"2.13064561s","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:20.283837Z","caller":"traceutil/trace.go:171","msg":"trace[433086699] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"445.894921ms","start":"2026-04-13T17:07:19.837924Z","end":"2026-04-13T17:07:20.283819Z","steps":["trace[433086699] 'process raft request' (duration: 445.652508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:20.284047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:19.837806Z","time spent":"446.150174ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:20.28892Z","caller":"traceutil/trace.go:171","msg":"trace[730142550] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:718; }","duration":"1.068045451s","start":"2026-04-13T17:07:19.220836Z","end":"2026-04-13T17:07:20.288882Z","steps":["trace[730142550] 'read index received' (duration: 1.045479739s)","trace[730142550] 'applied index is now lower than readState.Index' (duration: 22.563611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:07:20.289161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068294085s","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-04-13T17:07:20.289204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068259705s","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-04-13T17:07:20.289227Z","caller":"traceutil/trace.go:171","msg":"trace[832272700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"1.068398637s","start":"2026-04-13T17:07:19.220817Z","end":"2026-04-13T17:07:20.289216Z","steps":["trace[832272700] 'agreement among raft nodes before linearized reading' (duration: 1.068150523s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:20.28926Z","caller":"traceutil/trace.go:171","msg":"trace[65084485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:698; }","duration":"1.068320815s","start":"2026-04-13T17:07:19.220924Z","end":"2026-04-13T17:07:20.289245Z","steps":["trace[65084485] 'agreement among raft nodes before linearized reading' (duration: 1.068166173s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:20.289309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:19.220823Z","time spent":"1.068477888s","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:07:20.289346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.969972ms","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-04-13T17:07:20.289367Z","caller":"traceutil/trace.go:171","msg":"trace[1572256906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:698; }","duration":"980.015873ms","start":"2026-04-13T17:07:19.309345Z","end":"2026-04-13T17:07:20.289361Z","steps":["trace[1572256906] 'agreement among raft nodes before linearized reading' (duration: 979.950722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:20.289385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:19.309331Z","time spent":"980.049074ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:07:20.28927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:19.220734Z","time spent":"1.068526328s","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:07:20.289492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032395533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T17:07:20.289512Z","caller":"traceutil/trace.go:171","msg":"trace[1573580670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:698; }","duration":"1.032415474s","start":"2026-04-13T17:07:19.25709Z","end":"2026-04-13T17:07:20.289506Z","steps":["trace[1573580670] 'agreement among raft nodes before linearized reading' (duration: 1.032357633s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:20.289535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:19.257039Z","time spent":"1.032488474s","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:07:20.289632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.118062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:20.290622Z","caller":"traceutil/trace.go:171","msg":"trace[1416294654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"554.583577ms","start":"2026-04-13T17:07:19.736023Z","end":"2026-04-13T17:07:20.290607Z","steps":["trace[1416294654] 'agreement among raft nodes before linearized reading' (duration: 553.078462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:20.290671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:19.73594Z","time spent":"554.708549ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:07:25.994031Z","caller":"traceutil/trace.go:171","msg":"trace[1482646973] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"260.466491ms","start":"2026-04-13T17:07:25.733533Z","end":"2026-04-13T17:07:25.993999Z","steps":["trace[1482646973] 'read index received' (duration: 260.228826ms)","trace[1482646973] 'applied index is now lower than readState.Index' (duration: 236.775µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:25.994112Z","caller":"traceutil/trace.go:171","msg":"trace[1256608398] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"289.166933ms","start":"2026-04-13T17:07:25.704915Z","end":"2026-04-13T17:07:25.994081Z","steps":["trace[1256608398] 'process raft request' (duration: 288.890567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:25.994247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.690085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:25.994297Z","caller":"traceutil/trace.go:171","msg":"trace[990328581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"260.811658ms","start":"2026-04-13T17:07:25.733477Z","end":"2026-04-13T17:07:25.994288Z","steps":["trace[990328581] 'agreement among raft nodes before linearized reading' (duration: 260.694136ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:27.892994Z","caller":"traceutil/trace.go:171","msg":"trace[1074928760] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"158.806993ms","start":"2026-04-13T17:07:27.734089Z","end":"2026-04-13T17:07:27.892896Z","steps":["trace[1074928760] 'read index received' (duration: 158.638279ms)","trace[1074928760] 'applied index is now lower than readState.Index' (duration: 166.983µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:27.893004Z","caller":"traceutil/trace.go:171","msg":"trace[1473433051] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"163.753419ms","start":"2026-04-13T17:07:27.729202Z","end":"2026-04-13T17:07:27.892955Z","steps":["trace[1473433051] 'process raft request' (duration: 163.495914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:27.893141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.027957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:27.89318Z","caller":"traceutil/trace.go:171","msg":"trace[780132031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"159.15168ms","start":"2026-04-13T17:07:27.734016Z","end":"2026-04-13T17:07:27.893168Z","steps":["trace[780132031] 'agreement among raft nodes before linearized reading' (duration: 159.063988ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:28.169358Z","caller":"traceutil/trace.go:171","msg":"trace[918571047] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"127.655816ms","start":"2026-04-13T17:07:28.041664Z","end":"2026-04-13T17:07:28.16932Z","steps":["trace[918571047] 'process raft request' (duration: 127.412441ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:28.667291Z","caller":"traceutil/trace.go:171","msg":"trace[1314637735] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"145.129957ms","start":"2026-04-13T17:07:28.522119Z","end":"2026-04-13T17:07:28.667249Z","steps":["trace[1314637735] 'process raft request' (duration: 142.5742ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:28.667266Z","caller":"traceutil/trace.go:171","msg":"trace[467495680] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"143.693074ms","start":"2026-04-13T17:07:28.523491Z","end":"2026-04-13T17:07:28.667184Z","steps":["trace[467495680] 'process raft request' (duration: 143.615082ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:30.804833Z","caller":"traceutil/trace.go:171","msg":"trace[163731325] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"245.605333ms","start":"2026-04-13T17:07:30.559189Z","end":"2026-04-13T17:07:30.804794Z","steps":["trace[163731325] 'process raft request' (duration: 245.388868ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:30.823763Z","caller":"traceutil/trace.go:171","msg":"trace[501942126] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"152.950972ms","start":"2026-04-13T17:07:30.670792Z","end":"2026-04-13T17:07:30.823743Z","steps":["trace[501942126] 'read index received' (duration: 133.845248ms)","trace[501942126] 'applied index is now lower than readState.Index' (duration: 19.104504ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:30.823877Z","caller":"traceutil/trace.go:171","msg":"trace[1791519905] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"264.106461ms","start":"2026-04-13T17:07:30.55976Z","end":"2026-04-13T17:07:30.823867Z","steps":["trace[1791519905] 'process raft request' (duration: 263.821395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:30.824197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.369723ms","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-04-13T17:07:30.824258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.322006ms","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-04-13T17:07:30.82431Z","caller":"traceutil/trace.go:171","msg":"trace[2094362302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"152.45513ms","start":"2026-04-13T17:07:30.67184Z","end":"2026-04-13T17:07:30.824295Z","steps":["trace[2094362302] 'agreement among raft nodes before linearized reading' (duration: 152.255545ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:30.824316Z","caller":"traceutil/trace.go:171","msg":"trace[417176023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"153.540846ms","start":"2026-04-13T17:07:30.670745Z","end":"2026-04-13T17:07:30.824286Z","steps":["trace[417176023] 'agreement among raft nodes before linearized reading' (duration: 153.243619ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:30.824515Z","caller":"traceutil/trace.go:171","msg":"trace[738515342] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"154.1316ms","start":"2026-04-13T17:07:30.670365Z","end":"2026-04-13T17:07:30.824497Z","steps":["trace[738515342] 'process raft request' (duration: 153.321251ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:38.594833Z","caller":"traceutil/trace.go:171","msg":"trace[2075338545] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"162.249724ms","start":"2026-04-13T17:07:38.432558Z","end":"2026-04-13T17:07:38.594808Z","steps":["trace[2075338545] 'read index received' (duration: 162.039958ms)","trace[2075338545] 'applied index is now lower than readState.Index' (duration: 208.836µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:38.595095Z","caller":"traceutil/trace.go:171","msg":"trace[96493899] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"826.734212ms","start":"2026-04-13T17:07:37.768334Z","end":"2026-04-13T17:07:38.595068Z","steps":["trace[96493899] 'process raft request' (duration: 826.337451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:38.595115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.543384ms","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-04-13T17:07:38.595172Z","caller":"traceutil/trace.go:171","msg":"trace[1502442295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"162.668507ms","start":"2026-04-13T17:07:38.432491Z","end":"2026-04-13T17:07:38.595159Z","steps":["trace[1502442295] 'agreement among raft nodes before linearized reading' (duration: 162.43736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:38.5953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:37.768292Z","time spent":"826.874516ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:08:11.391131Z","caller":"traceutil/trace.go:171","msg":"trace[1897004152] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"164.812221ms","start":"2026-04-13T17:08:11.226289Z","end":"2026-04-13T17:08:11.391101Z","steps":["trace[1897004152] 'process raft request' (duration: 110.876111ms)","trace[1897004152] 'compare' (duration: 53.790414ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:08:19.59803Z","caller":"traceutil/trace.go:171","msg":"trace[10851922] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"172.064248ms","start":"2026-04-13T17:08:19.425933Z","end":"2026-04-13T17:08:19.597997Z","steps":["trace[10851922] 'read index received' (duration: 171.707972ms)","trace[10851922] 'applied index is now lower than readState.Index' (duration: 354.396µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:08:19.598242Z","caller":"traceutil/trace.go:171","msg":"trace[842424372] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"180.426159ms","start":"2026-04-13T17:08:19.417784Z","end":"2026-04-13T17:08:19.59821Z","steps":["trace[842424372] 'process raft request' (duration: 179.959488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:19.598312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.361163ms","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-04-13T17:08:19.598409Z","caller":"traceutil/trace.go:171","msg":"trace[1574781239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"172.457556ms","start":"2026-04-13T17:08:19.425882Z","end":"2026-04-13T17:08:19.598339Z","steps":["trace[1574781239] 'agreement among raft nodes before linearized reading' (duration: 172.232745ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:09:29.30243Z","caller":"traceutil/trace.go:171","msg":"trace[1701421597] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"115.547162ms","start":"2026-04-13T17:09:29.186841Z","end":"2026-04-13T17:09:29.302388Z","steps":["trace[1701421597] 'process raft request' (duration: 115.293927ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:09:29.575505Z","caller":"traceutil/trace.go:171","msg":"trace[1312506712] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"108.813888ms","start":"2026-04-13T17:09:29.466607Z","end":"2026-04-13T17:09:29.575421Z","steps":["trace[1312506712] 'process raft request' (duration: 57.811209ms)","trace[1312506712] 'compare' (duration: 50.690431ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:09:29.575643Z","caller":"traceutil/trace.go:171","msg":"trace[1483600906] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"108.645398ms","start":"2026-04-13T17:09:29.466962Z","end":"2026-04-13T17:09:29.575608Z","steps":["trace[1483600906] 'process raft request' (duration: 108.389793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:42.209335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.519395ms","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-04-13T17:09:42.20988Z","caller":"traceutil/trace.go:171","msg":"trace[1159098187] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1396; }","duration":"379.08005ms","start":"2026-04-13T17:09:41.830775Z","end":"2026-04-13T17:09:42.209855Z","steps":["trace[1159098187] 'range keys from in-memory index tree' (duration: 378.499643ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:09:42.210589Z","caller":"traceutil/trace.go:171","msg":"trace[1371500666] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"477.012085ms","start":"2026-04-13T17:09:41.733557Z","end":"2026-04-13T17:09:42.210569Z","steps":["trace[1371500666] 'read index received' (duration: 444.423711ms)","trace[1371500666] 'applied index is now lower than readState.Index' (duration: 32.587354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:09:42.210759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.200057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:09:42.210813Z","caller":"traceutil/trace.go:171","msg":"trace[312217687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1397; }","duration":"477.26858ms","start":"2026-04-13T17:09:41.733534Z","end":"2026-04-13T17:09:42.210802Z","steps":["trace[312217687] 'agreement among raft nodes before linearized reading' (duration: 477.156953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:42.210841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:41.733479Z","time spent":"477.356607ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:09:42.210877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.324141ms","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-04-13T17:09:42.211008Z","caller":"traceutil/trace.go:171","msg":"trace[126047910] 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:1397; }","duration":"403.473601ms","start":"2026-04-13T17:09:41.807486Z","end":"2026-04-13T17:09:42.21096Z","steps":["trace[126047910] 'agreement among raft nodes before linearized reading' (duration: 403.309831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:42.211047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.795775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:09:42.211063Z","caller":"traceutil/trace.go:171","msg":"trace[1223837382] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1397; }","duration":"327.851088ms","start":"2026-04-13T17:09:41.883207Z","end":"2026-04-13T17:09:42.211058Z","steps":["trace[1223837382] 'agreement among raft nodes before linearized reading' (duration: 327.817845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:42.211077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:41.883184Z","time spent":"327.89008ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":10,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-13T17:09:42.210805Z","caller":"traceutil/trace.go:171","msg":"trace[698759253] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"519.476032ms","start":"2026-04-13T17:09:41.691152Z","end":"2026-04-13T17:09:42.210628Z","steps":["trace[698759253] 'process raft request' (duration: 486.807782ms)","trace[698759253] 'compare' (duration: 32.481419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:09:42.211108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:41.807423Z","time spent":"403.62919ms","remote":"127.0.0.1:59644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:09:42.21142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:41.69113Z","time spent":"520.026806ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}