{"level":"warn","ts":"2026-04-22T13:10:34.609603Z","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-22T13:10:34.60975Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-22T13:10:34.609874Z","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-22T13:10:34.61002Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-22T13:10:34.610096Z","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-22T13:10:34.611421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-22T13:10:34.611495Z","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.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-22T13:10:34.616159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.442678ms"} {"level":"info","ts":"2026-04-22T13:10:34.635374Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-22T13:10:34.635452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T13:10:34.63547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-22T13:10:34.63548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T13:10:34.635487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-22T13:10:34.635524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-22T13:10:34.650405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T13:10:34.651657Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T13:10:34.652945Z","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-22T13:10:34.656317Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T13:10:34.656488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T13:10:34.656546Z","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-22T13:10:34.65669Z","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-22T13:10:34.656703Z","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-22T13:10:34.658811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-22T13:10:34.659023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-22T13:10:34.661565Z","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-22T13:10:34.661986Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T13:10:34.66205Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T13:10:34.661777Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-22T13:10:34.662149Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-22T13:10:35.13634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T13:10:35.136414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T13:10:35.136464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-22T13:10:35.136487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-22T13:10:35.136505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-22T13:10:35.136527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-22T13:10:35.136545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-22T13:10:35.137625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T13:10:35.137792Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:10:35.137844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:10:35.138118Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:35.139155Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:35.139346Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:35.139407Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:35.141965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-22T13:10:35.142131Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T13:10:35.142192Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T13:10:35.145499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-22T13:10:35.415392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.877777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T13:10:35.416793Z","caller":"traceutil/trace.go:171","msg":"trace[366998071] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"172.367573ms","start":"2026-04-22T13:10:35.244409Z","end":"2026-04-22T13:10:35.416776Z","steps":["trace[366998071] 'count revisions from in-memory index tree' (duration: 170.725773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:35.415553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.095342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.303118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.487694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.360871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.402339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.353238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.41584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.22097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.267051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.184493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.163243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.151671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.415916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.967606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.416041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.385257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T13:10:35.416742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.454984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T13:10:35.417482Z","caller":"traceutil/trace.go:171","msg":"trace[30332812] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"173.03149ms","start":"2026-04-22T13:10:35.244441Z","end":"2026-04-22T13:10:35.417473Z","steps":["trace[30332812] 'range keys from in-memory index tree' (duration: 171.133054ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.417501Z","caller":"traceutil/trace.go:171","msg":"trace[1082062229] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"164.227346ms","start":"2026-04-22T13:10:35.253268Z","end":"2026-04-22T13:10:35.417495Z","steps":["trace[1082062229] 'count revisions from in-memory index tree' (duration: 162.435672ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.418662Z","caller":"traceutil/trace.go:171","msg":"trace[253023577] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"128.999101ms","start":"2026-04-22T13:10:35.289649Z","end":"2026-04-22T13:10:35.418648Z","steps":["trace[253023577] 'count revisions from in-memory index tree' (duration: 126.106271ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.418765Z","caller":"traceutil/trace.go:171","msg":"trace[1342309532] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"129.067823ms","start":"2026-04-22T13:10:35.289685Z","end":"2026-04-22T13:10:35.418753Z","steps":["trace[1342309532] 'range keys from in-memory index tree' (duration: 126.10907ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.418807Z","caller":"traceutil/trace.go:171","msg":"trace[1820654725] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"165.426565ms","start":"2026-04-22T13:10:35.253373Z","end":"2026-04-22T13:10:35.4188Z","steps":["trace[1820654725] 'range keys from in-memory index tree' (duration: 162.235878ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.418864Z","caller":"traceutil/trace.go:171","msg":"trace[261552106] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"183.619137ms","start":"2026-04-22T13:10:35.235231Z","end":"2026-04-22T13:10:35.41885Z","steps":["trace[261552106] 'range keys from in-memory index tree' (duration: 181.381702ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.418891Z","caller":"traceutil/trace.go:171","msg":"trace[1512208718] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"155.512685ms","start":"2026-04-22T13:10:35.263372Z","end":"2026-04-22T13:10:35.418885Z","steps":["trace[1512208718] 'range keys from in-memory index tree' (duration: 152.251036ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419041Z","caller":"traceutil/trace.go:171","msg":"trace[200747460] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"155.568806ms","start":"2026-04-22T13:10:35.26344Z","end":"2026-04-22T13:10:35.419009Z","steps":["trace[200747460] 'count revisions from in-memory index tree' (duration: 152.239046ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419111Z","caller":"traceutil/trace.go:171","msg":"trace[1546226587] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"110.327978ms","start":"2026-04-22T13:10:35.308726Z","end":"2026-04-22T13:10:35.419054Z","steps":["trace[1546226587] 'count revisions from in-memory index tree' (duration: 107.10755ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419138Z","caller":"traceutil/trace.go:171","msg":"trace[1696592293] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"140.52394ms","start":"2026-04-22T13:10:35.278595Z","end":"2026-04-22T13:10:35.419119Z","steps":["trace[1696592293] 'range keys from in-memory index tree' (duration: 137.138318ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419155Z","caller":"traceutil/trace.go:171","msg":"trace[202491444] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"140.600372ms","start":"2026-04-22T13:10:35.27855Z","end":"2026-04-22T13:10:35.41915Z","steps":["trace[202491444] 'count revisions from in-memory index tree' (duration: 137.145688ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419324Z","caller":"traceutil/trace.go:171","msg":"trace[2059703022] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"192.674897ms","start":"2026-04-22T13:10:35.226633Z","end":"2026-04-22T13:10:35.419308Z","steps":["trace[2059703022] 'count revisions from in-memory index tree' (duration: 189.326505ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419363Z","caller":"traceutil/trace.go:171","msg":"trace[1980396605] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"110.373248ms","start":"2026-04-22T13:10:35.308945Z","end":"2026-04-22T13:10:35.419318Z","steps":["trace[1980396605] 'range keys from in-memory index tree' (duration: 106.891155ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:35.419622Z","caller":"traceutil/trace.go:171","msg":"trace[128611695] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"182.029299ms","start":"2026-04-22T13:10:35.235413Z","end":"2026-04-22T13:10:35.417443Z","steps":["trace[128611695] 'count revisions from in-memory index tree' (duration: 180.00478ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:40.503833Z","caller":"traceutil/trace.go:171","msg":"trace[1075098544] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"120.345302ms","start":"2026-04-22T13:10:40.383428Z","end":"2026-04-22T13:10:40.503773Z","steps":["trace[1075098544] 'process raft request' (duration: 120.162637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.096984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.498875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:10:47.097204Z","caller":"traceutil/trace.go:171","msg":"trace[322735892] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:344; }","duration":"1.238921803s","start":"2026-04-22T13:10:45.858271Z","end":"2026-04-22T13:10:47.097193Z","steps":["trace[322735892] 'read index received' (duration: 345.845524ms)","trace[322735892] 'applied index is now lower than readState.Index' (duration: 893.075739ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:47.09725Z","caller":"traceutil/trace.go:171","msg":"trace[1531535817] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.277102619s","start":"2026-04-22T13:10:45.820141Z","end":"2026-04-22T13:10:47.097244Z","steps":["trace[1531535817] 'process raft request' (duration: 1.276917085s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.097284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.820123Z","time spent":"1.277140401s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:10:47.097364Z","caller":"traceutil/trace.go:171","msg":"trace[479422489] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"1.278247888s","start":"2026-04-22T13:10:45.819096Z","end":"2026-04-22T13:10:47.097344Z","steps":["trace[479422489] 'process raft request' (duration: 385.027876ms)","trace[479422489] 'compare' (duration: 892.299359ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:47.098262Z","caller":"traceutil/trace.go:171","msg":"trace[1372764273] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"1.273786499s","start":"2026-04-22T13:10:45.824458Z","end":"2026-04-22T13:10:47.098244Z","steps":["trace[1372764273] 'process raft request' (duration: 1.272646542s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.098321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.824443Z","time spent":"1.27384251s","remote":"127.0.0.1:49370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:10:47.098475Z","caller":"traceutil/trace.go:171","msg":"trace[1702759708] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"1.270560231s","start":"2026-04-22T13:10:45.827907Z","end":"2026-04-22T13:10:47.098467Z","steps":["trace[1702759708] 'process raft request' (duration: 1.269220609s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.098506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.827889Z","time spent":"1.270598913s","remote":"127.0.0.1:49386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:10:47.098667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240406329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-22T13:10:47.098691Z","caller":"traceutil/trace.go:171","msg":"trace[1713960143] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:338; }","duration":"1.2404591s","start":"2026-04-22T13:10:45.85822Z","end":"2026-04-22T13:10:47.098679Z","steps":["trace[1713960143] 'agreement among raft nodes before linearized reading' (duration: 1.240358408s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.098707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.858203Z","time spent":"1.240500541s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-22T13:10:47.099806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.819086Z","time spent":"1.278327809s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:10:47.104723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.951133ms","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-22T13:10:47.104777Z","caller":"traceutil/trace.go:171","msg":"trace[1597818220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:339; }","duration":"490.041305ms","start":"2026-04-22T13:10:46.614723Z","end":"2026-04-22T13:10:47.104765Z","steps":["trace[1597818220] 'agreement among raft nodes before linearized reading' (duration: 489.939762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.104806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:46.614707Z","time spent":"490.092796ms","remote":"127.0.0.1:49372","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-22T13:10:47.104997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.806756ms","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-04-22T13:10:47.10498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.550237ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:10:47.105188Z","caller":"traceutil/trace.go:171","msg":"trace[1926398681] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:339; }","duration":"153.754912ms","start":"2026-04-22T13:10:46.951416Z","end":"2026-04-22T13:10:47.105171Z","steps":["trace[1926398681] 'agreement among raft nodes before linearized reading' (duration: 153.520987ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:47.105024Z","caller":"traceutil/trace.go:171","msg":"trace[1180248196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"404.870038ms","start":"2026-04-22T13:10:46.700147Z","end":"2026-04-22T13:10:47.105017Z","steps":["trace[1180248196] 'agreement among raft nodes before linearized reading' (duration: 404.827107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:47.105464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:46.700126Z","time spent":"405.306828ms","remote":"127.0.0.1:49372","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-22T13:10:47.35111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.866132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:10:47.351502Z","caller":"traceutil/trace.go:171","msg":"trace[1778209746] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"200.462075ms","start":"2026-04-22T13:10:47.151018Z","end":"2026-04-22T13:10:47.35148Z","steps":["trace[1778209746] 'process raft request' (duration: 200.418964ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:47.351616Z","caller":"traceutil/trace.go:171","msg":"trace[382636419] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"208.539192ms","start":"2026-04-22T13:10:47.143051Z","end":"2026-04-22T13:10:47.351591Z","steps":["trace[382636419] 'process raft request' (duration: 208.278655ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:47.351646Z","caller":"traceutil/trace.go:171","msg":"trace[399594077] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"208.137212ms","start":"2026-04-22T13:10:47.143472Z","end":"2026-04-22T13:10:47.351609Z","steps":["trace[399594077] 'process raft request' (duration: 207.914957ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:47.351797Z","caller":"traceutil/trace.go:171","msg":"trace[1100340339] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"209.878454ms","start":"2026-04-22T13:10:47.141901Z","end":"2026-04-22T13:10:47.351779Z","steps":["trace[1100340339] 'process raft request' (duration: 209.328501ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:47.351791Z","caller":"traceutil/trace.go:171","msg":"trace[1630243831] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"210.330975ms","start":"2026-04-22T13:10:47.141427Z","end":"2026-04-22T13:10:47.351757Z","steps":["trace[1630243831] 'process raft request' (duration: 72.739475ms)","trace[1630243831] 'compare' (duration: 136.618397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:48.182231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.323554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:10:48.182358Z","caller":"traceutil/trace.go:171","msg":"trace[304295787] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"818.560099ms","start":"2026-04-22T13:10:47.363775Z","end":"2026-04-22T13:10:48.182335Z","steps":["trace[304295787] 'process raft request' (duration: 578.01821ms)","trace[304295787] 'compare' (duration: 240.14984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:48.182451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:47.363759Z","time spent":"818.639022ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:10:48.182695Z","caller":"traceutil/trace.go:171","msg":"trace[1894070452] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:357; }","duration":"452.428372ms","start":"2026-04-22T13:10:47.730249Z","end":"2026-04-22T13:10:48.182677Z","steps":["trace[1894070452] 'read index received' (duration: 211.627627ms)","trace[1894070452] 'applied index is now lower than readState.Index' (duration: 240.799905ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:48.182702Z","caller":"traceutil/trace.go:171","msg":"trace[621979250] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"818.705783ms","start":"2026-04-22T13:10:47.363985Z","end":"2026-04-22T13:10:48.182691Z","steps":["trace[621979250] 'process raft request' (duration: 818.637731ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:48.182734Z","caller":"traceutil/trace.go:171","msg":"trace[1945546982] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"818.800925ms","start":"2026-04-22T13:10:47.363924Z","end":"2026-04-22T13:10:48.182725Z","steps":["trace[1945546982] 'process raft request' (duration: 818.655432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:48.182761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:47.363972Z","time spent":"818.766515ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:10:48.182786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:47.363903Z","time spent":"818.853816ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:10:48.182844Z","caller":"traceutil/trace.go:171","msg":"trace[823670561] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"816.328395ms","start":"2026-04-22T13:10:47.366511Z","end":"2026-04-22T13:10:48.182839Z","steps":["trace[823670561] 'process raft request' (duration: 816.134311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:48.182874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:47.366502Z","time spent":"816.354676ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:10:48.182951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.704188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-22T13:10:48.182998Z","caller":"traceutil/trace.go:171","msg":"trace[910647589] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:351; }","duration":"452.79118ms","start":"2026-04-22T13:10:47.730191Z","end":"2026-04-22T13:10:48.182982Z","steps":["trace[910647589] 'agreement among raft nodes before linearized reading' (duration: 452.646607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:48.183045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:47.730171Z","time spent":"452.863663ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-22T13:10:48.65661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.341286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:10:48.656858Z","caller":"traceutil/trace.go:171","msg":"trace[736685380] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:361; }","duration":"371.670302ms","start":"2026-04-22T13:10:48.285169Z","end":"2026-04-22T13:10:48.65684Z","steps":["trace[736685380] 'read index received' (duration: 51.05434ms)","trace[736685380] 'applied index is now lower than readState.Index' (duration: 320.615082ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:48.656871Z","caller":"traceutil/trace.go:171","msg":"trace[1771139335] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"569.142775ms","start":"2026-04-22T13:10:48.087678Z","end":"2026-04-22T13:10:48.656821Z","steps":["trace[1771139335] 'process raft request' (duration: 248.521873ms)","trace[1771139335] 'compare' (duration: 320.207953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:48.656953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.775224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:10:48.656985Z","caller":"traceutil/trace.go:171","msg":"trace[618795165] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:354; }","duration":"371.846136ms","start":"2026-04-22T13:10:48.285131Z","end":"2026-04-22T13:10:48.656977Z","steps":["trace[618795165] 'agreement among raft nodes before linearized reading' (duration: 371.781605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:48.657019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:48.285115Z","time spent":"371.896197ms","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-04-22T13:10:48.657066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:48.087656Z","time spent":"569.34989ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:10:48.657095Z","caller":"traceutil/trace.go:171","msg":"trace[1674115672] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"468.712498ms","start":"2026-04-22T13:10:48.188369Z","end":"2026-04-22T13:10:48.657081Z","steps":["trace[1674115672] 'process raft request' (duration: 468.364489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:48.657162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:48.188349Z","time spent":"468.773169ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:10:48.657189Z","caller":"traceutil/trace.go:171","msg":"trace[206628909] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"467.808734ms","start":"2026-04-22T13:10:48.189355Z","end":"2026-04-22T13:10:48.657163Z","steps":["trace[206628909] 'process raft request' (duration: 467.447036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:48.657313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:48.189333Z","time spent":"467.911577ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:21.402815Z","caller":"traceutil/trace.go:171","msg":"trace[702451331] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"143.978023ms","start":"2026-04-22T13:11:21.258818Z","end":"2026-04-22T13:11:21.402796Z","steps":["trace[702451331] 'process raft request' (duration: 143.687675ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614059Z","caller":"traceutil/trace.go:171","msg":"trace[1992621131] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"274.133784ms","start":"2026-04-22T13:11:21.339892Z","end":"2026-04-22T13:11:21.614026Z","steps":["trace[1992621131] 'process raft request' (duration: 273.920519ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614131Z","caller":"traceutil/trace.go:171","msg":"trace[873382911] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"267.112019ms","start":"2026-04-22T13:11:21.346961Z","end":"2026-04-22T13:11:21.614073Z","steps":["trace[873382911] 'process raft request' (duration: 266.994896ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614147Z","caller":"traceutil/trace.go:171","msg":"trace[1551889715] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"263.972436ms","start":"2026-04-22T13:11:21.350143Z","end":"2026-04-22T13:11:21.614115Z","steps":["trace[1551889715] 'process raft request' (duration: 263.859833ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614233Z","caller":"traceutil/trace.go:171","msg":"trace[1031400167] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"258.674539ms","start":"2026-04-22T13:11:21.355522Z","end":"2026-04-22T13:11:21.614197Z","steps":["trace[1031400167] 'process raft request' (duration: 258.519214ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.61436Z","caller":"traceutil/trace.go:171","msg":"trace[78678573] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"246.789437ms","start":"2026-04-22T13:11:21.367559Z","end":"2026-04-22T13:11:21.614348Z","steps":["trace[78678573] 'process raft request' (duration: 246.669104ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614358Z","caller":"traceutil/trace.go:171","msg":"trace[266161793] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"246.852508ms","start":"2026-04-22T13:11:21.367457Z","end":"2026-04-22T13:11:21.614309Z","steps":["trace[266161793] 'process raft request' (duration: 246.630282ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614411Z","caller":"traceutil/trace.go:171","msg":"trace[917197508] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"237.213606ms","start":"2026-04-22T13:11:21.377187Z","end":"2026-04-22T13:11:21.6144Z","steps":["trace[917197508] 'process raft request' (duration: 237.086663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:21.614547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.019323ms","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-22T13:11:21.614594Z","caller":"traceutil/trace.go:171","msg":"trace[1804228573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"289.170138ms","start":"2026-04-22T13:11:21.325413Z","end":"2026-04-22T13:11:21.614584Z","steps":["trace[1804228573] 'agreement among raft nodes before linearized reading' (duration: 289.007063ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614597Z","caller":"traceutil/trace.go:171","msg":"trace[765306364] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"214.993375ms","start":"2026-04-22T13:11:21.39958Z","end":"2026-04-22T13:11:21.614574Z","steps":["trace[765306364] 'process raft request' (duration: 214.739118ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.614385Z","caller":"traceutil/trace.go:171","msg":"trace[1331636497] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:531; }","duration":"211.366829ms","start":"2026-04-22T13:11:21.402997Z","end":"2026-04-22T13:11:21.614364Z","steps":["trace[1331636497] 'read index received' (duration: 210.913437ms)","trace[1331636497] 'applied index is now lower than readState.Index' (duration: 452.522µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:21.624927Z","caller":"traceutil/trace.go:171","msg":"trace[825075165] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"216.340329ms","start":"2026-04-22T13:11:21.40851Z","end":"2026-04-22T13:11:21.62485Z","steps":["trace[825075165] 'process raft request' (duration: 216.130144ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.625082Z","caller":"traceutil/trace.go:171","msg":"trace[2017600645] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"215.906437ms","start":"2026-04-22T13:11:21.409157Z","end":"2026-04-22T13:11:21.625064Z","steps":["trace[2017600645] 'process raft request' (duration: 215.608349ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.628393Z","caller":"traceutil/trace.go:171","msg":"trace[1412144046] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"217.962011ms","start":"2026-04-22T13:11:21.410341Z","end":"2026-04-22T13:11:21.628303Z","steps":["trace[1412144046] 'process raft request' (duration: 214.485749ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.628563Z","caller":"traceutil/trace.go:171","msg":"trace[1023822484] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"213.37615ms","start":"2026-04-22T13:11:21.415174Z","end":"2026-04-22T13:11:21.62855Z","steps":["trace[1023822484] 'process raft request' (duration: 209.91842ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.628624Z","caller":"traceutil/trace.go:171","msg":"trace[1385114050] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"217.457668ms","start":"2026-04-22T13:11:21.411092Z","end":"2026-04-22T13:11:21.62855Z","steps":["trace[1385114050] 'process raft request' (duration: 213.823453ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.628806Z","caller":"traceutil/trace.go:171","msg":"trace[1678358933] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"216.911193ms","start":"2026-04-22T13:11:21.411865Z","end":"2026-04-22T13:11:21.628777Z","steps":["trace[1678358933] 'process raft request' (duration: 213.097023ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.628899Z","caller":"traceutil/trace.go:171","msg":"trace[1529386582] transaction","detail":"{read_only:false; number_of_response:1; response_revision:526; }","duration":"216.702168ms","start":"2026-04-22T13:11:21.412177Z","end":"2026-04-22T13:11:21.628879Z","steps":["trace[1529386582] 'process raft request' (duration: 212.869107ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:21.628909Z","caller":"traceutil/trace.go:171","msg":"trace[1906132321] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"130.140781ms","start":"2026-04-22T13:11:21.498743Z","end":"2026-04-22T13:11:21.628884Z","steps":["trace[1906132321] 'process raft request' (duration: 126.521237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:21.629547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.198461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:21.629688Z","caller":"traceutil/trace.go:171","msg":"trace[745457633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"146.343714ms","start":"2026-04-22T13:11:21.483276Z","end":"2026-04-22T13:11:21.62962Z","steps":["trace[745457633] 'agreement among raft nodes before linearized reading' (duration: 145.627175ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:28.311764Z","caller":"traceutil/trace.go:171","msg":"trace[1318497404] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"303.906172ms","start":"2026-04-22T13:11:28.007831Z","end":"2026-04-22T13:11:28.311737Z","steps":["trace[1318497404] 'process raft request' (duration: 303.264376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:28.311926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:28.007803Z","time spent":"304.044228ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:28.949489Z","caller":"traceutil/trace.go:171","msg":"trace[632071291] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"661.036781ms","start":"2026-04-22T13:11:28.288387Z","end":"2026-04-22T13:11:28.949423Z","steps":["trace[632071291] 'read index received' (duration: 22.479479ms)","trace[632071291] 'applied index is now lower than readState.Index' (duration: 638.555922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:28.949669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.267028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:11:28.94965Z","caller":"traceutil/trace.go:171","msg":"trace[1423819322] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"919.026062ms","start":"2026-04-22T13:11:28.030576Z","end":"2026-04-22T13:11:28.949602Z","steps":["trace[1423819322] 'process raft request' (duration: 918.626302ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:28.949707Z","caller":"traceutil/trace.go:171","msg":"trace[454351205] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:621; }","duration":"661.35954ms","start":"2026-04-22T13:11:28.288335Z","end":"2026-04-22T13:11:28.949695Z","steps":["trace[454351205] 'agreement among raft nodes before linearized reading' (duration: 661.269907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:28.94987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:28.030563Z","time spent":"919.208526ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:28.949918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:28.288315Z","time spent":"661.591346ms","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-04-22T13:11:29.450079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741597981904947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:11:30.168547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.280871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:11:30.168713Z","caller":"traceutil/trace.go:171","msg":"trace[1473911359] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"1.219129645s","start":"2026-04-22T13:11:28.949567Z","end":"2026-04-22T13:11:30.168697Z","steps":["trace[1473911359] 'read index received' (duration: 829.625479ms)","trace[1473911359] 'applied index is now lower than readState.Index' (duration: 389.503116ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:30.168801Z","caller":"traceutil/trace.go:171","msg":"trace[849928948] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.796757322s","start":"2026-04-22T13:11:28.372033Z","end":"2026-04-22T13:11:30.168791Z","steps":["trace[849928948] 'process raft request' (duration: 1.407169003s)","trace[849928948] 'compare' (duration: 389.147267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:30.168847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:28.372015Z","time spent":"1.796809193s","remote":"127.0.0.1:49362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:11:30.168966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:28.951399Z","time spent":"1.217560944s","remote":"127.0.0.1:49162","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:30.169053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.928283ms","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-04-22T13:11:30.16904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686677857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:11:30.169078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.843195ms","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-22T13:11:30.169085Z","caller":"traceutil/trace.go:171","msg":"trace[336172778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"507.003395ms","start":"2026-04-22T13:11:29.662072Z","end":"2026-04-22T13:11:30.169076Z","steps":["trace[336172778] 'agreement among raft nodes before linearized reading' (duration: 506.884032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:30.169115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:29.662052Z","time spent":"507.057166ms","remote":"127.0.0.1:49372","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":"info","ts":"2026-04-22T13:11:30.169115Z","caller":"traceutil/trace.go:171","msg":"trace[1160316569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"726.883386ms","start":"2026-04-22T13:11:29.442219Z","end":"2026-04-22T13:11:30.169103Z","steps":["trace[1160316569] 'agreement among raft nodes before linearized reading' (duration: 726.741923ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:30.169118Z","caller":"traceutil/trace.go:171","msg":"trace[1169994196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.686766509s","start":"2026-04-22T13:11:28.482331Z","end":"2026-04-22T13:11:30.169098Z","steps":["trace[1169994196] 'agreement among raft nodes before linearized reading' (duration: 1.686600785s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:30.169148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:29.442125Z","time spent":"727.017469ms","remote":"127.0.0.1:49372","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-22T13:11:30.169124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.610245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T13:11:30.169152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:28.482282Z","time spent":"1.686863721s","remote":"127.0.0.1:49108","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-22T13:11:30.1692Z","caller":"traceutil/trace.go:171","msg":"trace[1785230456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"793.705828ms","start":"2026-04-22T13:11:29.37547Z","end":"2026-04-22T13:11:30.169176Z","steps":["trace[1785230456] 'agreement among raft nodes before linearized reading' (duration: 793.487612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:30.169124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.183786ms","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-22T13:11:30.169245Z","caller":"traceutil/trace.go:171","msg":"trace[347216035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"500.358511ms","start":"2026-04-22T13:11:29.668879Z","end":"2026-04-22T13:11:30.169238Z","steps":["trace[347216035] 'agreement among raft nodes before linearized reading' (duration: 500.096433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:30.169248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:29.375456Z","time spent":"793.781871ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:11:30.169264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:29.668854Z","time spent":"500.404392ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T13:11:30.310639Z","caller":"traceutil/trace.go:171","msg":"trace[371406441] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"137.690479ms","start":"2026-04-22T13:11:30.17291Z","end":"2026-04-22T13:11:30.310601Z","steps":["trace[371406441] 'process raft request' (duration: 111.458041ms)","trace[371406441] 'compare' (duration: 25.859918ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:30.311163Z","caller":"traceutil/trace.go:171","msg":"trace[332544780] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"137.921694ms","start":"2026-04-22T13:11:30.173206Z","end":"2026-04-22T13:11:30.311127Z","steps":["trace[332544780] 'process raft request' (duration: 137.337669ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:30.311714Z","caller":"traceutil/trace.go:171","msg":"trace[287997925] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"138.1698ms","start":"2026-04-22T13:11:30.173523Z","end":"2026-04-22T13:11:30.311693Z","steps":["trace[287997925] 'process raft request' (duration: 137.233325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:30.312008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.29397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-22T13:11:30.312421Z","caller":"traceutil/trace.go:171","msg":"trace[1276047392] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"138.53309ms","start":"2026-04-22T13:11:30.17387Z","end":"2026-04-22T13:11:30.312403Z","steps":["trace[1276047392] 'process raft request' (duration: 137.76669ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:30.312069Z","caller":"traceutil/trace.go:171","msg":"trace[108784196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:627; }","duration":"139.396663ms","start":"2026-04-22T13:11:30.17266Z","end":"2026-04-22T13:11:30.312056Z","steps":["trace[108784196] 'agreement among raft nodes before linearized reading' (duration: 139.270219ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:33.433597Z","caller":"traceutil/trace.go:171","msg":"trace[1849969487] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"104.642743ms","start":"2026-04-22T13:11:33.328933Z","end":"2026-04-22T13:11:33.433576Z","steps":["trace[1849969487] 'read index received' (duration: 103.872872ms)","trace[1849969487] 'applied index is now lower than readState.Index' (duration: 768.801µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:33.433733Z","caller":"traceutil/trace.go:171","msg":"trace[227110090] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"305.905725ms","start":"2026-04-22T13:11:33.127742Z","end":"2026-04-22T13:11:33.433648Z","steps":["trace[227110090] 'process raft request' (duration: 305.078704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:33.433858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.90873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:33.43392Z","caller":"traceutil/trace.go:171","msg":"trace[1735088463] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:673; }","duration":"105.020212ms","start":"2026-04-22T13:11:33.328883Z","end":"2026-04-22T13:11:33.433903Z","steps":["trace[1735088463] 'agreement among raft nodes before linearized reading' (duration: 104.780056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:33.433982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:33.12772Z","time spent":"306.102861ms","remote":"127.0.0.1:49186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:36.587732Z","caller":"traceutil/trace.go:171","msg":"trace[1918097399] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"104.827597ms","start":"2026-04-22T13:11:36.482881Z","end":"2026-04-22T13:11:36.587708Z","steps":["trace[1918097399] 'read index received' (duration: 21.917675ms)","trace[1918097399] 'applied index is now lower than readState.Index' (duration: 82.908742ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:36.587827Z","caller":"traceutil/trace.go:171","msg":"trace[1094550992] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"117.083078ms","start":"2026-04-22T13:11:36.470715Z","end":"2026-04-22T13:11:36.587798Z","steps":["trace[1094550992] 'process raft request' (duration: 34.089094ms)","trace[1094550992] 'compare' (duration: 82.782789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:36.587919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.012052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:36.588016Z","caller":"traceutil/trace.go:171","msg":"trace[538439164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"105.151856ms","start":"2026-04-22T13:11:36.482848Z","end":"2026-04-22T13:11:36.588Z","steps":["trace[538439164] 'agreement among raft nodes before linearized reading' (duration: 104.981271ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:36.641342Z","caller":"traceutil/trace.go:171","msg":"trace[1525303899] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"140.911123ms","start":"2026-04-22T13:11:36.500403Z","end":"2026-04-22T13:11:36.641314Z","steps":["trace[1525303899] 'process raft request' (duration: 140.639275ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:36.651408Z","caller":"traceutil/trace.go:171","msg":"trace[1347488804] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"146.652633ms","start":"2026-04-22T13:11:36.504726Z","end":"2026-04-22T13:11:36.651379Z","steps":["trace[1347488804] 'process raft request' (duration: 146.386116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:36.651894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.014725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-22T13:11:36.652284Z","caller":"traceutil/trace.go:171","msg":"trace[762212966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"125.351164ms","start":"2026-04-22T13:11:36.526814Z","end":"2026-04-22T13:11:36.652165Z","steps":["trace[762212966] 'agreement among raft nodes before linearized reading' (duration: 124.889151ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:46.835773Z","caller":"traceutil/trace.go:171","msg":"trace[339579757] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"301.91344ms","start":"2026-04-22T13:11:46.533818Z","end":"2026-04-22T13:11:46.835732Z","steps":["trace[339579757] 'process raft request' (duration: 274.644627ms)","trace[339579757] 'compare' (duration: 27.014477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:46.835953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:46.533802Z","time spent":"302.071446ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:46.836303Z","caller":"traceutil/trace.go:171","msg":"trace[2082185133] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"301.514461ms","start":"2026-04-22T13:11:46.534759Z","end":"2026-04-22T13:11:46.836273Z","steps":["trace[2082185133] 'process raft request' (duration: 301.433139ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:46.836363Z","caller":"traceutil/trace.go:171","msg":"trace[1552572029] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"301.651174ms","start":"2026-04-22T13:11:46.534695Z","end":"2026-04-22T13:11:46.836346Z","steps":["trace[1552572029] 'process raft request' (duration: 300.908684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:46.836443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:46.534671Z","time spent":"301.733286ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:46.836474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:46.534742Z","time spent":"301.643564ms","remote":"127.0.0.1:49372","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-22T13:11:47.186912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.357561ms","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-22T13:11:47.18701Z","caller":"traceutil/trace.go:171","msg":"trace[1570660053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"184.508765ms","start":"2026-04-22T13:11:47.002479Z","end":"2026-04-22T13:11:47.186988Z","steps":["trace[1570660053] 'range keys from in-memory index tree' (duration: 184.145285ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:47.210689Z","caller":"traceutil/trace.go:171","msg":"trace[192389543] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"130.724165ms","start":"2026-04-22T13:11:47.079941Z","end":"2026-04-22T13:11:47.210665Z","steps":["trace[192389543] 'process raft request' (duration: 130.582322ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:22.239227Z","caller":"traceutil/trace.go:171","msg":"trace[1299060692] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"140.477672ms","start":"2026-04-22T13:12:22.098732Z","end":"2026-04-22T13:12:22.23921Z","steps":["trace[1299060692] 'read index received' (duration: 140.4068ms)","trace[1299060692] 'applied index is now lower than readState.Index' (duration: 70.232µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:22.239281Z","caller":"traceutil/trace.go:171","msg":"trace[1456505911] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"200.159105ms","start":"2026-04-22T13:12:22.039073Z","end":"2026-04-22T13:12:22.239233Z","steps":["trace[1456505911] 'process raft request' (duration: 200.015412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:22.239334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.585644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:22.239354Z","caller":"traceutil/trace.go:171","msg":"trace[1836373289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"140.647316ms","start":"2026-04-22T13:12:22.0987Z","end":"2026-04-22T13:12:22.239348Z","steps":["trace[1836373289] 'agreement among raft nodes before linearized reading' (duration: 140.598704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:47.391216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.036912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:12:47.391398Z","caller":"traceutil/trace.go:171","msg":"trace[1450438211] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"273.565185ms","start":"2026-04-22T13:12:47.117811Z","end":"2026-04-22T13:12:47.391376Z","steps":["trace[1450438211] 'read index received' (duration: 75.200095ms)","trace[1450438211] 'applied index is now lower than readState.Index' (duration: 198.36301ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:47.391467Z","caller":"traceutil/trace.go:171","msg":"trace[13528969] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"277.33698ms","start":"2026-04-22T13:12:47.11407Z","end":"2026-04-22T13:12:47.391407Z","steps":["trace[13528969] 'process raft request' (duration: 79.001791ms)","trace[13528969] 'compare' (duration: 197.874657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:47.391659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.839962ms","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-04-22T13:12:47.391772Z","caller":"traceutil/trace.go:171","msg":"trace[2033844647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"273.996786ms","start":"2026-04-22T13:12:47.117757Z","end":"2026-04-22T13:12:47.391753Z","steps":["trace[2033844647] 'agreement among raft nodes before linearized reading' (duration: 273.76837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:47.39189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.687148ms","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-04-22T13:12:47.391962Z","caller":"traceutil/trace.go:171","msg":"trace[1282691522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"271.801611ms","start":"2026-04-22T13:12:47.120142Z","end":"2026-04-22T13:12:47.391944Z","steps":["trace[1282691522] 'agreement among raft nodes before linearized reading' (duration: 271.528124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:47.676778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.859251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:12:47.677172Z","caller":"traceutil/trace.go:171","msg":"trace[242038785] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"281.663258ms","start":"2026-04-22T13:12:47.39544Z","end":"2026-04-22T13:12:47.677104Z","steps":["trace[242038785] 'process raft request' (duration: 137.382177ms)","trace[242038785] 'compare' (duration: 143.707076ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:47.677429Z","caller":"traceutil/trace.go:171","msg":"trace[1407212136] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1136; }","duration":"195.545909ms","start":"2026-04-22T13:12:47.481866Z","end":"2026-04-22T13:12:47.677412Z","steps":["trace[1407212136] 'read index received' (duration: 50.906789ms)","trace[1407212136] 'applied index is now lower than readState.Index' (duration: 144.63669ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:47.677604Z","caller":"traceutil/trace.go:171","msg":"trace[998605331] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"282.11128ms","start":"2026-04-22T13:12:47.395464Z","end":"2026-04-22T13:12:47.677575Z","steps":["trace[998605331] 'process raft request' (duration: 281.423433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:47.677749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.866457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:12:47.677793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.871625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:47.677819Z","caller":"traceutil/trace.go:171","msg":"trace[309796498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"196.000001ms","start":"2026-04-22T13:12:47.481797Z","end":"2026-04-22T13:12:47.677797Z","steps":["trace[309796498] 'agreement among raft nodes before linearized reading' (duration: 195.893459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:47.677857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.010004ms","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-22T13:12:47.677863Z","caller":"traceutil/trace.go:171","msg":"trace[986565087] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1093; }","duration":"144.963188ms","start":"2026-04-22T13:12:47.532873Z","end":"2026-04-22T13:12:47.677837Z","steps":["trace[986565087] 'agreement among raft nodes before linearized reading' (duration: 144.837205ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:47.677932Z","caller":"traceutil/trace.go:171","msg":"trace[560585158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"136.105546ms","start":"2026-04-22T13:12:47.541808Z","end":"2026-04-22T13:12:47.677914Z","steps":["trace[560585158] 'agreement among raft nodes before linearized reading' (duration: 135.929632ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:47.818975Z","caller":"traceutil/trace.go:171","msg":"trace[743924244] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"136.129307ms","start":"2026-04-22T13:12:47.68282Z","end":"2026-04-22T13:12:47.818949Z","steps":["trace[743924244] 'process raft request' (duration: 119.060801ms)","trace[743924244] 'compare' (duration: 16.965304ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:49.5523Z","caller":"traceutil/trace.go:171","msg":"trace[952105756] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"157.236955ms","start":"2026-04-22T13:12:49.395043Z","end":"2026-04-22T13:12:49.55228Z","steps":["trace[952105756] 'read index received' (duration: 157.05489ms)","trace[952105756] 'applied index is now lower than readState.Index' (duration: 181.065µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:49.552436Z","caller":"traceutil/trace.go:171","msg":"trace[1841296603] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"522.959559ms","start":"2026-04-22T13:12:49.029446Z","end":"2026-04-22T13:12:49.552406Z","steps":["trace[1841296603] 'process raft request' (duration: 522.675582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:49.552592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:49.029431Z","time spent":"523.074221ms","remote":"127.0.0.1:49372","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-22T13:12:49.553064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.434199ms","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-04-22T13:12:49.553145Z","caller":"traceutil/trace.go:171","msg":"trace[817647993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"158.111206ms","start":"2026-04-22T13:12:49.394995Z","end":"2026-04-22T13:12:49.553106Z","steps":["trace[817647993] 'agreement among raft nodes before linearized reading' (duration: 157.394318ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:49.915832Z","caller":"traceutil/trace.go:171","msg":"trace[1558871136] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"228.45578ms","start":"2026-04-22T13:12:49.687343Z","end":"2026-04-22T13:12:49.915798Z","steps":["trace[1558871136] 'process raft request' (duration: 228.214955ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:50.563041Z","caller":"traceutil/trace.go:171","msg":"trace[1826736457] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"226.589424ms","start":"2026-04-22T13:12:50.336421Z","end":"2026-04-22T13:12:50.56301Z","steps":["trace[1826736457] 'process raft request' (duration: 226.364568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:51.955237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.18297ms","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-22T13:12:51.955365Z","caller":"traceutil/trace.go:171","msg":"trace[1709445139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"318.354804ms","start":"2026-04-22T13:12:51.63699Z","end":"2026-04-22T13:12:51.955345Z","steps":["trace[1709445139] 'range keys from in-memory index tree' (duration: 317.836022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:51.955372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.234585ms","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-22T13:12:51.955409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:51.63697Z","time spent":"318.428796ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T13:12:51.955459Z","caller":"traceutil/trace.go:171","msg":"trace[24139432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"244.361968ms","start":"2026-04-22T13:12:51.711075Z","end":"2026-04-22T13:12:51.955437Z","steps":["trace[24139432] 'range keys from in-memory index tree' (duration: 243.62084ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:52.22756Z","caller":"traceutil/trace.go:171","msg":"trace[1524278833] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"267.565716ms","start":"2026-04-22T13:12:51.959974Z","end":"2026-04-22T13:12:52.22754Z","steps":["trace[1524278833] 'process raft request' (duration: 267.415052ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:52.227715Z","caller":"traceutil/trace.go:171","msg":"trace[1974880839] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1150; }","duration":"262.398957ms","start":"2026-04-22T13:12:51.965288Z","end":"2026-04-22T13:12:52.227687Z","steps":["trace[1974880839] 'read index received' (duration: 69.32355ms)","trace[1974880839] 'applied index is now lower than readState.Index' (duration: 193.073407ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:52.22775Z","caller":"traceutil/trace.go:171","msg":"trace[1828592786] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"267.007232ms","start":"2026-04-22T13:12:51.960715Z","end":"2026-04-22T13:12:52.227722Z","steps":["trace[1828592786] 'process raft request' (duration: 266.793357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:52.228012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.697765ms","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-22T13:12:52.228064Z","caller":"traceutil/trace.go:171","msg":"trace[1764075640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"262.789698ms","start":"2026-04-22T13:12:51.965262Z","end":"2026-04-22T13:12:52.228052Z","steps":["trace[1764075640] 'agreement among raft nodes before linearized reading' (duration: 262.558761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:02.765966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741597981906252,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:13:03.266816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741597981906252,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:13:03.47147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.38383395s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:13:03.472294Z","caller":"traceutil/trace.go:171","msg":"trace[193929229] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"1.384710052s","start":"2026-04-22T13:13:02.087551Z","end":"2026-04-22T13:13:03.472261Z","steps":["trace[193929229] 'process raft request' (duration: 1.384165148s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:03.472463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.087532Z","time spent":"1.384843354s","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2530,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:13:03.473672Z","caller":"traceutil/trace.go:171","msg":"trace[1067939557] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1237; }","duration":"1.208097564s","start":"2026-04-22T13:13:02.26555Z","end":"2026-04-22T13:13:03.473647Z","steps":["trace[1067939557] 'read index received' (duration: 1.206147285s)","trace[1067939557] 'applied index is now lower than readState.Index' (duration: 1.948729ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:03.473773Z","caller":"traceutil/trace.go:171","msg":"trace[33507546] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"813.629782ms","start":"2026-04-22T13:13:02.660129Z","end":"2026-04-22T13:13:03.473759Z","steps":["trace[33507546] 'process raft request' (duration: 813.400686ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:03.473761Z","caller":"traceutil/trace.go:171","msg":"trace[1187893780] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"860.348117ms","start":"2026-04-22T13:13:02.613383Z","end":"2026-04-22T13:13:03.473732Z","steps":["trace[1187893780] 'process raft request' (duration: 860.000179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:03.473874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.66012Z","time spent":"813.681524ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:13:03.473901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.613374Z","time spent":"860.448669ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:03.474164Z","caller":"traceutil/trace.go:171","msg":"trace[836168746] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"513.313457ms","start":"2026-04-22T13:13:02.96084Z","end":"2026-04-22T13:13:03.474154Z","steps":["trace[836168746] 'process raft request' (duration: 512.760703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:03.474267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.960817Z","time spent":"513.40876ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:03.474394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208767371s","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-04-22T13:13:03.474452Z","caller":"traceutil/trace.go:171","msg":"trace[1210846032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.208846213s","start":"2026-04-22T13:13:02.26559Z","end":"2026-04-22T13:13:03.474436Z","steps":["trace[1210846032] 'agreement among raft nodes before linearized reading' (duration: 1.208668789s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:03.474449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.143472ms","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-04-22T13:13:03.474488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.265585Z","time spent":"1.208896444s","remote":"127.0.0.1:49372","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-04-22T13:13:03.474488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.189443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:03.474505Z","caller":"traceutil/trace.go:171","msg":"trace[1245701888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"862.236984ms","start":"2026-04-22T13:13:02.612253Z","end":"2026-04-22T13:13:03.47449Z","steps":["trace[1245701888] 'agreement among raft nodes before linearized reading' (duration: 862.038429ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:03.474524Z","caller":"traceutil/trace.go:171","msg":"trace[1612827912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"991.252794ms","start":"2026-04-22T13:13:02.48326Z","end":"2026-04-22T13:13:03.474513Z","steps":["trace[1612827912] 'agreement among raft nodes before linearized reading' (duration: 991.195562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:03.474542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.612238Z","time spent":"862.296525ms","remote":"127.0.0.1:49372","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-22T13:13:03.474553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.483237Z","time spent":"991.309676ms","remote":"127.0.0.1:49108","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-22T13:13:03.474673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20912444s","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-04-22T13:13:03.474706Z","caller":"traceutil/trace.go:171","msg":"trace[1918403473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"1.209198932s","start":"2026-04-22T13:13:02.265498Z","end":"2026-04-22T13:13:03.474697Z","steps":["trace[1918403473] 'agreement among raft nodes before linearized reading' (duration: 1.2091185s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:03.474732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:02.265473Z","time spent":"1.209252404s","remote":"127.0.0.1:49372","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-04-22T13:13:08.007773Z","caller":"traceutil/trace.go:171","msg":"trace[1667599233] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"137.640354ms","start":"2026-04-22T13:13:07.870059Z","end":"2026-04-22T13:13:08.007699Z","steps":["trace[1667599233] 'process raft request' (duration: 111.994024ms)","trace[1667599233] 'compare' (duration: 25.436265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:10.005482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741597981906308,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T13:13:10.015225Z","caller":"traceutil/trace.go:171","msg":"trace[478968322] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"509.847412ms","start":"2026-04-22T13:13:09.505354Z","end":"2026-04-22T13:13:10.015202Z","steps":["trace[478968322] 'read index received' (duration: 509.780871ms)","trace[478968322] 'applied index is now lower than readState.Index' (duration: 63.261µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:10.015303Z","caller":"traceutil/trace.go:171","msg":"trace[812707159] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"518.115449ms","start":"2026-04-22T13:13:09.49716Z","end":"2026-04-22T13:13:10.015276Z","steps":["trace[812707159] 'process raft request' (duration: 517.895464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.015439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:09.497144Z","time spent":"518.211742ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:10.015485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.977749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:10.015528Z","caller":"traceutil/trace.go:171","msg":"trace[1373550404] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1210; }","duration":"127.077702ms","start":"2026-04-22T13:13:09.888438Z","end":"2026-04-22T13:13:10.015516Z","steps":["trace[1373550404] 'agreement among raft nodes before linearized reading' (duration: 126.990309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.015517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.281689ms","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-04-22T13:13:10.015538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.09564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:10.015584Z","caller":"traceutil/trace.go:171","msg":"trace[97224149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"507.376062ms","start":"2026-04-22T13:13:09.508193Z","end":"2026-04-22T13:13:10.015569Z","steps":["trace[97224149] 'agreement among raft nodes before linearized reading' (duration: 507.183897ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:10.015612Z","caller":"traceutil/trace.go:171","msg":"trace[605719018] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1210; }","duration":"482.217563ms","start":"2026-04-22T13:13:09.533377Z","end":"2026-04-22T13:13:10.015595Z","steps":["trace[605719018] 'agreement among raft nodes before linearized reading' (duration: 482.09296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.015628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:09.50818Z","time spent":"507.433533ms","remote":"127.0.0.1:49372","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-22T13:13:10.015653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:09.533358Z","time spent":"482.283965ms","remote":"127.0.0.1:44446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:10.015918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.551229ms","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-22T13:13:10.015952Z","caller":"traceutil/trace.go:171","msg":"trace[1182825401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"510.633773ms","start":"2026-04-22T13:13:09.505307Z","end":"2026-04-22T13:13:10.015941Z","steps":["trace[1182825401] 'agreement among raft nodes before linearized reading' (duration: 510.065109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.015981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:09.505287Z","time spent":"510.685273ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T13:13:10.51542Z","caller":"traceutil/trace.go:171","msg":"trace[1563364278] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"496.679613ms","start":"2026-04-22T13:13:10.018707Z","end":"2026-04-22T13:13:10.515386Z","steps":["trace[1563364278] 'process raft request' (duration: 496.452588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.515575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:10.018689Z","time spent":"496.807328ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:10.548111Z","caller":"traceutil/trace.go:171","msg":"trace[105309291] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"529.120923ms","start":"2026-04-22T13:13:10.018969Z","end":"2026-04-22T13:13:10.54809Z","steps":["trace[105309291] 'process raft request' (duration: 528.97836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.548256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:10.018961Z","time spent":"529.214026ms","remote":"127.0.0.1:49372","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-22T13:13:10.548392Z","caller":"traceutil/trace.go:171","msg":"trace[1434339293] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"529.255517ms","start":"2026-04-22T13:13:10.019102Z","end":"2026-04-22T13:13:10.548358Z","steps":["trace[1434339293] 'process raft request' (duration: 528.955509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.548546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:10.019083Z","time spent":"529.37941ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:10.548509Z","caller":"traceutil/trace.go:171","msg":"trace[1245258800] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"528.822016ms","start":"2026-04-22T13:13:10.019643Z","end":"2026-04-22T13:13:10.548465Z","steps":["trace[1245258800] 'process raft request' (duration: 528.615111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.548662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:10.019629Z","time spent":"528.98546ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:23.009373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.196259ms","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-22T13:13:23.009456Z","caller":"traceutil/trace.go:171","msg":"trace[1552529112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"233.331023ms","start":"2026-04-22T13:13:22.776106Z","end":"2026-04-22T13:13:23.009437Z","steps":["trace[1552529112] 'range keys from in-memory index tree' (duration: 232.935582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:26.305578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.067165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:26.306096Z","caller":"traceutil/trace.go:171","msg":"trace[1658842387] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"596.790811ms","start":"2026-04-22T13:13:25.709277Z","end":"2026-04-22T13:13:26.306067Z","steps":["trace[1658842387] 'process raft request' (duration: 361.125562ms)","trace[1658842387] 'compare' (duration: 234.348656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:26.306188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:25.709192Z","time spent":"596.948925ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:26.39472Z","caller":"traceutil/trace.go:171","msg":"trace[566667335] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"331.524782ms","start":"2026-04-22T13:13:26.063116Z","end":"2026-04-22T13:13:26.39464Z","steps":["trace[566667335] 'process raft request' (duration: 331.487021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:26.394884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:26.063093Z","time spent":"331.718667ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:26.394694Z","caller":"traceutil/trace.go:171","msg":"trace[1336655117] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"678.219044ms","start":"2026-04-22T13:13:25.71645Z","end":"2026-04-22T13:13:26.394669Z","steps":["trace[1336655117] 'process raft request' (duration: 678.050719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:26.395064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:25.716435Z","time spent":"678.578882ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:26.556547Z","caller":"traceutil/trace.go:171","msg":"trace[706640781] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"155.279834ms","start":"2026-04-22T13:13:26.401253Z","end":"2026-04-22T13:13:26.556533Z","steps":["trace[706640781] 'process raft request' (duration: 155.009598ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:26.556751Z","caller":"traceutil/trace.go:171","msg":"trace[393332767] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"150.956096ms","start":"2026-04-22T13:13:26.405788Z","end":"2026-04-22T13:13:26.556744Z","steps":["trace[393332767] 'process raft request' (duration: 150.561187ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:30.99445Z","caller":"traceutil/trace.go:171","msg":"trace[1222173155] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"352.116924ms","start":"2026-04-22T13:13:30.642313Z","end":"2026-04-22T13:13:30.99443Z","steps":["trace[1222173155] 'read index received' (duration: 351.974461ms)","trace[1222173155] 'applied index is now lower than readState.Index' (duration: 141.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:30.994562Z","caller":"traceutil/trace.go:171","msg":"trace[415112722] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"359.937119ms","start":"2026-04-22T13:13:30.634614Z","end":"2026-04-22T13:13:30.994551Z","steps":["trace[415112722] 'process raft request' (duration: 359.712814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:30.994655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.634591Z","time spent":"360.00236ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:30.994723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.410621ms","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-22T13:13:30.99475Z","caller":"traceutil/trace.go:171","msg":"trace[1045151851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1318; }","duration":"352.465823ms","start":"2026-04-22T13:13:30.642277Z","end":"2026-04-22T13:13:30.994743Z","steps":["trace[1045151851] 'agreement among raft nodes before linearized reading' (duration: 352.401421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:30.994767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.642262Z","time spent":"352.501124ms","remote":"127.0.0.1:49372","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-22T13:13:30.994661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.959531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T13:13:30.994825Z","caller":"traceutil/trace.go:171","msg":"trace[1120452545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"321.142235ms","start":"2026-04-22T13:13:30.673659Z","end":"2026-04-22T13:13:30.994801Z","steps":["trace[1120452545] 'agreement among raft nodes before linearized reading' (duration: 320.927649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:30.994908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.673639Z","time spent":"321.249208ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:30.994949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.373827ms","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-04-22T13:13:30.994757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.511785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:30.995041Z","caller":"traceutil/trace.go:171","msg":"trace[1726616412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"312.49763ms","start":"2026-04-22T13:13:30.682513Z","end":"2026-04-22T13:13:30.995011Z","steps":["trace[1726616412] 'agreement among raft nodes before linearized reading' (duration: 312.229673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:30.995095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.682505Z","time spent":"312.580282ms","remote":"127.0.0.1:49372","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":"info","ts":"2026-04-22T13:13:30.995034Z","caller":"traceutil/trace.go:171","msg":"trace[1113943453] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1318; }","duration":"235.827853ms","start":"2026-04-22T13:13:30.759191Z","end":"2026-04-22T13:13:30.995019Z","steps":["trace[1113943453] 'agreement among raft nodes before linearized reading' (duration: 235.492634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:31.487337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.960844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:31.487551Z","caller":"traceutil/trace.go:171","msg":"trace[1329423147] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"489.375599ms","start":"2026-04-22T13:13:30.99814Z","end":"2026-04-22T13:13:31.487515Z","steps":["trace[1329423147] 'process raft request' (duration: 109.641296ms)","trace[1329423147] 'compare' (duration: 378.406431ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:31.48763Z","caller":"traceutil/trace.go:171","msg":"trace[1406978514] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"489.045102ms","start":"2026-04-22T13:13:30.998572Z","end":"2026-04-22T13:13:31.487617Z","steps":["trace[1406978514] 'process raft request' (duration: 488.926779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:31.4877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.998553Z","time spent":"489.109904ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:31.487723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.998126Z","time spent":"489.512753ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:31.487614Z","caller":"traceutil/trace.go:171","msg":"trace[703422915] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"489.327679ms","start":"2026-04-22T13:13:30.998261Z","end":"2026-04-22T13:13:31.487589Z","steps":["trace[703422915] 'process raft request' (duration: 489.178184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:31.487835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:30.998231Z","time spent":"489.576565ms","remote":"127.0.0.1:49372","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-22T13:13:31.487553Z","caller":"traceutil/trace.go:171","msg":"trace[1977702186] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1374; }","duration":"439.282695ms","start":"2026-04-22T13:13:31.048254Z","end":"2026-04-22T13:13:31.487536Z","steps":["trace[1977702186] 'read index received' (duration: 59.543091ms)","trace[1977702186] 'applied index is now lower than readState.Index' (duration: 379.738704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:31.488069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.815907ms","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-04-22T13:13:31.488115Z","caller":"traceutil/trace.go:171","msg":"trace[660062271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"439.883739ms","start":"2026-04-22T13:13:31.048219Z","end":"2026-04-22T13:13:31.488103Z","steps":["trace[660062271] 'agreement among raft nodes before linearized reading' (duration: 439.721315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:31.488147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:31.048208Z","time spent":"439.93204ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T13:13:31.50804Z","caller":"traceutil/trace.go:171","msg":"trace[1309206083] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"129.228734ms","start":"2026-04-22T13:13:31.37878Z","end":"2026-04-22T13:13:31.508008Z","steps":["trace[1309206083] 'process raft request' (duration: 128.919326ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:32.754648Z","caller":"traceutil/trace.go:171","msg":"trace[1431574871] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"272.450355ms","start":"2026-04-22T13:13:32.482179Z","end":"2026-04-22T13:13:32.75463Z","steps":["trace[1431574871] 'read index received' (duration: 272.298482ms)","trace[1431574871] 'applied index is now lower than readState.Index' (duration: 151.023µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:32.754739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.545737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:32.754766Z","caller":"traceutil/trace.go:171","msg":"trace[1449509808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"272.616698ms","start":"2026-04-22T13:13:32.482141Z","end":"2026-04-22T13:13:32.754758Z","steps":["trace[1449509808] 'agreement among raft nodes before linearized reading' (duration: 272.561487ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:32.754856Z","caller":"traceutil/trace.go:171","msg":"trace[1013511039] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"318.407687ms","start":"2026-04-22T13:13:32.436304Z","end":"2026-04-22T13:13:32.754712Z","steps":["trace[1013511039] 'process raft request' (duration: 318.182202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:32.755056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:32.436282Z","time spent":"318.699975ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:33.335953Z","caller":"traceutil/trace.go:171","msg":"trace[1708945158] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"309.26471ms","start":"2026-04-22T13:13:33.026661Z","end":"2026-04-22T13:13:33.335926Z","steps":["trace[1708945158] 'process raft request' (duration: 309.109707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:33.336104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.026644Z","time spent":"309.383663ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:33.891212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.895953ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T13:13:33.891366Z","caller":"traceutil/trace.go:171","msg":"trace[404809823] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"408.60601ms","start":"2026-04-22T13:13:33.482741Z","end":"2026-04-22T13:13:33.891347Z","steps":["trace[404809823] 'read index received' (duration: 271.518152ms)","trace[404809823] 'applied index is now lower than readState.Index' (duration: 137.086228ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:33.891484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.734623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:33.891518Z","caller":"traceutil/trace.go:171","msg":"trace[1237405202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"408.820665ms","start":"2026-04-22T13:13:33.482689Z","end":"2026-04-22T13:13:33.891509Z","steps":["trace[1237405202] 'agreement among raft nodes before linearized reading' (duration: 408.747693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:33.891557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.482666Z","time spent":"408.884177ms","remote":"127.0.0.1:49108","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-22T13:13:34.151477Z","caller":"traceutil/trace.go:171","msg":"trace[1745945475] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"260.064377ms","start":"2026-04-22T13:13:33.891392Z","end":"2026-04-22T13:13:34.151457Z","steps":["trace[1745945475] 'read index received' (duration: 259.898903ms)","trace[1745945475] 'applied index is now lower than readState.Index' (duration: 164.594µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:34.151578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.798525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:34.151549Z","caller":"traceutil/trace.go:171","msg":"trace[1534569146] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"306.546353ms","start":"2026-04-22T13:13:33.844968Z","end":"2026-04-22T13:13:34.151515Z","steps":["trace[1534569146] 'process raft request' (duration: 306.326917ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.151659Z","caller":"traceutil/trace.go:171","msg":"trace[1474525946] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1327; }","duration":"412.935839ms","start":"2026-04-22T13:13:33.738704Z","end":"2026-04-22T13:13:34.15164Z","steps":["trace[1474525946] 'agreement among raft nodes before linearized reading' (duration: 412.767965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:34.151764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.690394ms","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-04-22T13:13:34.151678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.429051ms","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-04-22T13:13:34.151794Z","caller":"traceutil/trace.go:171","msg":"trace[1340789602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"659.737976ms","start":"2026-04-22T13:13:33.492047Z","end":"2026-04-22T13:13:34.151785Z","steps":["trace[1340789602] 'agreement among raft nodes before linearized reading' (duration: 659.660654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:34.151775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.604577ms","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-04-22T13:13:34.151777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.125315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T13:13:34.151795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.844952Z","time spent":"306.741258ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":886,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:13:34.151802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.030428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-22T13:13:34.151821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.492038Z","time spent":"659.778037ms","remote":"127.0.0.1:49372","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":"info","ts":"2026-04-22T13:13:34.151831Z","caller":"traceutil/trace.go:171","msg":"trace[995677603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"660.71312ms","start":"2026-04-22T13:13:33.491103Z","end":"2026-04-22T13:13:34.151817Z","steps":["trace[995677603] 'agreement among raft nodes before linearized reading' (duration: 660.552616ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.15184Z","caller":"traceutil/trace.go:171","msg":"trace[1421591125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"660.224627ms","start":"2026-04-22T13:13:33.491602Z","end":"2026-04-22T13:13:34.151827Z","steps":["trace[1421591125] 'agreement among raft nodes before linearized reading' (duration: 660.028172ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.151835Z","caller":"traceutil/trace.go:171","msg":"trace[216627475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"633.641946ms","start":"2026-04-22T13:13:33.518163Z","end":"2026-04-22T13:13:34.151805Z","steps":["trace[216627475] 'agreement among raft nodes before linearized reading' (duration: 633.388239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:34.151865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.491085Z","time spent":"660.774512ms","remote":"127.0.0.1:49372","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":"info","ts":"2026-04-22T13:13:34.151865Z","caller":"traceutil/trace.go:171","msg":"trace[1162969847] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1327; }","duration":"396.103389ms","start":"2026-04-22T13:13:33.755739Z","end":"2026-04-22T13:13:34.151842Z","steps":["trace[1162969847] 'agreement among raft nodes before linearized reading' (duration: 395.952336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:34.151875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.491586Z","time spent":"660.281499ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:34.151892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.518141Z","time spent":"633.739829ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:13:34.151907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.75573Z","time spent":"396.165862ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-22T13:13:34.151763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:33.738682Z","time spent":"413.064292ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:34.175634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.226208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:34.175661Z","caller":"traceutil/trace.go:171","msg":"trace[602509177] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"107.171245ms","start":"2026-04-22T13:13:34.06847Z","end":"2026-04-22T13:13:34.175641Z","steps":["trace[602509177] 'process raft request' (duration: 106.950849ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.175726Z","caller":"traceutil/trace.go:171","msg":"trace[701605361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"282.342961ms","start":"2026-04-22T13:13:33.89336Z","end":"2026-04-22T13:13:34.175703Z","steps":["trace[701605361] 'agreement among raft nodes before linearized reading' (duration: 282.202176ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.332496Z","caller":"traceutil/trace.go:171","msg":"trace[649753785] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"175.416273ms","start":"2026-04-22T13:13:34.157052Z","end":"2026-04-22T13:13:34.332469Z","steps":["trace[649753785] 'process raft request' (duration: 175.218548ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.332891Z","caller":"traceutil/trace.go:171","msg":"trace[458902740] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"175.439083ms","start":"2026-04-22T13:13:34.157421Z","end":"2026-04-22T13:13:34.33286Z","steps":["trace[458902740] 'process raft request' (duration: 175.005402ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.33297Z","caller":"traceutil/trace.go:171","msg":"trace[1818635168] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"174.438319ms","start":"2026-04-22T13:13:34.158509Z","end":"2026-04-22T13:13:34.332947Z","steps":["trace[1818635168] 'process raft request' (duration: 174.395268ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.333011Z","caller":"traceutil/trace.go:171","msg":"trace[356613119] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"174.677984ms","start":"2026-04-22T13:13:34.158312Z","end":"2026-04-22T13:13:34.33299Z","steps":["trace[356613119] 'process raft request' (duration: 174.556571ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.333206Z","caller":"traceutil/trace.go:171","msg":"trace[1112951358] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"175.687789ms","start":"2026-04-22T13:13:34.157504Z","end":"2026-04-22T13:13:34.333191Z","steps":["trace[1112951358] 'process raft request' (duration: 175.241398ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:34.877842Z","caller":"traceutil/trace.go:171","msg":"trace[550880154] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"117.377899ms","start":"2026-04-22T13:13:34.760443Z","end":"2026-04-22T13:13:34.87782Z","steps":["trace[550880154] 'process raft request' (duration: 117.178664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:35.443344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.337632ms","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:533"} {"level":"warn","ts":"2026-04-22T13:13:35.443391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.301181ms","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-04-22T13:13:35.443437Z","caller":"traceutil/trace.go:171","msg":"trace[392814618] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"383.478486ms","start":"2026-04-22T13:13:35.059936Z","end":"2026-04-22T13:13:35.443414Z","steps":["trace[392814618] 'range keys from in-memory index tree' (duration: 383.122736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:35.443482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:35.059913Z","time spent":"383.559057ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-22T13:13:35.443484Z","caller":"traceutil/trace.go:171","msg":"trace[32943101] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1334; }","duration":"237.464305ms","start":"2026-04-22T13:13:35.205993Z","end":"2026-04-22T13:13:35.443457Z","steps":["trace[32943101] 'count revisions from in-memory index tree' (duration: 237.015624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:35.443506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.891139ms","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-22T13:13:35.443585Z","caller":"traceutil/trace.go:171","msg":"trace[390764076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"103.027512ms","start":"2026-04-22T13:13:35.340536Z","end":"2026-04-22T13:13:35.443564Z","steps":["trace[390764076] 'range keys from in-memory index tree' (duration: 102.694134ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:35.827385Z","caller":"traceutil/trace.go:171","msg":"trace[1683761703] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"346.317872ms","start":"2026-04-22T13:13:35.481042Z","end":"2026-04-22T13:13:35.82736Z","steps":["trace[1683761703] 'read index received' (duration: 13.681431ms)","trace[1683761703] 'applied index is now lower than readState.Index' (duration: 332.635411ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:35.827471Z","caller":"traceutil/trace.go:171","msg":"trace[921002905] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"379.443856ms","start":"2026-04-22T13:13:35.448016Z","end":"2026-04-22T13:13:35.82746Z","steps":["trace[921002905] 'process raft request' (duration: 313.504596ms)","trace[921002905] 'compare' (duration: 65.599181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:35.827552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:35.447999Z","time spent":"379.489918ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:35.827523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.469776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:35.827676Z","caller":"traceutil/trace.go:171","msg":"trace[1875608080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"346.657941ms","start":"2026-04-22T13:13:35.480997Z","end":"2026-04-22T13:13:35.827655Z","steps":["trace[1875608080] 'agreement among raft nodes before linearized reading' (duration: 346.472667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:35.827731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:35.480983Z","time spent":"346.738413ms","remote":"127.0.0.1:49108","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-22T13:13:35.856469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.253828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:35.85655Z","caller":"traceutil/trace.go:171","msg":"trace[539950110] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1337; }","duration":"220.412492ms","start":"2026-04-22T13:13:35.636119Z","end":"2026-04-22T13:13:35.856532Z","steps":["trace[539950110] 'agreement among raft nodes before linearized reading' (duration: 220.221607ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:36.042458Z","caller":"traceutil/trace.go:171","msg":"trace[638779562] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"105.444293ms","start":"2026-04-22T13:13:35.936985Z","end":"2026-04-22T13:13:36.04243Z","steps":["trace[638779562] 'process raft request' (duration: 105.296888ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:38.432513Z","caller":"traceutil/trace.go:171","msg":"trace[262814031] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"136.507305ms","start":"2026-04-22T13:13:38.295987Z","end":"2026-04-22T13:13:38.432495Z","steps":["trace[262814031] 'read index received' (duration: 136.338731ms)","trace[262814031] 'applied index is now lower than readState.Index' (duration: 167.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:38.432622Z","caller":"traceutil/trace.go:171","msg":"trace[1083071180] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"598.144224ms","start":"2026-04-22T13:13:37.834468Z","end":"2026-04-22T13:13:38.432613Z","steps":["trace[1083071180] 'process raft request' (duration: 597.898578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.432731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:37.834454Z","time spent":"598.203175ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:38.433356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.359606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:13:38.433428Z","caller":"traceutil/trace.go:171","msg":"trace[1255865681] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1361; }","duration":"137.475129ms","start":"2026-04-22T13:13:38.295936Z","end":"2026-04-22T13:13:38.433411Z","steps":["trace[1255865681] 'agreement among raft nodes before linearized reading' (duration: 137.324825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.730788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.14441ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-22T13:13:40.092751Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.474135379s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:13:40.093378Z","caller":"traceutil/trace.go:171","msg":"trace[80770257] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1418; }","duration":"1.611836002s","start":"2026-04-22T13:13:38.481518Z","end":"2026-04-22T13:13:40.093354Z","steps":["trace[80770257] 'read index received' (duration: 137.127819ms)","trace[80770257] 'applied index is now lower than readState.Index' (duration: 1.474707293s)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:40.093619Z","caller":"traceutil/trace.go:171","msg":"trace[1011981550] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"1.656520264s","start":"2026-04-22T13:13:38.437063Z","end":"2026-04-22T13:13:40.093584Z","steps":["trace[1011981550] 'process raft request' (duration: 1.656231127s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.093784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.437057Z","time spent":"1.656637947s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.093878Z","caller":"traceutil/trace.go:171","msg":"trace[1290634607] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.657136219s","start":"2026-04-22T13:13:38.436722Z","end":"2026-04-22T13:13:40.093858Z","steps":["trace[1290634607] 'process raft request' (duration: 1.656294459s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.093879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.612341416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:40.093959Z","caller":"traceutil/trace.go:171","msg":"trace[234126306] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.65673198s","start":"2026-04-22T13:13:38.437196Z","end":"2026-04-22T13:13:40.093928Z","steps":["trace[234126306] 'process raft request' (duration: 1.656127465s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.094025Z","caller":"traceutil/trace.go:171","msg":"trace[1525914823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"1.61249955s","start":"2026-04-22T13:13:38.481501Z","end":"2026-04-22T13:13:40.094001Z","steps":["trace[1525914823] 'agreement among raft nodes before linearized reading' (duration: 1.612236674s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.094038Z","caller":"traceutil/trace.go:171","msg":"trace[1068766243] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.657009027s","start":"2026-04-22T13:13:38.436984Z","end":"2026-04-22T13:13:40.093993Z","steps":["trace[1068766243] 'process raft request' (duration: 1.656265478s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.094088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.437167Z","time spent":"1.656851813s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:40.09411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.481439Z","time spent":"1.612659324s","remote":"127.0.0.1:49108","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-22T13:13:40.094136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.436711Z","time spent":"1.657212871s","remote":"127.0.0.1:49372","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-22T13:13:40.09422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.436837Z","time spent":"1.657265423s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:40.097344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311802722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:13:40.097363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.879724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T13:13:40.097377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608550171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-22T13:13:40.097368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.091689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-22T13:13:40.09742Z","caller":"traceutil/trace.go:171","msg":"trace[483741169] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1367; }","duration":"1.608612023s","start":"2026-04-22T13:13:38.488795Z","end":"2026-04-22T13:13:40.097407Z","steps":["trace[483741169] 'agreement among raft nodes before linearized reading' (duration: 1.608421939s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.097414Z","caller":"traceutil/trace.go:171","msg":"trace[1832180862] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1367; }","duration":"329.976756ms","start":"2026-04-22T13:13:39.767424Z","end":"2026-04-22T13:13:40.0974Z","steps":["trace[1832180862] 'agreement among raft nodes before linearized reading' (duration: 329.768391ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.097413Z","caller":"traceutil/trace.go:171","msg":"trace[2067375269] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1367; }","duration":"1.311930065s","start":"2026-04-22T13:13:38.785464Z","end":"2026-04-22T13:13:40.097394Z","steps":["trace[2067375269] 'agreement among raft nodes before linearized reading' (duration: 1.311814422s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.097435Z","caller":"traceutil/trace.go:171","msg":"trace[451270520] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"1.206607386s","start":"2026-04-22T13:13:38.890818Z","end":"2026-04-22T13:13:40.097425Z","steps":["trace[451270520] 'process raft request' (duration: 1.206294279s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.097448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.488785Z","time spent":"1.608656714s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-22T13:13:40.097436Z","caller":"traceutil/trace.go:171","msg":"trace[777778613] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"759.205701ms","start":"2026-04-22T13:13:39.338214Z","end":"2026-04-22T13:13:40.097419Z","steps":["trace[777778613] 'agreement among raft nodes before linearized reading' (duration: 758.982376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.097463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.767228Z","time spent":"330.221842ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:40.097468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.785434Z","time spent":"1.312020198s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:40.097486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.89079Z","time spent":"1.206667667s","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:40.097499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.338194Z","time spent":"759.287404ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-22T13:13:40.097528Z","caller":"traceutil/trace.go:171","msg":"trace[579565022] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"1.365817985s","start":"2026-04-22T13:13:38.731688Z","end":"2026-04-22T13:13:40.097506Z","steps":["trace[579565022] 'process raft request' (duration: 1.365318004s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.097633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.731667Z","time spent":"1.365904838s","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:14:04.500559Z","caller":"traceutil/trace.go:171","msg":"trace[2140844498] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"177.444813ms","start":"2026-04-22T13:14:04.323095Z","end":"2026-04-22T13:14:04.50054Z","steps":["trace[2140844498] 'process raft request' (duration: 89.890695ms)","trace[2140844498] 'compare' (duration: 87.328052ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:14:04.500784Z","caller":"traceutil/trace.go:171","msg":"trace[1519195998] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1538; }","duration":"111.81706ms","start":"2026-04-22T13:14:04.388665Z","end":"2026-04-22T13:14:04.500482Z","steps":["trace[1519195998] 'read index received' (duration: 24.332804ms)","trace[1519195998] 'applied index is now lower than readState.Index' (duration: 87.482926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:14:04.501041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.357955ms","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-04-22T13:14:04.501123Z","caller":"traceutil/trace.go:171","msg":"trace[1095682579] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1476; }","duration":"112.489627ms","start":"2026-04-22T13:14:04.388614Z","end":"2026-04-22T13:14:04.501104Z","steps":["trace[1095682579] 'agreement among raft nodes before linearized reading' (duration: 112.232601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:04.501134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.209246ms","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-22T13:14:04.501207Z","caller":"traceutil/trace.go:171","msg":"trace[1993972264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1476; }","duration":"111.324329ms","start":"2026-04-22T13:14:04.389867Z","end":"2026-04-22T13:14:04.501191Z","steps":["trace[1993972264] 'agreement among raft nodes before linearized reading' (duration: 111.141815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:04.501223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.269696ms","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-22T13:14:04.50138Z","caller":"traceutil/trace.go:171","msg":"trace[1363192675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1476; }","duration":"111.421141ms","start":"2026-04-22T13:14:04.389921Z","end":"2026-04-22T13:14:04.501342Z","steps":["trace[1363192675] 'agreement among raft nodes before linearized reading' (duration: 111.042322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:04.501606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.567599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T13:14:04.501671Z","caller":"traceutil/trace.go:171","msg":"trace[929110675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1476; }","duration":"112.661912ms","start":"2026-04-22T13:14:04.388997Z","end":"2026-04-22T13:14:04.501659Z","steps":["trace[929110675] 'agreement among raft nodes before linearized reading' (duration: 112.224501ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:14:04.700238Z","caller":"traceutil/trace.go:171","msg":"trace[1792741806] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"112.59765ms","start":"2026-04-22T13:14:04.587592Z","end":"2026-04-22T13:14:04.70019Z","steps":["trace[1792741806] 'process raft request' (duration: 26.369865ms)","trace[1792741806] 'compare' (duration: 85.924258ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:14:05.205871Z","caller":"traceutil/trace.go:171","msg":"trace[296615759] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"240.620644ms","start":"2026-04-22T13:14:04.965222Z","end":"2026-04-22T13:14:05.205843Z","steps":["trace[296615759] 'process raft request' (duration: 240.361846ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:14:37.249289Z","caller":"traceutil/trace.go:171","msg":"trace[67332179] linearizableReadLoop","detail":"{readStateIndex:1698; appliedIndex:1697; }","duration":"408.697845ms","start":"2026-04-22T13:14:36.840559Z","end":"2026-04-22T13:14:37.249257Z","steps":["trace[67332179] 'read index received' (duration: 407.848425ms)","trace[67332179] 'applied index is now lower than readState.Index' (duration: 848.77µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:14:37.249326Z","caller":"traceutil/trace.go:171","msg":"trace[1626527576] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"451.983183ms","start":"2026-04-22T13:14:36.797323Z","end":"2026-04-22T13:14:37.249306Z","steps":["trace[1626527576] 'process raft request' (duration: 451.098851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:37.24942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:14:36.797311Z","time spent":"452.055655ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:14:37.249515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.952212ms","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-22T13:14:37.24959Z","caller":"traceutil/trace.go:171","msg":"trace[572256938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1629; }","duration":"409.061945ms","start":"2026-04-22T13:14:36.84052Z","end":"2026-04-22T13:14:37.249582Z","steps":["trace[572256938] 'agreement among raft nodes before linearized reading' (duration: 408.880071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:37.24964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:14:36.840508Z","time spent":"409.127166ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T13:16:27.606147Z","caller":"traceutil/trace.go:171","msg":"trace[1838073961] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"474.824938ms","start":"2026-04-22T13:16:27.131302Z","end":"2026-04-22T13:16:27.606127Z","steps":["trace[1838073961] 'read index received' (duration: 474.667104ms)","trace[1838073961] 'applied index is now lower than readState.Index' (duration: 156.904µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:16:27.60628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.959431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:16:27.606248Z","caller":"traceutil/trace.go:171","msg":"trace[891445735] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"513.114003ms","start":"2026-04-22T13:16:27.093097Z","end":"2026-04-22T13:16:27.606211Z","steps":["trace[891445735] 'process raft request' (duration: 512.917328ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:16:27.606309Z","caller":"traceutil/trace.go:171","msg":"trace[350426403] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2132; }","duration":"475.089424ms","start":"2026-04-22T13:16:27.131212Z","end":"2026-04-22T13:16:27.606302Z","steps":["trace[350426403] 'agreement among raft nodes before linearized reading' (duration: 475.016602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:16:27.606334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:16:27.131192Z","time spent":"475.135336ms","remote":"127.0.0.1:44640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:16:27.606417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.755549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:16:27.606433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.207921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-22T13:16:27.606491Z","caller":"traceutil/trace.go:171","msg":"trace[1547889247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2132; }","duration":"123.856431ms","start":"2026-04-22T13:16:27.482614Z","end":"2026-04-22T13:16:27.606471Z","steps":["trace[1547889247] 'agreement among raft nodes before linearized reading' (duration: 123.695266ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:16:27.606519Z","caller":"traceutil/trace.go:171","msg":"trace[1931200608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2132; }","duration":"239.338814ms","start":"2026-04-22T13:16:27.36716Z","end":"2026-04-22T13:16:27.606499Z","steps":["trace[1931200608] 'agreement among raft nodes before linearized reading' (duration: 239.127388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:16:27.606567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.995216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T13:16:27.606628Z","caller":"traceutil/trace.go:171","msg":"trace[2134221450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2132; }","duration":"205.077628ms","start":"2026-04-22T13:16:27.401525Z","end":"2026-04-22T13:16:27.606602Z","steps":["trace[2134221450] 'agreement among raft nodes before linearized reading' (duration: 204.859903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:16:27.606769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.78029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T13:16:27.606874Z","caller":"traceutil/trace.go:171","msg":"trace[856844842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2132; }","duration":"198.920724ms","start":"2026-04-22T13:16:27.407926Z","end":"2026-04-22T13:16:27.606846Z","steps":["trace[856844842] 'agreement among raft nodes before linearized reading' (duration: 198.445523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:16:27.607204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:16:27.093069Z","time spent":"513.264016ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:16:29.414806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.141647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:16:29.414882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.428603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:16:29.414917Z","caller":"traceutil/trace.go:171","msg":"trace[784929623] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2140; }","duration":"122.28535ms","start":"2026-04-22T13:16:29.292597Z","end":"2026-04-22T13:16:29.414882Z","steps":["trace[784929623] 'count revisions from in-memory index tree' (duration: 122.037364ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:16:29.414938Z","caller":"traceutil/trace.go:171","msg":"trace[845093344] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2140; }","duration":"241.541396ms","start":"2026-04-22T13:16:29.173385Z","end":"2026-04-22T13:16:29.414927Z","steps":["trace[845093344] 'count revisions from in-memory index tree' (duration: 241.338431ms)"],"step_count":1}