{"level":"warn","ts":"2026-04-30T11:07:16.479975Z","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-30T11:07:16.480052Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-30T11:07:16.480113Z","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-30T11:07:16.480125Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-30T11:07:16.480141Z","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-30T11:07:16.480796Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-04-30T11:07:16.480871Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-30T11:07:16.487461Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.358631ms"} {"level":"info","ts":"2026-04-30T11:07:16.498557Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-04-30T11:07:16.49865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-04-30T11:07:16.498675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-04-30T11:07:16.498688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-30T11:07:16.498697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-04-30T11:07:16.498736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-04-30T11:07:16.505939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-30T11:07:16.507346Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-30T11:07:16.50863Z","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-30T11:07:16.513869Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-30T11:07:16.514385Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-30T11:07:16.51444Z","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-30T11:07:16.514687Z","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-30T11:07:16.514791Z","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-30T11:07:16.515823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-04-30T11:07:16.516233Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-30T11:07:16.518185Z","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-30T11:07:16.518362Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-30T11:07:16.518407Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-30T11:07:16.518669Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-30T11:07:16.518706Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-30T11:07:16.799529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-04-30T11:07:16.799587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-30T11:07:16.799624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-04-30T11:07:16.799688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-04-30T11:07:16.799706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-30T11:07:16.799748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-04-30T11:07:16.79977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-30T11:07:16.800508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T11:07:16.801333Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T11:07:16.801334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-30T11:07:16.801439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T11:07:16.801638Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-30T11:07:16.80166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-30T11:07:16.801817Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T11:07:16.801948Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T11:07:16.802043Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T11:07:16.804934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-30T11:07:16.824818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-04-30T11:07:19.422997Z","caller":"traceutil/trace.go:171","msg":"trace[1811886368] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"230.126662ms","start":"2026-04-30T11:07:19.192836Z","end":"2026-04-30T11:07:19.422963Z","steps":["trace[1811886368] 'process raft request' (duration: 196.972498ms)","trace[1811886368] 'compare' (duration: 33.00257ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:07:25.217928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.483188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-30T11:07:25.217946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.683936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-30T11:07:25.218022Z","caller":"traceutil/trace.go:171","msg":"trace[660121151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"158.6125ms","start":"2026-04-30T11:07:25.059395Z","end":"2026-04-30T11:07:25.218008Z","steps":["trace[660121151] 'range keys from in-memory index tree' (duration: 158.401635ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.218046Z","caller":"traceutil/trace.go:171","msg":"trace[2101060194] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"107.83041ms","start":"2026-04-30T11:07:25.110196Z","end":"2026-04-30T11:07:25.218026Z","steps":["trace[2101060194] 'range keys from in-memory index tree' (duration: 107.515752ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.360195Z","caller":"traceutil/trace.go:171","msg":"trace[1758262203] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"133.71851ms","start":"2026-04-30T11:07:25.226453Z","end":"2026-04-30T11:07:25.360171Z","steps":["trace[1758262203] 'process raft request' (duration: 133.151147ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.360521Z","caller":"traceutil/trace.go:171","msg":"trace[635820507] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"133.504496ms","start":"2026-04-30T11:07:25.226989Z","end":"2026-04-30T11:07:25.360494Z","steps":["trace[635820507] 'process raft request' (duration: 132.82487ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.360522Z","caller":"traceutil/trace.go:171","msg":"trace[1190327802] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"133.388223ms","start":"2026-04-30T11:07:25.227112Z","end":"2026-04-30T11:07:25.3605Z","steps":["trace[1190327802] 'process raft request' (duration: 132.757328ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.477998Z","caller":"traceutil/trace.go:171","msg":"trace[994797546] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"109.367346ms","start":"2026-04-30T11:07:25.3686Z","end":"2026-04-30T11:07:25.477967Z","steps":["trace[994797546] 'process raft request' (duration: 108.71379ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.478371Z","caller":"traceutil/trace.go:171","msg":"trace[1393797299] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"109.149601ms","start":"2026-04-30T11:07:25.369205Z","end":"2026-04-30T11:07:25.478355Z","steps":["trace[1393797299] 'process raft request' (duration: 108.70819ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.478503Z","caller":"traceutil/trace.go:171","msg":"trace[2000683096] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"105.456483ms","start":"2026-04-30T11:07:25.373036Z","end":"2026-04-30T11:07:25.478493Z","steps":["trace[2000683096] 'process raft request' (duration: 105.006292ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.47858Z","caller":"traceutil/trace.go:171","msg":"trace[1623177673] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"107.8322ms","start":"2026-04-30T11:07:25.370732Z","end":"2026-04-30T11:07:25.478564Z","steps":["trace[1623177673] 'process raft request' (duration: 107.264666ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.478333Z","caller":"traceutil/trace.go:171","msg":"trace[2106191881] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"109.631792ms","start":"2026-04-30T11:07:25.368676Z","end":"2026-04-30T11:07:25.478308Z","steps":["trace[2106191881] 'process raft request' (duration: 109.186911ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.478441Z","caller":"traceutil/trace.go:171","msg":"trace[1730887823] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"108.347002ms","start":"2026-04-30T11:07:25.370082Z","end":"2026-04-30T11:07:25.478429Z","steps":["trace[1730887823] 'process raft request' (duration: 107.86476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:25.901205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.973471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:07:25.901567Z","caller":"traceutil/trace.go:171","msg":"trace[887474827] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:360; }","duration":"391.551037ms","start":"2026-04-30T11:07:25.509997Z","end":"2026-04-30T11:07:25.901548Z","steps":["trace[887474827] 'read index received' (duration: 41.00268ms)","trace[887474827] 'applied index is now lower than readState.Index' (duration: 350.547377ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:25.901561Z","caller":"traceutil/trace.go:171","msg":"trace[921865106] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"412.863602ms","start":"2026-04-30T11:07:25.488657Z","end":"2026-04-30T11:07:25.90152Z","steps":["trace[921865106] 'process raft request' (duration: 180.936588ms)","trace[921865106] 'compare' (duration: 230.824308ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:25.901593Z","caller":"traceutil/trace.go:171","msg":"trace[2102104046] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"412.622395ms","start":"2026-04-30T11:07:25.488924Z","end":"2026-04-30T11:07:25.901547Z","steps":["trace[2102104046] 'process raft request' (duration: 412.37982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:25.90178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.488898Z","time spent":"412.79575ms","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T11:07:25.901792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.488637Z","time spent":"413.008955ms","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:07:25.901841Z","caller":"traceutil/trace.go:171","msg":"trace[1383136298] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"412.312498ms","start":"2026-04-30T11:07:25.489515Z","end":"2026-04-30T11:07:25.901827Z","steps":["trace[1383136298] 'process raft request' (duration: 411.936909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:25.902243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.382751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-30T11:07:25.9023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.281094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-30T11:07:25.902296Z","caller":"traceutil/trace.go:171","msg":"trace[1868840441] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"412.553554ms","start":"2026-04-30T11:07:25.489725Z","end":"2026-04-30T11:07:25.902278Z","steps":["trace[1868840441] 'process raft request' (duration: 411.773245ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.902326Z","caller":"traceutil/trace.go:171","msg":"trace[1602741239] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:355; }","duration":"341.524514ms","start":"2026-04-30T11:07:25.560778Z","end":"2026-04-30T11:07:25.902302Z","steps":["trace[1602741239] 'agreement among raft nodes before linearized reading' (duration: 341.372951ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:25.902352Z","caller":"traceutil/trace.go:171","msg":"trace[1413024017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:355; }","duration":"290.378226ms","start":"2026-04-30T11:07:25.611952Z","end":"2026-04-30T11:07:25.902331Z","steps":["trace[1413024017] 'agreement among raft nodes before linearized reading' (duration: 290.275924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:25.90237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.560747Z","time spent":"341.614977ms","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-30T11:07:25.902374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.489717Z","time spent":"412.627116ms","remote":"127.0.0.1:36634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T11:07:25.90239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.363216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-30T11:07:25.902478Z","caller":"traceutil/trace.go:171","msg":"trace[408539533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"392.51609ms","start":"2026-04-30T11:07:25.509943Z","end":"2026-04-30T11:07:25.902459Z","steps":["trace[408539533] 'agreement among raft nodes before linearized reading' (duration: 392.278584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:25.902525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.509922Z","time spent":"392.597153ms","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-30T11:07:25.903172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.489506Z","time spent":"412.515014ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:07:26.239226Z","caller":"traceutil/trace.go:171","msg":"trace[111542873] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"327.399271ms","start":"2026-04-30T11:07:25.911802Z","end":"2026-04-30T11:07:26.239202Z","steps":["trace[111542873] 'process raft request' (duration: 327.34844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:26.24059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.911796Z","time spent":"328.689681ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:07:26.23943Z","caller":"traceutil/trace.go:171","msg":"trace[1131963140] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"332.200734ms","start":"2026-04-30T11:07:25.907197Z","end":"2026-04-30T11:07:26.239398Z","steps":["trace[1131963140] 'process raft request' (duration: 234.426223ms)","trace[1131963140] 'compare' (duration: 97.197338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:07:26.240912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.907174Z","time spent":"333.651888ms","remote":"127.0.0.1:36634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T11:07:26.239593Z","caller":"traceutil/trace.go:171","msg":"trace[659275884] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"331.124819ms","start":"2026-04-30T11:07:25.908457Z","end":"2026-04-30T11:07:26.239582Z","steps":["trace[659275884] 'process raft request' (duration: 330.532775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:26.241115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.908449Z","time spent":"332.650305ms","remote":"127.0.0.1:37078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T11:07:26.23965Z","caller":"traceutil/trace.go:171","msg":"trace[994101518] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"328.375264ms","start":"2026-04-30T11:07:25.911255Z","end":"2026-04-30T11:07:26.23963Z","steps":["trace[994101518] 'process raft request' (duration: 327.863362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:26.241266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.911226Z","time spent":"330.018283ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T11:07:26.239809Z","caller":"traceutil/trace.go:171","msg":"trace[1526958164] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"329.134182ms","start":"2026-04-30T11:07:25.910626Z","end":"2026-04-30T11:07:26.23976Z","steps":["trace[1526958164] 'process raft request' (duration: 328.430105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:26.241455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:25.910614Z","time spent":"330.801731ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:07:26.239877Z","caller":"traceutil/trace.go:171","msg":"trace[1093750094] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:366; }","duration":"177.723652ms","start":"2026-04-30T11:07:26.062119Z","end":"2026-04-30T11:07:26.239843Z","steps":["trace[1093750094] 'read index received' (duration: 79.51811ms)","trace[1093750094] 'applied index is now lower than readState.Index' (duration: 98.204492ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:26.24003Z","caller":"traceutil/trace.go:171","msg":"trace[342293658] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"244.145132ms","start":"2026-04-30T11:07:25.995866Z","end":"2026-04-30T11:07:26.240011Z","steps":["trace[342293658] 'process raft request' (duration: 243.812504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:26.240071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.919866ms","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-30T11:07:26.241719Z","caller":"traceutil/trace.go:171","msg":"trace[1405331555] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:362; }","duration":"179.591406ms","start":"2026-04-30T11:07:26.06211Z","end":"2026-04-30T11:07:26.241701Z","steps":["trace[1405331555] 'agreement among raft nodes before linearized reading' (duration: 177.846805ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:45.41502Z","caller":"traceutil/trace.go:171","msg":"trace[23862896] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"392.346817ms","start":"2026-04-30T11:07:45.022651Z","end":"2026-04-30T11:07:45.414997Z","steps":["trace[23862896] 'process raft request' (duration: 392.208264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:45.415282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:45.022624Z","time spent":"392.584342ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:07:49.070156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701230182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:07:49.570956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701230182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:07:50.071905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701230182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:07:50.140554Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.631091965s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T11:07:50.141136Z","caller":"traceutil/trace.go:171","msg":"trace[1002879782] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.63174806s","start":"2026-04-30T11:07:48.509364Z","end":"2026-04-30T11:07:50.141112Z","steps":["trace[1002879782] 'process raft request' (duration: 1.631576396s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:50.141307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:48.509345Z","time spent":"1.631886383s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:07:50.376789Z","caller":"traceutil/trace.go:171","msg":"trace[1601624837] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"1.806991314s","start":"2026-04-30T11:07:48.569773Z","end":"2026-04-30T11:07:50.376764Z","steps":["trace[1601624837] 'read index received' (duration: 1.571094677s)","trace[1601624837] 'applied index is now lower than readState.Index' (duration: 235.895217ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:50.376939Z","caller":"traceutil/trace.go:171","msg":"trace[1971041727] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.210152082s","start":"2026-04-30T11:07:49.166654Z","end":"2026-04-30T11:07:50.376806Z","steps":["trace[1971041727] 'process raft request' (duration: 1.118949246s)","trace[1971041727] 'compare' (duration: 91.022252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:07:50.376996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807146448s","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-30T11:07:50.377039Z","caller":"traceutil/trace.go:171","msg":"trace[1977226612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"1.807298121s","start":"2026-04-30T11:07:48.569729Z","end":"2026-04-30T11:07:50.377028Z","steps":["trace[1977226612] 'agreement among raft nodes before linearized reading' (duration: 1.807150208s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:50.377097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:48.569709Z","time spent":"1.807379293s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:07:50.377064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.642618ms","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-30T11:07:50.377125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459154939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:07:50.377159Z","caller":"traceutil/trace.go:171","msg":"trace[2134505997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"945.771011ms","start":"2026-04-30T11:07:49.431365Z","end":"2026-04-30T11:07:50.377136Z","steps":["trace[2134505997] 'agreement among raft nodes before linearized reading' (duration: 945.614677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:50.377243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:49.431347Z","time spent":"945.882893ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T11:07:50.377242Z","caller":"traceutil/trace.go:171","msg":"trace[439947988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.459316143s","start":"2026-04-30T11:07:48.917901Z","end":"2026-04-30T11:07:50.377217Z","steps":["trace[439947988] 'agreement among raft nodes before linearized reading' (duration: 1.459083047s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:50.377219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.523179ms","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-30T11:07:50.377304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:48.917861Z","time spent":"1.459434426s","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-30T11:07:50.377319Z","caller":"traceutil/trace.go:171","msg":"trace[46966027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"839.726603ms","start":"2026-04-30T11:07:49.537564Z","end":"2026-04-30T11:07:50.377291Z","steps":["trace[46966027] 'agreement among raft nodes before linearized reading' (duration: 839.433987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:50.3774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:49.537546Z","time spent":"839.841547ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T11:07:50.377505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:49.166598Z","time spent":"1.210457329s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:07:50.377769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50275263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:07:50.377819Z","caller":"traceutil/trace.go:171","msg":"trace[171007639] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:515; }","duration":"1.502863253s","start":"2026-04-30T11:07:48.874943Z","end":"2026-04-30T11:07:50.377806Z","steps":["trace[171007639] 'agreement among raft nodes before linearized reading' (duration: 1.502014323s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:50.377856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:48.874923Z","time spent":"1.502924774s","remote":"127.0.0.1:36618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-30T11:07:53.023601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.093108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:07:53.02367Z","caller":"traceutil/trace.go:171","msg":"trace[1717692652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"106.213911ms","start":"2026-04-30T11:07:52.917441Z","end":"2026-04-30T11:07:53.023655Z","steps":["trace[1717692652] 'range keys from in-memory index tree' (duration: 106.007896ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:57.212458Z","caller":"traceutil/trace.go:171","msg":"trace[151117726] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"540.984904ms","start":"2026-04-30T11:07:56.671339Z","end":"2026-04-30T11:07:57.212324Z","steps":["trace[151117726] 'process raft request' (duration: 539.467108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:57.212839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:56.671306Z","time spent":"541.428895ms","remote":"127.0.0.1:36618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:07:57.683523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.400454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:07:57.68367Z","caller":"traceutil/trace.go:171","msg":"trace[1202963439] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"418.016574ms","start":"2026-04-30T11:07:57.265639Z","end":"2026-04-30T11:07:57.683656Z","steps":["trace[1202963439] 'process raft request' (duration: 417.966083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:57.68372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:57.265618Z","time spent":"418.078236ms","remote":"127.0.0.1:37098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T11:07:57.683718Z","caller":"traceutil/trace.go:171","msg":"trace[848733265] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"457.252693ms","start":"2026-04-30T11:07:57.226436Z","end":"2026-04-30T11:07:57.683689Z","steps":["trace[848733265] 'process raft request' (duration: 305.624314ms)","trace[848733265] 'compare' (duration: 151.24905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:07:57.683831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:57.226415Z","time spent":"457.360826ms","remote":"127.0.0.1:36764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:07:58.675241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701230435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:07:59.175746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701230435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:07:59.27421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356291024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:07:59.274301Z","caller":"traceutil/trace.go:171","msg":"trace[1463393471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.356413817s","start":"2026-04-30T11:07:57.917866Z","end":"2026-04-30T11:07:59.27428Z","steps":["trace[1463393471] 'range keys from in-memory index tree' (duration: 1.356236832s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:59.274342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:57.917849Z","time spent":"1.356485098s","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-30T11:07:59.274634Z","caller":"traceutil/trace.go:171","msg":"trace[622874911] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"1.099573268s","start":"2026-04-30T11:07:58.175035Z","end":"2026-04-30T11:07:59.274609Z","steps":["trace[622874911] 'read index received' (duration: 1.03757651s)","trace[622874911] 'applied index is now lower than readState.Index' (duration: 61.995838ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:59.274755Z","caller":"traceutil/trace.go:171","msg":"trace[717253337] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.189584208s","start":"2026-04-30T11:07:58.08516Z","end":"2026-04-30T11:07:59.274744Z","steps":["trace[717253337] 'process raft request' (duration: 1.127675763s)","trace[717253337] 'compare' (duration: 61.405063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:07:59.274878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:58.08513Z","time spent":"1.1896583s","remote":"127.0.0.1:36618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:07:59.275117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.150161ms","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-30T11:07:59.275196Z","caller":"traceutil/trace.go:171","msg":"trace[1293196452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"844.301455ms","start":"2026-04-30T11:07:58.430875Z","end":"2026-04-30T11:07:59.275176Z","steps":["trace[1293196452] 'agreement among raft nodes before linearized reading' (duration: 844.07319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:59.275238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:58.43084Z","time spent":"844.389597ms","remote":"127.0.0.1:36864","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-30T11:07:59.275255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.935395ms","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-30T11:07:59.275296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100254704s","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-30T11:07:59.275309Z","caller":"traceutil/trace.go:171","msg":"trace[461371760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"830.010857ms","start":"2026-04-30T11:07:58.445283Z","end":"2026-04-30T11:07:59.275293Z","steps":["trace[461371760] 'agreement among raft nodes before linearized reading' (duration: 829.855493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:59.275339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:58.445269Z","time spent":"830.065548ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-30T11:07:59.275333Z","caller":"traceutil/trace.go:171","msg":"trace[2087468910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"1.100316835s","start":"2026-04-30T11:07:58.175006Z","end":"2026-04-30T11:07:59.275323Z","steps":["trace[2087468910] 'agreement among raft nodes before linearized reading' (duration: 1.100189182s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:59.275364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:58.174992Z","time spent":"1.100365366s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-30T11:07:59.275428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.361789ms","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-30T11:07:59.275444Z","caller":"traceutil/trace.go:171","msg":"trace[1350541951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"831.43134ms","start":"2026-04-30T11:07:58.444007Z","end":"2026-04-30T11:07:59.275438Z","steps":["trace[1350541951] 'agreement among raft nodes before linearized reading' (duration: 831.390139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:07:59.275459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:07:58.44398Z","time spent":"831.474971ms","remote":"127.0.0.1:36864","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-30T11:07:59.493995Z","caller":"traceutil/trace.go:171","msg":"trace[1718562515] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"157.372225ms","start":"2026-04-30T11:07:59.336599Z","end":"2026-04-30T11:07:59.493971Z","steps":["trace[1718562515] 'process raft request' (duration: 82.567414ms)","trace[1718562515] 'compare' (duration: 74.675808ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:59.635126Z","caller":"traceutil/trace.go:171","msg":"trace[149644916] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"117.483871ms","start":"2026-04-30T11:07:59.517615Z","end":"2026-04-30T11:07:59.635099Z","steps":["trace[149644916] 'process raft request' (duration: 96.981155ms)","trace[149644916] 'compare' (duration: 20.336592ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:07:59.637565Z","caller":"traceutil/trace.go:171","msg":"trace[952431338] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"119.606811ms","start":"2026-04-30T11:07:59.517942Z","end":"2026-04-30T11:07:59.637549Z","steps":["trace[952431338] 'process raft request' (duration: 119.422706ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:59.637577Z","caller":"traceutil/trace.go:171","msg":"trace[1366035778] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"117.00256ms","start":"2026-04-30T11:07:59.520555Z","end":"2026-04-30T11:07:59.637557Z","steps":["trace[1366035778] 'process raft request' (duration: 116.958519ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:07:59.637662Z","caller":"traceutil/trace.go:171","msg":"trace[1875637571] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"117.089322ms","start":"2026-04-30T11:07:59.520524Z","end":"2026-04-30T11:07:59.637614Z","steps":["trace[1875637571] 'process raft request' (duration: 116.942899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:01.707249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.835944ms","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-30T11:08:01.707334Z","caller":"traceutil/trace.go:171","msg":"trace[1254433470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"369.973437ms","start":"2026-04-30T11:08:01.337342Z","end":"2026-04-30T11:08:01.707315Z","steps":["trace[1254433470] 'range keys from in-memory index tree' (duration: 369.600879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:01.707373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.338087ms","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-30T11:08:01.707428Z","caller":"traceutil/trace.go:171","msg":"trace[1438188133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"403.414449ms","start":"2026-04-30T11:08:01.303992Z","end":"2026-04-30T11:08:01.707407Z","steps":["trace[1438188133] 'range keys from in-memory index tree' (duration: 403.220354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:01.707519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:01.303973Z","time spent":"403.520162ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:08:01.707378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:01.33732Z","time spent":"370.049729ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T11:08:01.707259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.041289ms","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-30T11:08:01.707837Z","caller":"traceutil/trace.go:171","msg":"trace[2130841764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"370.692534ms","start":"2026-04-30T11:08:01.337123Z","end":"2026-04-30T11:08:01.707815Z","steps":["trace[2130841764] 'range keys from in-memory index tree' (duration: 369.867265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:01.707893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:01.337116Z","time spent":"370.759676ms","remote":"127.0.0.1:36864","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-30T11:08:01.707881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.868908ms","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-30T11:08:01.707921Z","caller":"traceutil/trace.go:171","msg":"trace[1839205830] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"308.386679ms","start":"2026-04-30T11:08:01.399522Z","end":"2026-04-30T11:08:01.707908Z","steps":["trace[1839205830] 'process raft request' (duration: 307.311174ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:01.707943Z","caller":"traceutil/trace.go:171","msg":"trace[1428872542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"370.972211ms","start":"2026-04-30T11:08:01.336955Z","end":"2026-04-30T11:08:01.707928Z","steps":["trace[1428872542] 'range keys from in-memory index tree' (duration: 370.698594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:01.70801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:01.336934Z","time spent":"371.062832ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-30T11:08:01.708085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:01.399501Z","time spent":"308.451861ms","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T11:08:01.71207Z","caller":"traceutil/trace.go:171","msg":"trace[2060830075] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"109.318768ms","start":"2026-04-30T11:08:01.602714Z","end":"2026-04-30T11:08:01.712033Z","steps":["trace[2060830075] 'process raft request' (duration: 109.124323ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:06.197895Z","caller":"traceutil/trace.go:171","msg":"trace[156699037] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"103.538751ms","start":"2026-04-30T11:08:06.094328Z","end":"2026-04-30T11:08:06.197867Z","steps":["trace[156699037] 'read index received' (duration: 21.11101ms)","trace[156699037] 'applied index is now lower than readState.Index' (duration: 82.426361ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:06.198391Z","caller":"traceutil/trace.go:171","msg":"trace[799704281] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"120.882232ms","start":"2026-04-30T11:08:06.077483Z","end":"2026-04-30T11:08:06.198365Z","steps":["trace[799704281] 'process raft request' (duration: 96.607777ms)","trace[799704281] 'compare' (duration: 23.447485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:08:06.198427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.058023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:06.198632Z","caller":"traceutil/trace.go:171","msg":"trace[528514036] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:726; }","duration":"104.31768ms","start":"2026-04-30T11:08:06.094292Z","end":"2026-04-30T11:08:06.198609Z","steps":["trace[528514036] 'agreement among raft nodes before linearized reading' (duration: 104.027853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:06.228082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.462319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:06.228147Z","caller":"traceutil/trace.go:171","msg":"trace[1472367680] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:726; }","duration":"133.554521ms","start":"2026-04-30T11:08:06.094576Z","end":"2026-04-30T11:08:06.228131Z","steps":["trace[1472367680] 'agreement among raft nodes before linearized reading' (duration: 133.442939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:06.228145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.692104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:08:06.228078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.84368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:06.228215Z","caller":"traceutil/trace.go:171","msg":"trace[1569996322] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:726; }","duration":"133.791587ms","start":"2026-04-30T11:08:06.094404Z","end":"2026-04-30T11:08:06.228196Z","steps":["trace[1569996322] 'agreement among raft nodes before linearized reading' (duration: 133.641483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:06.228102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.077271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:06.228231Z","caller":"traceutil/trace.go:171","msg":"trace[369285686] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:726; }","duration":"118.031984ms","start":"2026-04-30T11:08:06.11018Z","end":"2026-04-30T11:08:06.228212Z","steps":["trace[369285686] 'agreement among raft nodes before linearized reading' (duration: 117.813369ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:06.228341Z","caller":"traceutil/trace.go:171","msg":"trace[890729103] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:726; }","duration":"117.304287ms","start":"2026-04-30T11:08:06.110983Z","end":"2026-04-30T11:08:06.228287Z","steps":["trace[890729103] 'agreement among raft nodes before linearized reading' (duration: 117.072072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:06.228498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.746527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:06.228569Z","caller":"traceutil/trace.go:171","msg":"trace[780853769] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:726; }","duration":"118.313611ms","start":"2026-04-30T11:08:06.110244Z","end":"2026-04-30T11:08:06.228558Z","steps":["trace[780853769] 'agreement among raft nodes before linearized reading' (duration: 117.717226ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:11.951387Z","caller":"traceutil/trace.go:171","msg":"trace[2040371297] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"169.565484ms","start":"2026-04-30T11:08:11.781799Z","end":"2026-04-30T11:08:11.951365Z","steps":["trace[2040371297] 'process raft request' (duration: 169.247256ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:12.453731Z","caller":"traceutil/trace.go:171","msg":"trace[2134918988] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"497.790333ms","start":"2026-04-30T11:08:11.955918Z","end":"2026-04-30T11:08:12.453709Z","steps":["trace[2134918988] 'process raft request' (duration: 497.755323ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:12.453763Z","caller":"traceutil/trace.go:171","msg":"trace[12095036] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"497.951037ms","start":"2026-04-30T11:08:11.955767Z","end":"2026-04-30T11:08:12.453719Z","steps":["trace[12095036] 'process raft request' (duration: 497.869985ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:12.453764Z","caller":"traceutil/trace.go:171","msg":"trace[45383885] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"498.464359ms","start":"2026-04-30T11:08:11.955243Z","end":"2026-04-30T11:08:12.453707Z","steps":["trace[45383885] 'process raft request' (duration: 412.844753ms)","trace[45383885] 'compare' (duration: 85.456623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:08:12.453928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:11.955742Z","time spent":"498.09202ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:12.453882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:11.955899Z","time spent":"497.896656ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:12.454854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:11.955221Z","time spent":"498.993831ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:08:15.21935Z","caller":"traceutil/trace.go:171","msg":"trace[1138205914] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"146.354804ms","start":"2026-04-30T11:08:15.072966Z","end":"2026-04-30T11:08:15.219321Z","steps":["trace[1138205914] 'process raft request' (duration: 146.127809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.419557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701230741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:08:16.695475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.119541839s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-30T11:08:16.936799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.248529ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:16.936931Z","caller":"traceutil/trace.go:171","msg":"trace[2011401559] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:794; }","duration":"704.390163ms","start":"2026-04-30T11:08:16.232522Z","end":"2026-04-30T11:08:16.936912Z","steps":["trace[2011401559] 'range keys from in-memory index tree' (duration: 704.233379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.942389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.561386ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-30T11:08:16.942715Z","caller":"traceutil/trace.go:171","msg":"trace[2054680754] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"1.023948176s","start":"2026-04-30T11:08:15.918732Z","end":"2026-04-30T11:08:16.94268Z","steps":["trace[2054680754] 'read index received' (duration: 776.95261ms)","trace[2054680754] 'applied index is now lower than readState.Index' (duration: 246.994686ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:16.942776Z","caller":"traceutil/trace.go:171","msg":"trace[516487926] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"597.905812ms","start":"2026-04-30T11:08:16.344829Z","end":"2026-04-30T11:08:16.942735Z","steps":["trace[516487926] 'process raft request' (duration: 597.681907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.94283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0240918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:16.942894Z","caller":"traceutil/trace.go:171","msg":"trace[202322665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"1.024212963s","start":"2026-04-30T11:08:15.918663Z","end":"2026-04-30T11:08:16.942876Z","steps":["trace[202322665] 'agreement among raft nodes before linearized reading' (duration: 1.02409214s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.942939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:15.91864Z","time spent":"1.024288595s","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:08:16.942928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:16.344785Z","time spent":"598.066676ms","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:16.942986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.680529ms","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-30T11:08:16.94303Z","caller":"traceutil/trace.go:171","msg":"trace[549806478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"475.766171ms","start":"2026-04-30T11:08:16.467253Z","end":"2026-04-30T11:08:16.943019Z","steps":["trace[549806478] 'agreement among raft nodes before linearized reading' (duration: 475.628038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.943043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.831503ms","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-30T11:08:16.943115Z","caller":"traceutil/trace.go:171","msg":"trace[1891302006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"475.931215ms","start":"2026-04-30T11:08:16.467167Z","end":"2026-04-30T11:08:16.943098Z","steps":["trace[1891302006] 'agreement among raft nodes before linearized reading' (duration: 475.748061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.943158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.645236ms","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-30T11:08:16.943191Z","caller":"traceutil/trace.go:171","msg":"trace[1500811459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"477.711377ms","start":"2026-04-30T11:08:16.465472Z","end":"2026-04-30T11:08:16.943183Z","steps":["trace[1500811459] 'agreement among raft nodes before linearized reading' (duration: 477.636185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.943219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:16.465456Z","time spent":"477.757969ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:08:16.943159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:16.467157Z","time spent":"475.993846ms","remote":"127.0.0.1:36864","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-30T11:08:16.943068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:16.46723Z","time spent":"475.829453ms","remote":"127.0.0.1:36864","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-30T11:08:16.943795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.738444ms","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-30T11:08:16.943903Z","caller":"traceutil/trace.go:171","msg":"trace[809405621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"980.839117ms","start":"2026-04-30T11:08:15.963005Z","end":"2026-04-30T11:08:16.943844Z","steps":["trace[809405621] 'agreement among raft nodes before linearized reading' (duration: 979.849693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:16.943944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:15.962982Z","time spent":"980.952849ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-30T11:08:17.09701Z","caller":"traceutil/trace.go:171","msg":"trace[644708445] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"148.997806ms","start":"2026-04-30T11:08:16.947991Z","end":"2026-04-30T11:08:17.096989Z","steps":["trace[644708445] 'process raft request' (duration: 148.922505ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:17.097051Z","caller":"traceutil/trace.go:171","msg":"trace[1435407265] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"149.927378ms","start":"2026-04-30T11:08:16.947097Z","end":"2026-04-30T11:08:17.097024Z","steps":["trace[1435407265] 'process raft request' (duration: 116.20475ms)","trace[1435407265] 'compare' (duration: 33.491673ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:17.097242Z","caller":"traceutil/trace.go:171","msg":"trace[1488883582] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"149.202161ms","start":"2026-04-30T11:08:16.948027Z","end":"2026-04-30T11:08:17.097229Z","steps":["trace[1488883582] 'process raft request' (duration: 148.930715ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:17.097332Z","caller":"traceutil/trace.go:171","msg":"trace[537764481] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"149.293033ms","start":"2026-04-30T11:08:16.948028Z","end":"2026-04-30T11:08:17.097321Z","steps":["trace[537764481] 'process raft request' (duration: 149.16738ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:17.097319Z","caller":"traceutil/trace.go:171","msg":"trace[476838312] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:820; }","duration":"144.638794ms","start":"2026-04-30T11:08:16.952661Z","end":"2026-04-30T11:08:17.0973Z","steps":["trace[476838312] 'read index received' (duration: 110.613559ms)","trace[476838312] 'applied index is now lower than readState.Index' (duration: 34.024545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:08:17.097458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.776987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:17.097491Z","caller":"traceutil/trace.go:171","msg":"trace[2143888145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"144.840068ms","start":"2026-04-30T11:08:16.952644Z","end":"2026-04-30T11:08:17.097484Z","steps":["trace[2143888145] 'agreement among raft nodes before linearized reading' (duration: 144.774127ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:19.431819Z","caller":"traceutil/trace.go:171","msg":"trace[323149397] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"120.745938ms","start":"2026-04-30T11:08:19.31104Z","end":"2026-04-30T11:08:19.431786Z","steps":["trace[323149397] 'process raft request' (duration: 120.577274ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:27.617244Z","caller":"traceutil/trace.go:171","msg":"trace[1687941922] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"117.357508ms","start":"2026-04-30T11:08:27.499857Z","end":"2026-04-30T11:08:27.617214Z","steps":["trace[1687941922] 'process raft request' (duration: 117.114552ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:44.306546Z","caller":"traceutil/trace.go:171","msg":"trace[147931443] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"193.93313ms","start":"2026-04-30T11:08:44.112595Z","end":"2026-04-30T11:08:44.306528Z","steps":["trace[147931443] 'process raft request' (duration: 193.796857ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:45.952134Z","caller":"traceutil/trace.go:171","msg":"trace[1617520811] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"126.565426ms","start":"2026-04-30T11:08:45.825534Z","end":"2026-04-30T11:08:45.952099Z","steps":["trace[1617520811] 'process raft request' (duration: 71.755749ms)","trace[1617520811] 'compare' (duration: 54.583852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:08:47.472652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.509693ms","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-30T11:08:47.472738Z","caller":"traceutil/trace.go:171","msg":"trace[87071336] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:989; }","duration":"480.644797ms","start":"2026-04-30T11:08:46.992074Z","end":"2026-04-30T11:08:47.472719Z","steps":["trace[87071336] 'count revisions from in-memory index tree' (duration: 480.396041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:47.472789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:46.992059Z","time spent":"480.714238ms","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:08:47.472891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.304118ms","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-30T11:08:47.47297Z","caller":"traceutil/trace.go:171","msg":"trace[671213130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"188.462661ms","start":"2026-04-30T11:08:47.28449Z","end":"2026-04-30T11:08:47.472952Z","steps":["trace[671213130] 'range keys from in-memory index tree' (duration: 188.119663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:47.473188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240745968s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:08:47.473245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.336915ms","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-30T11:08:47.473261Z","caller":"traceutil/trace.go:171","msg":"trace[1466830878] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:989; }","duration":"1.24083848s","start":"2026-04-30T11:08:46.232411Z","end":"2026-04-30T11:08:47.47325Z","steps":["trace[1466830878] 'range keys from in-memory index tree' (duration: 1.240737408s)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:47.473285Z","caller":"traceutil/trace.go:171","msg":"trace[186282704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"130.420877ms","start":"2026-04-30T11:08:47.342852Z","end":"2026-04-30T11:08:47.473273Z","steps":["trace[186282704] 'range keys from in-memory index tree' (duration: 130.200352ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:47.676968Z","caller":"traceutil/trace.go:171","msg":"trace[2013208858] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"199.288908ms","start":"2026-04-30T11:08:47.477661Z","end":"2026-04-30T11:08:47.67695Z","steps":["trace[2013208858] 'process raft request' (duration: 199.134474ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:47.677129Z","caller":"traceutil/trace.go:171","msg":"trace[112605309] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"199.445642ms","start":"2026-04-30T11:08:47.477658Z","end":"2026-04-30T11:08:47.677104Z","steps":["trace[112605309] 'process raft request' (duration: 199.249757ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:48.051781Z","caller":"traceutil/trace.go:171","msg":"trace[384584705] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"573.924405ms","start":"2026-04-30T11:08:47.477839Z","end":"2026-04-30T11:08:48.051763Z","steps":["trace[384584705] 'process raft request' (duration: 573.871794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:48.051913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:47.47783Z","time spent":"574.018678ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:08:48.052066Z","caller":"traceutil/trace.go:171","msg":"trace[1452683683] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"574.308394ms","start":"2026-04-30T11:08:47.477726Z","end":"2026-04-30T11:08:48.052035Z","steps":["trace[1452683683] 'process raft request' (duration: 494.011483ms)","trace[1452683683] 'compare' (duration: 79.704097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:08:48.052156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:47.477699Z","time spent":"574.415477ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:08:48.119158Z","caller":"traceutil/trace.go:171","msg":"trace[2103777486] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1023; }","duration":"199.736738ms","start":"2026-04-30T11:08:47.919404Z","end":"2026-04-30T11:08:48.119141Z","steps":["trace[2103777486] 'read index received' (duration: 52.431621ms)","trace[2103777486] 'applied index is now lower than readState.Index' (duration: 147.304346ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:48.11922Z","caller":"traceutil/trace.go:171","msg":"trace[670668251] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"353.154759ms","start":"2026-04-30T11:08:47.766045Z","end":"2026-04-30T11:08:48.1192Z","steps":["trace[670668251] 'process raft request' (duration: 353.023056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:48.119341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:47.766021Z","time spent":"353.243451ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:48.119416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.647864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:08:48.119488Z","caller":"traceutil/trace.go:171","msg":"trace[125619153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"163.750366ms","start":"2026-04-30T11:08:47.955725Z","end":"2026-04-30T11:08:48.119475Z","steps":["trace[125619153] 'agreement among raft nodes before linearized reading' (duration: 163.576042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:48.119445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.855551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:08:48.119589Z","caller":"traceutil/trace.go:171","msg":"trace[1747406738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"200.225369ms","start":"2026-04-30T11:08:47.919356Z","end":"2026-04-30T11:08:48.119581Z","steps":["trace[1747406738] 'agreement among raft nodes before linearized reading' (duration: 199.873071ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:52.067414Z","caller":"traceutil/trace.go:171","msg":"trace[747548074] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"356.97723ms","start":"2026-04-30T11:08:51.710409Z","end":"2026-04-30T11:08:52.067387Z","steps":["trace[747548074] 'read index received' (duration: 356.777635ms)","trace[747548074] 'applied index is now lower than readState.Index' (duration: 198.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:52.067516Z","caller":"traceutil/trace.go:171","msg":"trace[1792498238] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"485.728508ms","start":"2026-04-30T11:08:51.581753Z","end":"2026-04-30T11:08:52.067481Z","steps":["trace[1792498238] 'process raft request' (duration: 485.443731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:52.067691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:51.581725Z","time spent":"485.873481ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:52.0677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.885977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:08:52.067731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.994948ms","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-30T11:08:52.067734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.301958ms","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-30T11:08:52.067817Z","caller":"traceutil/trace.go:171","msg":"trace[6205728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"357.43246ms","start":"2026-04-30T11:08:51.710365Z","end":"2026-04-30T11:08:52.067797Z","steps":["trace[6205728] 'agreement among raft nodes before linearized reading' (duration: 357.150704ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:52.067768Z","caller":"traceutil/trace.go:171","msg":"trace[225948809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"340.072379ms","start":"2026-04-30T11:08:51.727684Z","end":"2026-04-30T11:08:52.067757Z","steps":["trace[225948809] 'agreement among raft nodes before linearized reading' (duration: 339.929916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:52.067869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:51.710346Z","time spent":"357.505893ms","remote":"127.0.0.1:36864","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-30T11:08:52.067761Z","caller":"traceutil/trace.go:171","msg":"trace[1155138941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"149.98271ms","start":"2026-04-30T11:08:51.917751Z","end":"2026-04-30T11:08:52.067734Z","steps":["trace[1155138941] 'agreement among raft nodes before linearized reading' (duration: 149.891938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:52.067945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:51.727662Z","time spent":"340.252274ms","remote":"127.0.0.1:36864","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-30T11:08:52.383635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.264912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:08:52.383757Z","caller":"traceutil/trace.go:171","msg":"trace[1528331685] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"271.782863ms","start":"2026-04-30T11:08:52.111962Z","end":"2026-04-30T11:08:52.383745Z","steps":["trace[1528331685] 'read index received' (duration: 38.357567ms)","trace[1528331685] 'applied index is now lower than readState.Index' (duration: 233.424556ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:52.383782Z","caller":"traceutil/trace.go:171","msg":"trace[652529484] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"312.474676ms","start":"2026-04-30T11:08:52.07128Z","end":"2026-04-30T11:08:52.383754Z","steps":["trace[652529484] 'process raft request' (duration: 79.031ms)","trace[652529484] 'compare' (duration: 233.17121ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:52.383822Z","caller":"traceutil/trace.go:171","msg":"trace[757969806] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"311.155485ms","start":"2026-04-30T11:08:52.072635Z","end":"2026-04-30T11:08:52.383791Z","steps":["trace[757969806] 'process raft request' (duration: 311.072303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:52.383874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.918687ms","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-30T11:08:52.383895Z","caller":"traceutil/trace.go:171","msg":"trace[155200879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"271.981518ms","start":"2026-04-30T11:08:52.111906Z","end":"2026-04-30T11:08:52.383888Z","steps":["trace[155200879] 'agreement among raft nodes before linearized reading' (duration: 271.890556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:52.3839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:52.071263Z","time spent":"312.574159ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:52.383976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:52.072621Z","time spent":"311.277307ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:52.414606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.9457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-30T11:08:52.414674Z","caller":"traceutil/trace.go:171","msg":"trace[1452241510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"281.048692ms","start":"2026-04-30T11:08:52.133608Z","end":"2026-04-30T11:08:52.414657Z","steps":["trace[1452241510] 'agreement among raft nodes before linearized reading' (duration: 280.883489ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:52.414777Z","caller":"traceutil/trace.go:171","msg":"trace[1511849487] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"256.390429ms","start":"2026-04-30T11:08:52.158356Z","end":"2026-04-30T11:08:52.414746Z","steps":["trace[1511849487] 'process raft request' (duration: 256.02361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:52.414899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.675074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-30T11:08:52.414966Z","caller":"traceutil/trace.go:171","msg":"trace[2128999369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"182.792487ms","start":"2026-04-30T11:08:52.232164Z","end":"2026-04-30T11:08:52.414956Z","steps":["trace[2128999369] 'agreement among raft nodes before linearized reading' (duration: 182.584312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:53.89695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:08:54.349535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366739991s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:08:54.349611Z","caller":"traceutil/trace.go:171","msg":"trace[610495474] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.961997471s","start":"2026-04-30T11:08:52.387597Z","end":"2026-04-30T11:08:54.349594Z","steps":["trace[610495474] 'process raft request' (duration: 595.154778ms)","trace[610495474] 'compare' (duration: 1.366396372s)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:08:54.349664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:52.387576Z","time spent":"1.962066763s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:54.39763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:08:54.898117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:08:55.398257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:08:55.407733Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.424942768s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T11:08:55.409519Z","caller":"traceutil/trace.go:171","msg":"trace[428751404] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"2.990947075s","start":"2026-04-30T11:08:52.41855Z","end":"2026-04-30T11:08:55.409497Z","steps":["trace[428751404] 'process raft request' (duration: 2.990902484s)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:55.409812Z","caller":"traceutil/trace.go:171","msg":"trace[1967632878] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"2.991952239s","start":"2026-04-30T11:08:52.417847Z","end":"2026-04-30T11:08:55.409799Z","steps":["trace[1967632878] 'process raft request' (duration: 2.990032504s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:55.40988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:52.417835Z","time spent":"2.99200758s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:08:55.40964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:52.418534Z","time spent":"2.991037228s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:08:55.459472Z","caller":"traceutil/trace.go:171","msg":"trace[31110101] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.137074383s","start":"2026-04-30T11:08:54.322374Z","end":"2026-04-30T11:08:55.459449Z","steps":["trace[31110101] 'process raft request' (duration: 1.13691748s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:55.459612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:54.322349Z","time spent":"1.137187916s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:08:55.459615Z","caller":"traceutil/trace.go:171","msg":"trace[1459599841] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1044; }","duration":"2.063036972s","start":"2026-04-30T11:08:53.396552Z","end":"2026-04-30T11:08:55.459589Z","steps":["trace[1459599841] 'read index received' (duration: 2.011335888s)","trace[1459599841] 'applied index is now lower than readState.Index' (duration: 51.699374ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:08:55.459653Z","caller":"traceutil/trace.go:171","msg":"trace[849931553] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.040171771s","start":"2026-04-30T11:08:54.419458Z","end":"2026-04-30T11:08:55.459629Z","steps":["trace[849931553] 'process raft request' (duration: 1.039953695s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:55.459765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063213996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-30T11:08:55.459779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542349897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:08:55.459775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:54.419429Z","time spent":"1.040276953s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:08:55.459798Z","caller":"traceutil/trace.go:171","msg":"trace[1227564654] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1015; }","duration":"2.063276517s","start":"2026-04-30T11:08:53.396511Z","end":"2026-04-30T11:08:55.459788Z","steps":["trace[1227564654] 'agreement among raft nodes before linearized reading' (duration: 2.063214016s)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:08:55.459819Z","caller":"traceutil/trace.go:171","msg":"trace[726553033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"1.542430469s","start":"2026-04-30T11:08:53.917379Z","end":"2026-04-30T11:08:55.45981Z","steps":["trace[726553033] 'agreement among raft nodes before linearized reading' (duration: 1.542348357s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:55.45983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:53.396491Z","time spent":"2.063330768s","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:08:55.459845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:53.917362Z","time spent":"1.54247769s","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:08:55.459853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070301704s","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-30T11:08:55.459885Z","caller":"traceutil/trace.go:171","msg":"trace[1100796902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"1.070353854s","start":"2026-04-30T11:08:54.389522Z","end":"2026-04-30T11:08:55.459876Z","steps":["trace[1100796902] 'agreement among raft nodes before linearized reading' (duration: 1.070217191s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:55.459913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:54.389515Z","time spent":"1.070391305s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:08:55.460015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071394389s","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-30T11:08:55.460066Z","caller":"traceutil/trace.go:171","msg":"trace[1399525432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"1.071472621s","start":"2026-04-30T11:08:54.388579Z","end":"2026-04-30T11:08:55.460051Z","steps":["trace[1399525432] 'agreement among raft nodes before linearized reading' (duration: 1.071310337s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:08:55.460144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:08:54.388565Z","time spent":"1.071567003s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T11:09:14.806067Z","caller":"traceutil/trace.go:171","msg":"trace[201314802] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"373.120462ms","start":"2026-04-30T11:09:14.432916Z","end":"2026-04-30T11:09:14.806037Z","steps":["trace[201314802] 'process raft request' (duration: 372.884156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:14.806237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:14.432878Z","time spent":"373.272906ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:15.741322Z","caller":"traceutil/trace.go:171","msg":"trace[130811437] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"213.708108ms","start":"2026-04-30T11:09:15.52759Z","end":"2026-04-30T11:09:15.741298Z","steps":["trace[130811437] 'process raft request' (duration: 213.519514ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:15.761276Z","caller":"traceutil/trace.go:171","msg":"trace[67965003] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"221.512223ms","start":"2026-04-30T11:09:15.539733Z","end":"2026-04-30T11:09:15.761245Z","steps":["trace[67965003] 'process raft request' (duration: 221.36178ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:15.832595Z","caller":"traceutil/trace.go:171","msg":"trace[1524000744] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"262.284209ms","start":"2026-04-30T11:09:15.570286Z","end":"2026-04-30T11:09:15.83257Z","steps":["trace[1524000744] 'read index received' (duration: 170.816914ms)","trace[1524000744] 'applied index is now lower than readState.Index' (duration: 91.466245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:15.83278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.366971ms","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-30T11:09:15.832781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.496443ms","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-30T11:09:15.832818Z","caller":"traceutil/trace.go:171","msg":"trace[2000184996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"205.448313ms","start":"2026-04-30T11:09:15.627355Z","end":"2026-04-30T11:09:15.832804Z","steps":["trace[2000184996] 'agreement among raft nodes before linearized reading' (duration: 205.345831ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:15.832822Z","caller":"traceutil/trace.go:171","msg":"trace[1320602428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"262.584985ms","start":"2026-04-30T11:09:15.570226Z","end":"2026-04-30T11:09:15.832811Z","steps":["trace[1320602428] 'agreement among raft nodes before linearized reading' (duration: 262.423492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:15.832813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.093956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-30T11:09:15.832873Z","caller":"traceutil/trace.go:171","msg":"trace[2138802661] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1079; }","duration":"156.26599ms","start":"2026-04-30T11:09:15.676592Z","end":"2026-04-30T11:09:15.832858Z","steps":["trace[2138802661] 'agreement among raft nodes before linearized reading' (duration: 156.126686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:15.83305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.595682ms","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-30T11:09:15.833134Z","caller":"traceutil/trace.go:171","msg":"trace[1803795933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"201.719575ms","start":"2026-04-30T11:09:15.631394Z","end":"2026-04-30T11:09:15.833114Z","steps":["trace[1803795933] 'agreement among raft nodes before linearized reading' (duration: 201.381627ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:15.944648Z","caller":"traceutil/trace.go:171","msg":"trace[34856810] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"107.917943ms","start":"2026-04-30T11:09:15.83671Z","end":"2026-04-30T11:09:15.944628Z","steps":["trace[34856810] 'process raft request' (duration: 107.875752ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:15.944866Z","caller":"traceutil/trace.go:171","msg":"trace[1865896432] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"108.395135ms","start":"2026-04-30T11:09:15.836452Z","end":"2026-04-30T11:09:15.944847Z","steps":["trace[1865896432] 'process raft request' (duration: 107.102395ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:17.369898Z","caller":"traceutil/trace.go:171","msg":"trace[320908292] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"451.59722ms","start":"2026-04-30T11:09:16.918273Z","end":"2026-04-30T11:09:17.36987Z","steps":["trace[320908292] 'read index received' (duration: 451.378755ms)","trace[320908292] 'applied index is now lower than readState.Index' (duration: 217.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:17.370012Z","caller":"traceutil/trace.go:171","msg":"trace[1118819343] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"556.545754ms","start":"2026-04-30T11:09:16.813453Z","end":"2026-04-30T11:09:17.369998Z","steps":["trace[1118819343] 'process raft request' (duration: 556.289718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:17.370139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:16.813435Z","time spent":"556.609235ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:17.370285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.01905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:17.370323Z","caller":"traceutil/trace.go:171","msg":"trace[1237347668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"452.108142ms","start":"2026-04-30T11:09:16.918205Z","end":"2026-04-30T11:09:17.370313Z","steps":["trace[1237347668] 'agreement among raft nodes before linearized reading' (duration: 452.05233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:17.37035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:16.918172Z","time spent":"452.172593ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:09:17.370362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.22655ms","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-30T11:09:17.370489Z","caller":"traceutil/trace.go:171","msg":"trace[250613945] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1084; }","duration":"290.375044ms","start":"2026-04-30T11:09:17.080071Z","end":"2026-04-30T11:09:17.370446Z","steps":["trace[250613945] 'agreement among raft nodes before linearized reading' (duration: 290.246931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:18.264894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.983479ms","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-30T11:09:18.264983Z","caller":"traceutil/trace.go:171","msg":"trace[335068303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"316.112433ms","start":"2026-04-30T11:09:17.948851Z","end":"2026-04-30T11:09:18.264963Z","steps":["trace[335068303] 'range keys from in-memory index tree' (duration: 315.770274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:18.265027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:17.948836Z","time spent":"316.181554ms","remote":"127.0.0.1:36864","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-30T11:09:18.265091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.412559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:09:18.26509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.193534ms","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-30T11:09:18.265192Z","caller":"traceutil/trace.go:171","msg":"trace[603286194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"346.535112ms","start":"2026-04-30T11:09:17.918617Z","end":"2026-04-30T11:09:18.265153Z","steps":["trace[603286194] 'range keys from in-memory index tree' (duration: 346.339248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:18.265213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.166108ms","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-30T11:09:18.265238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:17.918598Z","time spent":"346.627465ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-30T11:09:18.265258Z","caller":"traceutil/trace.go:171","msg":"trace[708810513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"316.381449ms","start":"2026-04-30T11:09:17.948841Z","end":"2026-04-30T11:09:18.265222Z","steps":["trace[708810513] 'range keys from in-memory index tree' (duration: 315.965989ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:18.265276Z","caller":"traceutil/trace.go:171","msg":"trace[23827135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"388.25642ms","start":"2026-04-30T11:09:17.877001Z","end":"2026-04-30T11:09:18.265258Z","steps":["trace[23827135] 'range keys from in-memory index tree' (duration: 387.992234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:18.265327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:17.87697Z","time spent":"388.344582ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:09:18.26533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:17.948828Z","time spent":"316.482261ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T11:09:19.144711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:09:19.498149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.618135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:09:19.498358Z","caller":"traceutil/trace.go:171","msg":"trace[1974616023] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"854.117016ms","start":"2026-04-30T11:09:18.64422Z","end":"2026-04-30T11:09:19.498337Z","steps":["trace[1974616023] 'read index received' (duration: 572.02992ms)","trace[1974616023] 'applied index is now lower than readState.Index' (duration: 282.085916ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:19.498483Z","caller":"traceutil/trace.go:171","msg":"trace[1219170092] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"1.228506628s","start":"2026-04-30T11:09:18.269941Z","end":"2026-04-30T11:09:19.498447Z","steps":["trace[1219170092] 'process raft request' (duration: 946.500924ms)","trace[1219170092] 'compare' (duration: 281.178714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:19.498646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.033899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:09:19.498673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.447554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-30T11:09:19.49875Z","caller":"traceutil/trace.go:171","msg":"trace[1303715270] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1089; }","duration":"854.568327ms","start":"2026-04-30T11:09:18.644123Z","end":"2026-04-30T11:09:19.498737Z","steps":["trace[1303715270] 'agreement among raft nodes before linearized reading' (duration: 854.402693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:19.498821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:18.644102Z","time spent":"854.70628ms","remote":"127.0.0.1:36648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:09:19.498965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:18.269927Z","time spent":"1.228741164s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:19.499113Z","caller":"traceutil/trace.go:171","msg":"trace[1749183729] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.228632021s","start":"2026-04-30T11:09:18.270468Z","end":"2026-04-30T11:09:19.4991Z","steps":["trace[1749183729] 'process raft request' (duration: 1.227788731s)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:19.4987Z","caller":"traceutil/trace.go:171","msg":"trace[188221357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"580.128021ms","start":"2026-04-30T11:09:18.918557Z","end":"2026-04-30T11:09:19.498685Z","steps":["trace[188221357] 'agreement among raft nodes before linearized reading' (duration: 580.026248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:19.499211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:18.918528Z","time spent":"580.657883ms","remote":"127.0.0.1:36526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:09:19.499225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:18.270445Z","time spent":"1.228738743s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:19.499404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.598548ms","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-30T11:09:19.499463Z","caller":"traceutil/trace.go:171","msg":"trace[1889098720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"124.704261ms","start":"2026-04-30T11:09:19.374745Z","end":"2026-04-30T11:09:19.499449Z","steps":["trace[1889098720] 'agreement among raft nodes before linearized reading' (duration: 123.991414ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:19.957887Z","caller":"traceutil/trace.go:171","msg":"trace[1038512645] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"167.929766ms","start":"2026-04-30T11:09:19.789931Z","end":"2026-04-30T11:09:19.957861Z","steps":["trace[1038512645] 'read index received' (duration: 125.404519ms)","trace[1038512645] 'applied index is now lower than readState.Index' (duration: 42.523826ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:19.957953Z","caller":"traceutil/trace.go:171","msg":"trace[486306336] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"454.682563ms","start":"2026-04-30T11:09:19.503242Z","end":"2026-04-30T11:09:19.957924Z","steps":["trace[486306336] 'process raft request' (duration: 412.071525ms)","trace[486306336] 'compare' (duration: 42.25862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:19.958091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:19.503218Z","time spent":"454.796425ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:19.958125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.169131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:09:19.958179Z","caller":"traceutil/trace.go:171","msg":"trace[440708819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"168.276984ms","start":"2026-04-30T11:09:19.789887Z","end":"2026-04-30T11:09:19.958164Z","steps":["trace[440708819] 'agreement among raft nodes before linearized reading' (duration: 168.092539ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:19.967342Z","caller":"traceutil/trace.go:171","msg":"trace[1752257628] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"123.585625ms","start":"2026-04-30T11:09:19.843735Z","end":"2026-04-30T11:09:19.96732Z","steps":["trace[1752257628] 'process raft request' (duration: 122.987381ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:20.488902Z","caller":"traceutil/trace.go:171","msg":"trace[1407354439] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"144.127192ms","start":"2026-04-30T11:09:20.344744Z","end":"2026-04-30T11:09:20.488871Z","steps":["trace[1407354439] 'process raft request' (duration: 143.835285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:22.00441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:09:22.341752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.757408778s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T11:09:22.342059Z","caller":"traceutil/trace.go:171","msg":"trace[1787697052] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.757791608s","start":"2026-04-30T11:09:20.58425Z","end":"2026-04-30T11:09:22.342042Z","steps":["trace[1787697052] 'process raft request' (duration: 1.757670875s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:22.342156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:20.584228Z","time spent":"1.757860739s","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:22.355256Z","caller":"traceutil/trace.go:171","msg":"trace[1853855140] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1132; }","duration":"850.919492ms","start":"2026-04-30T11:09:21.504325Z","end":"2026-04-30T11:09:22.355245Z","steps":["trace[1853855140] 'read index received' (duration: 837.623887ms)","trace[1853855140] 'applied index is now lower than readState.Index' (duration: 13.294885ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:22.355313Z","caller":"traceutil/trace.go:171","msg":"trace[1342375603] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"382.229968ms","start":"2026-04-30T11:09:21.973058Z","end":"2026-04-30T11:09:22.355288Z","steps":["trace[1342375603] 'process raft request' (duration: 382.109915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:22.355375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.043264ms","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-30T11:09:22.355396Z","caller":"traceutil/trace.go:171","msg":"trace[963696585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"851.095186ms","start":"2026-04-30T11:09:21.504294Z","end":"2026-04-30T11:09:22.355389Z","steps":["trace[963696585] 'agreement among raft nodes before linearized reading' (duration: 851.009394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:22.355414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:21.504277Z","time spent":"851.132246ms","remote":"127.0.0.1:36864","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-30T11:09:22.355429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:21.973025Z","time spent":"382.3419ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:22.355494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.513561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-30T11:09:22.355494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.913053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-30T11:09:22.355538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.650819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:22.355534Z","caller":"traceutil/trace.go:171","msg":"trace[1643003225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"392.617493ms","start":"2026-04-30T11:09:21.962905Z","end":"2026-04-30T11:09:22.355523Z","steps":["trace[1643003225] 'agreement among raft nodes before linearized reading' (duration: 392.523841ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:22.355543Z","caller":"traceutil/trace.go:171","msg":"trace[1741863089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"352.992215ms","start":"2026-04-30T11:09:22.002541Z","end":"2026-04-30T11:09:22.355533Z","steps":["trace[1741863089] 'agreement among raft nodes before linearized reading' (duration: 352.872673ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:22.355554Z","caller":"traceutil/trace.go:171","msg":"trace[1563566265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"437.66748ms","start":"2026-04-30T11:09:21.917881Z","end":"2026-04-30T11:09:22.355549Z","steps":["trace[1563566265] 'agreement among raft nodes before linearized reading' (duration: 437.642149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:22.355569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:21.917814Z","time spent":"437.751162ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:09:22.355566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:21.962802Z","time spent":"392.756807ms","remote":"127.0.0.1:36864","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-30T11:09:22.35557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:22.002524Z","time spent":"353.038356ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-30T11:09:22.355775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.321881ms","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-30T11:09:22.355816Z","caller":"traceutil/trace.go:171","msg":"trace[1540550234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"851.406513ms","start":"2026-04-30T11:09:21.504398Z","end":"2026-04-30T11:09:22.355805Z","steps":["trace[1540550234] 'agreement among raft nodes before linearized reading' (duration: 851.002873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:22.355865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:21.504377Z","time spent":"851.480535ms","remote":"127.0.0.1:36864","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-30T11:09:22.652585Z","caller":"traceutil/trace.go:171","msg":"trace[4215652] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"157.022906ms","start":"2026-04-30T11:09:22.49551Z","end":"2026-04-30T11:09:22.652533Z","steps":["trace[4215652] 'process raft request' (duration: 156.919004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:23.643973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.508582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:23.644083Z","caller":"traceutil/trace.go:171","msg":"trace[199673545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"725.672596ms","start":"2026-04-30T11:09:22.918385Z","end":"2026-04-30T11:09:23.644057Z","steps":["trace[199673545] 'range keys from in-memory index tree' (duration: 725.39727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:23.644136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:22.918361Z","time spent":"725.764238ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-30T11:09:24.601645Z","caller":"traceutil/trace.go:171","msg":"trace[1442899705] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"188.380449ms","start":"2026-04-30T11:09:24.413245Z","end":"2026-04-30T11:09:24.601625Z","steps":["trace[1442899705] 'read index received' (duration: 188.265936ms)","trace[1442899705] 'applied index is now lower than readState.Index' (duration: 113.473µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:24.60183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.577743ms","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-30T11:09:24.601857Z","caller":"traceutil/trace.go:171","msg":"trace[281590048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"188.704186ms","start":"2026-04-30T11:09:24.413146Z","end":"2026-04-30T11:09:24.60185Z","steps":["trace[281590048] 'agreement among raft nodes before linearized reading' (duration: 188.589284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:24.601902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.258363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"warn","ts":"2026-04-30T11:09:24.60196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.541946ms","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-30T11:09:24.601984Z","caller":"traceutil/trace.go:171","msg":"trace[757253494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"179.393016ms","start":"2026-04-30T11:09:24.422572Z","end":"2026-04-30T11:09:24.601965Z","steps":["trace[757253494] 'agreement among raft nodes before linearized reading' (duration: 179.188991ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:24.602028Z","caller":"traceutil/trace.go:171","msg":"trace[90388410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"178.668659ms","start":"2026-04-30T11:09:24.423345Z","end":"2026-04-30T11:09:24.602013Z","steps":["trace[90388410] 'agreement among raft nodes before linearized reading' (duration: 178.437874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:24.602163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.868464ms","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-30T11:09:24.60236Z","caller":"traceutil/trace.go:171","msg":"trace[1071958967] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"240.744658ms","start":"2026-04-30T11:09:24.360931Z","end":"2026-04-30T11:09:24.601676Z","steps":["trace[1071958967] 'process raft request' (duration: 240.564954ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:24.602359Z","caller":"traceutil/trace.go:171","msg":"trace[227492686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"179.004557ms","start":"2026-04-30T11:09:24.423244Z","end":"2026-04-30T11:09:24.602249Z","steps":["trace[227492686] 'agreement among raft nodes before linearized reading' (duration: 178.563726ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:24.921021Z","caller":"traceutil/trace.go:171","msg":"trace[1150025149] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"312.989729ms","start":"2026-04-30T11:09:24.608008Z","end":"2026-04-30T11:09:24.920998Z","steps":["trace[1150025149] 'process raft request' (duration: 312.836605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:24.921209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:24.607978Z","time spent":"313.117322ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:24.921396Z","caller":"traceutil/trace.go:171","msg":"trace[626762923] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"313.307166ms","start":"2026-04-30T11:09:24.608032Z","end":"2026-04-30T11:09:24.921339Z","steps":["trace[626762923] 'process raft request' (duration: 312.931217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:24.921629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:24.608009Z","time spent":"313.46092ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:24.921836Z","caller":"traceutil/trace.go:171","msg":"trace[1729429084] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1142; }","duration":"265.777321ms","start":"2026-04-30T11:09:24.656038Z","end":"2026-04-30T11:09:24.921816Z","steps":["trace[1729429084] 'read index received' (duration: 264.757737ms)","trace[1729429084] 'applied index is now lower than readState.Index' (duration: 1.018554ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:24.921873Z","caller":"traceutil/trace.go:171","msg":"trace[1208398527] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"313.783577ms","start":"2026-04-30T11:09:24.608076Z","end":"2026-04-30T11:09:24.921859Z","steps":["trace[1208398527] 'process raft request' (duration: 313.172582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:24.92195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:24.608057Z","time spent":"313.858239ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:24.922331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.285433ms","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-30T11:09:24.922378Z","caller":"traceutil/trace.go:171","msg":"trace[528750563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"266.374405ms","start":"2026-04-30T11:09:24.655991Z","end":"2026-04-30T11:09:24.922365Z","steps":["trace[528750563] 'agreement among raft nodes before linearized reading' (duration: 265.946775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:25.782006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.93694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:09:25.782194Z","caller":"traceutil/trace.go:171","msg":"trace[1412948899] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"855.395857ms","start":"2026-04-30T11:09:24.926776Z","end":"2026-04-30T11:09:25.782172Z","steps":["trace[1412948899] 'process raft request' (duration: 367.202341ms)","trace[1412948899] 'compare' (duration: 487.643013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:25.78229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:24.926758Z","time spent":"855.49901ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:26.04888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.682914ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-30T11:09:26.049363Z","caller":"traceutil/trace.go:171","msg":"trace[130053880] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"290.010965ms","start":"2026-04-30T11:09:25.759317Z","end":"2026-04-30T11:09:26.049328Z","steps":["trace[130053880] 'read index received' (duration: 106.860819ms)","trace[130053880] 'applied index is now lower than readState.Index' (duration: 183.147656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:26.049848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.486325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:26.050006Z","caller":"traceutil/trace.go:171","msg":"trace[142365123] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1107; }","duration":"290.68111ms","start":"2026-04-30T11:09:25.759265Z","end":"2026-04-30T11:09:26.049947Z","steps":["trace[142365123] 'agreement among raft nodes before linearized reading' (duration: 290.144728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:37.040293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.711279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:09:37.040368Z","caller":"traceutil/trace.go:171","msg":"trace[758502774] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"344.74571ms","start":"2026-04-30T11:09:36.69561Z","end":"2026-04-30T11:09:37.040356Z","steps":["trace[758502774] 'read index received' (duration: 59.870627ms)","trace[758502774] 'applied index is now lower than readState.Index' (duration: 284.874113ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:37.040433Z","caller":"traceutil/trace.go:171","msg":"trace[617425763] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"375.15692ms","start":"2026-04-30T11:09:36.665266Z","end":"2026-04-30T11:09:37.040423Z","steps":["trace[617425763] 'process raft request' (duration: 90.246426ms)","trace[617425763] 'compare' (duration: 284.610137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:37.04047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:36.665239Z","time spent":"375.211011ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:37.040775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.16665ms","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-30T11:09:37.040807Z","caller":"traceutil/trace.go:171","msg":"trace[935753675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"345.225752ms","start":"2026-04-30T11:09:36.69557Z","end":"2026-04-30T11:09:37.040795Z","steps":["trace[935753675] 'agreement among raft nodes before linearized reading' (duration: 344.966976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:37.04107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.419425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:09:37.041246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:36.695553Z","time spent":"345.674902ms","remote":"127.0.0.1:36864","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-30T11:09:37.041277Z","caller":"traceutil/trace.go:171","msg":"trace[484071095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"124.794594ms","start":"2026-04-30T11:09:36.916358Z","end":"2026-04-30T11:09:37.041152Z","steps":["trace[484071095] 'agreement among raft nodes before linearized reading' (duration: 124.237081ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:37.440362Z","caller":"traceutil/trace.go:171","msg":"trace[275256926] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"395.940481ms","start":"2026-04-30T11:09:37.044397Z","end":"2026-04-30T11:09:37.440337Z","steps":["trace[275256926] 'process raft request' (duration: 395.751066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:37.440965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:37.044381Z","time spent":"396.510375ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:37.445537Z","caller":"traceutil/trace.go:171","msg":"trace[1584636680] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"399.947846ms","start":"2026-04-30T11:09:37.045576Z","end":"2026-04-30T11:09:37.445524Z","steps":["trace[1584636680] 'process raft request' (duration: 399.840343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:37.445651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:37.045565Z","time spent":"400.023418ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:37.445717Z","caller":"traceutil/trace.go:171","msg":"trace[1619953964] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"399.989527ms","start":"2026-04-30T11:09:37.045674Z","end":"2026-04-30T11:09:37.445663Z","steps":["trace[1619953964] 'process raft request' (duration: 399.776092ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:37.445891Z","caller":"traceutil/trace.go:171","msg":"trace[1348972050] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"401.069493ms","start":"2026-04-30T11:09:37.044681Z","end":"2026-04-30T11:09:37.44575Z","steps":["trace[1348972050] 'process raft request' (duration: 400.372517ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:37.445926Z","caller":"traceutil/trace.go:171","msg":"trace[1926313018] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1233; }","duration":"301.066946ms","start":"2026-04-30T11:09:37.144418Z","end":"2026-04-30T11:09:37.445485Z","steps":["trace[1926313018] 'read index received' (duration: 295.801481ms)","trace[1926313018] 'applied index is now lower than readState.Index' (duration: 5.264685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:37.445905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:37.045641Z","time spent":"400.155561ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:37.446003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.586248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:37.446031Z","caller":"traceutil/trace.go:171","msg":"trace[1088231974] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1193; }","duration":"301.656169ms","start":"2026-04-30T11:09:37.144366Z","end":"2026-04-30T11:09:37.446022Z","steps":["trace[1088231974] 'agreement among raft nodes before linearized reading' (duration: 301.584968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:37.446061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:37.144343Z","time spent":"301.712101ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:09:37.446027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:37.044668Z","time spent":"401.283468ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:39.639976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.102098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:09:39.640058Z","caller":"traceutil/trace.go:171","msg":"trace[2065357933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"195.230431ms","start":"2026-04-30T11:09:39.444806Z","end":"2026-04-30T11:09:39.640037Z","steps":["trace[2065357933] 'range keys from in-memory index tree' (duration: 194.923394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:39.640111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.00484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:39.640184Z","caller":"traceutil/trace.go:171","msg":"trace[426916271] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1199; }","duration":"316.126853ms","start":"2026-04-30T11:09:39.324039Z","end":"2026-04-30T11:09:39.640166Z","steps":["trace[426916271] 'count revisions from in-memory index tree' (duration: 315.930449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:39.640208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.607662ms","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-30T11:09:39.640225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:39.324022Z","time spent":"316.191904ms","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-30T11:09:39.640255Z","caller":"traceutil/trace.go:171","msg":"trace[28379528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"190.684794ms","start":"2026-04-30T11:09:39.449553Z","end":"2026-04-30T11:09:39.640238Z","steps":["trace[28379528] 'range keys from in-memory index tree' (duration: 190.301274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:39.640117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.024522ms","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-30T11:09:39.640439Z","caller":"traceutil/trace.go:171","msg":"trace[2059645043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"191.389591ms","start":"2026-04-30T11:09:39.449038Z","end":"2026-04-30T11:09:39.640428Z","steps":["trace[2059645043] 'range keys from in-memory index tree' (duration: 190.746695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:39.640539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.355282ms","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-30T11:09:39.640568Z","caller":"traceutil/trace.go:171","msg":"trace[2065646218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"189.415664ms","start":"2026-04-30T11:09:39.451143Z","end":"2026-04-30T11:09:39.640558Z","steps":["trace[2065646218] 'range keys from in-memory index tree' (duration: 189.2541ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:41.528708Z","caller":"traceutil/trace.go:171","msg":"trace[217837547] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"248.932002ms","start":"2026-04-30T11:09:41.279759Z","end":"2026-04-30T11:09:41.528691Z","steps":["trace[217837547] 'read index received' (duration: 248.740648ms)","trace[217837547] 'applied index is now lower than readState.Index' (duration: 190.644µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:41.528813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.043045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:41.528833Z","caller":"traceutil/trace.go:171","msg":"trace[798145863] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1206; }","duration":"249.113346ms","start":"2026-04-30T11:09:41.279713Z","end":"2026-04-30T11:09:41.528826Z","steps":["trace[798145863] 'agreement among raft nodes before linearized reading' (duration: 249.041445ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:41.529078Z","caller":"traceutil/trace.go:171","msg":"trace[559852876] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"425.654015ms","start":"2026-04-30T11:09:41.103418Z","end":"2026-04-30T11:09:41.529072Z","steps":["trace[559852876] 'process raft request' (duration: 425.148933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:41.529129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.103397Z","time spent":"425.696917ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:42.268743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.229428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:42.268805Z","caller":"traceutil/trace.go:171","msg":"trace[1312809887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"352.337129ms","start":"2026-04-30T11:09:41.916451Z","end":"2026-04-30T11:09:42.268788Z","steps":["trace[1312809887] 'range keys from in-memory index tree' (duration: 352.098254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.268835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.91643Z","time spent":"352.398561ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:09:42.268944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.103459ms","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-30T11:09:42.269019Z","caller":"traceutil/trace.go:171","msg":"trace[284876251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"604.221322ms","start":"2026-04-30T11:09:41.664776Z","end":"2026-04-30T11:09:42.268997Z","steps":["trace[284876251] 'range keys from in-memory index tree' (duration: 603.919805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.269014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.969418ms","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-30T11:09:42.269059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.664753Z","time spent":"604.298183ms","remote":"127.0.0.1:36864","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-30T11:09:42.269091Z","caller":"traceutil/trace.go:171","msg":"trace[1478971851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"343.091861ms","start":"2026-04-30T11:09:41.925976Z","end":"2026-04-30T11:09:42.269068Z","steps":["trace[1478971851] 'range keys from in-memory index tree' (duration: 342.792854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.269137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.925955Z","time spent":"343.172593ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T11:09:42.269143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.20313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:09:42.269185Z","caller":"traceutil/trace.go:171","msg":"trace[87503948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"601.252812ms","start":"2026-04-30T11:09:41.667908Z","end":"2026-04-30T11:09:42.269161Z","steps":["trace[87503948] 'range keys from in-memory index tree' (duration: 601.076847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.269209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.667896Z","time spent":"601.308203ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-30T11:09:42.269234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.294763ms","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-30T11:09:42.269321Z","caller":"traceutil/trace.go:171","msg":"trace[361056101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"601.466576ms","start":"2026-04-30T11:09:41.667831Z","end":"2026-04-30T11:09:42.269298Z","steps":["trace[361056101] 'range keys from in-memory index tree' (duration: 601.129669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.269374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.497587ms","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-30T11:09:42.269374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.667821Z","time spent":"601.539279ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T11:09:42.269394Z","caller":"traceutil/trace.go:171","msg":"trace[1608926273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"601.556978ms","start":"2026-04-30T11:09:41.667831Z","end":"2026-04-30T11:09:42.269388Z","steps":["trace[1608926273] 'range keys from in-memory index tree' (duration: 601.419175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.269412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.667808Z","time spent":"601.60061ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:09:42.268972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.724244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:42.269519Z","caller":"traceutil/trace.go:171","msg":"trace[968770901] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1206; }","duration":"300.289018ms","start":"2026-04-30T11:09:41.969209Z","end":"2026-04-30T11:09:42.269498Z","steps":["trace[968770901] 'count revisions from in-memory index tree' (duration: 299.642482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:42.269574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:41.969195Z","time spent":"300.360359ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:09:50.598644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.003422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:09:50.598832Z","caller":"traceutil/trace.go:171","msg":"trace[683639009] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"224.089934ms","start":"2026-04-30T11:09:50.37472Z","end":"2026-04-30T11:09:50.59881Z","steps":["trace[683639009] 'process raft request' (duration: 111.863028ms)","trace[683639009] 'compare' (duration: 111.905478ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:50.598831Z","caller":"traceutil/trace.go:171","msg":"trace[1971284237] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1280; }","duration":"196.136812ms","start":"2026-04-30T11:09:50.402684Z","end":"2026-04-30T11:09:50.598821Z","steps":["trace[1971284237] 'read index received' (duration: 19.920521ms)","trace[1971284237] 'applied index is now lower than readState.Index' (duration: 176.215641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:50.598954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.267636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T11:09:50.598929Z","caller":"traceutil/trace.go:171","msg":"trace[326308798] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"223.512391ms","start":"2026-04-30T11:09:50.375377Z","end":"2026-04-30T11:09:50.598889Z","steps":["trace[326308798] 'process raft request' (duration: 223.363237ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:50.598964Z","caller":"traceutil/trace.go:171","msg":"trace[1059512688] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"221.588476ms","start":"2026-04-30T11:09:50.377368Z","end":"2026-04-30T11:09:50.598956Z","steps":["trace[1059512688] 'process raft request' (duration: 221.412851ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:50.598981Z","caller":"traceutil/trace.go:171","msg":"trace[178363855] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:1237; }","duration":"196.325397ms","start":"2026-04-30T11:09:50.402649Z","end":"2026-04-30T11:09:50.598975Z","steps":["trace[178363855] 'agreement among raft nodes before linearized reading' (duration: 196.214564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:55.597697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.744668ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-30T11:09:56.050458Z","caller":"traceutil/trace.go:171","msg":"trace[577320891] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"106.087371ms","start":"2026-04-30T11:09:55.944354Z","end":"2026-04-30T11:09:56.050441Z","steps":["trace[577320891] 'process raft request' (duration: 105.960948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.041701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.534568ms","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-30T11:09:57.041753Z","caller":"traceutil/trace.go:171","msg":"trace[952466504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"380.593939ms","start":"2026-04-30T11:09:56.661147Z","end":"2026-04-30T11:09:57.041741Z","steps":["trace[952466504] 'range keys from in-memory index tree' (duration: 380.384254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.041775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:56.661095Z","time spent":"380.675391ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T11:09:57.041939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.275441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:09:57.04196Z","caller":"traceutil/trace.go:171","msg":"trace[1983456444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"380.315632ms","start":"2026-04-30T11:09:56.661638Z","end":"2026-04-30T11:09:57.041954Z","steps":["trace[1983456444] 'range keys from in-memory index tree' (duration: 380.21441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.041977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:56.661631Z","time spent":"380.341862ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-30T11:09:57.041953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.482702ms","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-30T11:09:57.04202Z","caller":"traceutil/trace.go:171","msg":"trace[1708025911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"646.592464ms","start":"2026-04-30T11:09:56.395411Z","end":"2026-04-30T11:09:57.042003Z","steps":["trace[1708025911] 'range keys from in-memory index tree' (duration: 646.327808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.041992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.581282ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:09:57.042088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:56.39539Z","time spent":"646.688437ms","remote":"127.0.0.1:36864","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-30T11:09:57.042108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.662446ms","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-30T11:09:57.042116Z","caller":"traceutil/trace.go:171","msg":"trace[272617364] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1252; }","duration":"809.720726ms","start":"2026-04-30T11:09:56.232372Z","end":"2026-04-30T11:09:57.042092Z","steps":["trace[272617364] 'range keys from in-memory index tree' (duration: 809.565492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.042249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.115424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:57.042271Z","caller":"traceutil/trace.go:171","msg":"trace[302475021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"123.169145ms","start":"2026-04-30T11:09:56.919096Z","end":"2026-04-30T11:09:57.042265Z","steps":["trace[302475021] 'range keys from in-memory index tree' (duration: 123.062723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.042452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.017229ms","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-30T11:09:57.042503Z","caller":"traceutil/trace.go:171","msg":"trace[239156140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"512.098152ms","start":"2026-04-30T11:09:56.530393Z","end":"2026-04-30T11:09:57.042491Z","steps":["trace[239156140] 'range keys from in-memory index tree' (duration: 511.904407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.042544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:56.530377Z","time spent":"512.150902ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T11:09:57.042131Z","caller":"traceutil/trace.go:171","msg":"trace[1679533803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"379.700888ms","start":"2026-04-30T11:09:56.662424Z","end":"2026-04-30T11:09:57.042125Z","steps":["trace[1679533803] 'range keys from in-memory index tree' (duration: 379.476803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:57.042652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:56.662418Z","time spent":"380.179359ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-30T11:09:57.268511Z","caller":"traceutil/trace.go:171","msg":"trace[1836367621] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"221.103834ms","start":"2026-04-30T11:09:57.047383Z","end":"2026-04-30T11:09:57.268487Z","steps":["trace[1836367621] 'process raft request' (duration: 194.594597ms)","trace[1836367621] 'compare' (duration: 26.389804ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:57.268771Z","caller":"traceutil/trace.go:171","msg":"trace[1320365026] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"220.692674ms","start":"2026-04-30T11:09:57.048055Z","end":"2026-04-30T11:09:57.268748Z","steps":["trace[1320365026] 'process raft request' (duration: 220.652993ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:57.268809Z","caller":"traceutil/trace.go:171","msg":"trace[479604001] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"221.162655ms","start":"2026-04-30T11:09:57.047639Z","end":"2026-04-30T11:09:57.268802Z","steps":["trace[479604001] 'process raft request' (duration: 220.825427ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:57.26888Z","caller":"traceutil/trace.go:171","msg":"trace[331671581] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"220.837067ms","start":"2026-04-30T11:09:57.048023Z","end":"2026-04-30T11:09:57.26886Z","steps":["trace[331671581] 'process raft request' (duration: 220.594531ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:57.301751Z","caller":"traceutil/trace.go:171","msg":"trace[970953698] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"154.874276ms","start":"2026-04-30T11:09:57.146856Z","end":"2026-04-30T11:09:57.30173Z","steps":["trace[970953698] 'process raft request' (duration: 154.780004ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:58.498169Z","caller":"traceutil/trace.go:171","msg":"trace[577617829] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"442.414612ms","start":"2026-04-30T11:09:58.055724Z","end":"2026-04-30T11:09:58.498139Z","steps":["trace[577617829] 'process raft request' (duration: 442.169107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:58.498312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:58.055707Z","time spent":"442.530846ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:59.38022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.834543ms","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-30T11:09:59.380293Z","caller":"traceutil/trace.go:171","msg":"trace[1638394405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"107.931225ms","start":"2026-04-30T11:09:59.272344Z","end":"2026-04-30T11:09:59.380275Z","steps":["trace[1638394405] 'range keys from in-memory index tree' (duration: 107.679269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.380579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.283904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:09:59.380617Z","caller":"traceutil/trace.go:171","msg":"trace[1267609202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"108.350175ms","start":"2026-04-30T11:09:59.272258Z","end":"2026-04-30T11:09:59.380608Z","steps":["trace[1267609202] 'range keys from in-memory index tree' (duration: 108.054098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.380709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.258793ms","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-30T11:09:59.380757Z","caller":"traceutil/trace.go:171","msg":"trace[496126629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"108.327924ms","start":"2026-04-30T11:09:59.272419Z","end":"2026-04-30T11:09:59.380747Z","steps":["trace[496126629] 'range keys from in-memory index tree' (duration: 108.15629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.380802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.320657ms","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-30T11:09:59.380865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.391979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:09:59.380901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.094639ms","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-30T11:09:59.380917Z","caller":"traceutil/trace.go:171","msg":"trace[974911882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"108.14397ms","start":"2026-04-30T11:09:59.272768Z","end":"2026-04-30T11:09:59.380912Z","steps":["trace[974911882] 'range keys from in-memory index tree' (duration: 107.970316ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:59.380954Z","caller":"traceutil/trace.go:171","msg":"trace[1892464996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"463.488821ms","start":"2026-04-30T11:09:58.917422Z","end":"2026-04-30T11:09:59.38091Z","steps":["trace[1892464996] 'range keys from in-memory index tree' (duration: 463.324597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.380994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:58.917402Z","time spent":"463.583824ms","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-30T11:09:59.381003Z","caller":"traceutil/trace.go:171","msg":"trace[1146250077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"283.543252ms","start":"2026-04-30T11:09:59.097355Z","end":"2026-04-30T11:09:59.380899Z","steps":["trace[1146250077] 'range keys from in-memory index tree' (duration: 283.02777ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:59.573784Z","caller":"traceutil/trace.go:171","msg":"trace[699820379] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"189.023914ms","start":"2026-04-30T11:09:59.384734Z","end":"2026-04-30T11:09:59.573758Z","steps":["trace[699820379] 'process raft request' (duration: 188.84666ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:59.742949Z","caller":"traceutil/trace.go:171","msg":"trace[964698318] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"358.166308ms","start":"2026-04-30T11:09:59.384759Z","end":"2026-04-30T11:09:59.742925Z","steps":["trace[964698318] 'process raft request' (duration: 357.919922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.743111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:59.384735Z","time spent":"358.28245ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:59.743332Z","caller":"traceutil/trace.go:171","msg":"trace[1232646739] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"358.369453ms","start":"2026-04-30T11:09:59.384941Z","end":"2026-04-30T11:09:59.743311Z","steps":["trace[1232646739] 'process raft request' (duration: 357.900742ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:09:59.743485Z","caller":"traceutil/trace.go:171","msg":"trace[1272499188] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"358.374843ms","start":"2026-04-30T11:09:59.385053Z","end":"2026-04-30T11:09:59.743428Z","steps":["trace[1272499188] 'process raft request' (duration: 358.157558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.743542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:59.384933Z","time spent":"358.458495ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:59.743549Z","caller":"traceutil/trace.go:171","msg":"trace[1756911156] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"358.425324ms","start":"2026-04-30T11:09:59.385112Z","end":"2026-04-30T11:09:59.743537Z","steps":["trace[1756911156] 'process raft request' (duration: 358.27106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.743619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:59.385096Z","time spent":"358.488056ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:09:59.743673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:59.385043Z","time spent":"358.537057ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:09:59.743875Z","caller":"traceutil/trace.go:171","msg":"trace[1361074999] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1308; }","duration":"220.52691ms","start":"2026-04-30T11:09:59.523326Z","end":"2026-04-30T11:09:59.743853Z","steps":["trace[1361074999] 'read index received' (duration: 50.25185ms)","trace[1361074999] 'applied index is now lower than readState.Index' (duration: 170.27411ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:59.743895Z","caller":"traceutil/trace.go:171","msg":"trace[834072106] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"321.490809ms","start":"2026-04-30T11:09:59.422373Z","end":"2026-04-30T11:09:59.743864Z","steps":["trace[834072106] 'process raft request' (duration: 321.11891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.744083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.752355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-30T11:09:59.74412Z","caller":"traceutil/trace.go:171","msg":"trace[1474238737] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1266; }","duration":"220.789136ms","start":"2026-04-30T11:09:59.523321Z","end":"2026-04-30T11:09:59.74411Z","steps":["trace[1474238737] 'agreement among raft nodes before linearized reading' (duration: 220.618982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:09:59.744202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:09:59.422356Z","time spent":"321.694365ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T11:09:59.921868Z","caller":"traceutil/trace.go:171","msg":"trace[1252278381] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"119.889168ms","start":"2026-04-30T11:09:59.801956Z","end":"2026-04-30T11:09:59.921845Z","steps":["trace[1252278381] 'read index received' (duration: 65.971532ms)","trace[1252278381] 'applied index is now lower than readState.Index' (duration: 53.916576ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:09:59.92199Z","caller":"traceutil/trace.go:171","msg":"trace[292185784] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"174.767787ms","start":"2026-04-30T11:09:59.747209Z","end":"2026-04-30T11:09:59.921977Z","steps":["trace[292185784] 'process raft request' (duration: 120.594715ms)","trace[292185784] 'compare' (duration: 53.906066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:09:59.922329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.334659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:09:59.922377Z","caller":"traceutil/trace.go:171","msg":"trace[1213967925] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1267; }","duration":"120.44083ms","start":"2026-04-30T11:09:59.801925Z","end":"2026-04-30T11:09:59.922366Z","steps":["trace[1213967925] 'agreement among raft nodes before linearized reading' (duration: 120.300017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.029515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:00.403632Z","time spent":"625.878235ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-30T11:10:01.985528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.996334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:10:01.985713Z","caller":"traceutil/trace.go:171","msg":"trace[876818160] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"955.086917ms","start":"2026-04-30T11:10:01.03061Z","end":"2026-04-30T11:10:01.985696Z","steps":["trace[876818160] 'process raft request' (duration: 955.014816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.985821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:01.030562Z","time spent":"955.22701ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:10:01.985983Z","caller":"traceutil/trace.go:171","msg":"trace[566499721] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"1.483198348s","start":"2026-04-30T11:10:00.502757Z","end":"2026-04-30T11:10:01.985955Z","steps":["trace[566499721] 'process raft request' (duration: 557.702912ms)","trace[566499721] 'compare' (duration: 924.476122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:10:01.986079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322362201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-30T11:10:01.986113Z","caller":"traceutil/trace.go:171","msg":"trace[1456644127] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1270; }","duration":"1.322421533s","start":"2026-04-30T11:10:00.663679Z","end":"2026-04-30T11:10:01.9861Z","steps":["trace[1456644127] 'agreement among raft nodes before linearized reading' (duration: 1.322352101s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.986122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:00.502735Z","time spent":"1.48330632s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:10:01.986143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:00.663664Z","time spent":"1.322471024s","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-30T11:10:01.986006Z","caller":"traceutil/trace.go:171","msg":"trace[1673572216] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"1.322280729s","start":"2026-04-30T11:10:00.663708Z","end":"2026-04-30T11:10:01.985989Z","steps":["trace[1673572216] 'read index received' (duration: 365.632725ms)","trace[1673572216] 'applied index is now lower than readState.Index' (duration: 956.646124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:10:01.986306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067400756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:01.986365Z","caller":"traceutil/trace.go:171","msg":"trace[1697570903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"1.067511579s","start":"2026-04-30T11:10:00.918838Z","end":"2026-04-30T11:10:01.986349Z","steps":["trace[1697570903] 'agreement among raft nodes before linearized reading' (duration: 1.067418777s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.9864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:00.918815Z","time spent":"1.067578221s","remote":"127.0.0.1:36530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:10:01.986407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.397833ms","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-30T11:10:01.98642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.189954ms","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-30T11:10:01.986452Z","caller":"traceutil/trace.go:171","msg":"trace[479091546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"237.302347ms","start":"2026-04-30T11:10:01.749142Z","end":"2026-04-30T11:10:01.986444Z","steps":["trace[479091546] 'agreement among raft nodes before linearized reading' (duration: 237.217575ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:01.986453Z","caller":"traceutil/trace.go:171","msg":"trace[1405599989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"238.467785ms","start":"2026-04-30T11:10:01.747966Z","end":"2026-04-30T11:10:01.986433Z","steps":["trace[1405599989] 'agreement among raft nodes before linearized reading' (duration: 238.305431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.986539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.381979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:10:01.986575Z","caller":"traceutil/trace.go:171","msg":"trace[1162538869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"237.43829ms","start":"2026-04-30T11:10:01.749126Z","end":"2026-04-30T11:10:01.986564Z","steps":["trace[1162538869] 'agreement among raft nodes before linearized reading' (duration: 237.284286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.986579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.473951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-30T11:10:01.986587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.315313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:01.986606Z","caller":"traceutil/trace.go:171","msg":"trace[158702470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"237.544843ms","start":"2026-04-30T11:10:01.749053Z","end":"2026-04-30T11:10:01.986598Z","steps":["trace[158702470] 'agreement among raft nodes before linearized reading' (duration: 237.468851ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:01.98662Z","caller":"traceutil/trace.go:171","msg":"trace[1981469644] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1270; }","duration":"402.376354ms","start":"2026-04-30T11:10:01.584235Z","end":"2026-04-30T11:10:01.986611Z","steps":["trace[1981469644] 'agreement among raft nodes before linearized reading' (duration: 402.324543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.986649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:01.584219Z","time spent":"402.422976ms","remote":"127.0.0.1:36680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:10:01.986693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.738521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:10:01.986708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.414035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-30T11:10:01.98672Z","caller":"traceutil/trace.go:171","msg":"trace[213872387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1270; }","duration":"802.789262ms","start":"2026-04-30T11:10:01.183922Z","end":"2026-04-30T11:10:01.986711Z","steps":["trace[213872387] 'agreement among raft nodes before linearized reading' (duration: 802.746181ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:01.986738Z","caller":"traceutil/trace.go:171","msg":"trace[2122570942] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1270; }","duration":"481.481657ms","start":"2026-04-30T11:10:01.505248Z","end":"2026-04-30T11:10:01.986729Z","steps":["trace[2122570942] 'agreement among raft nodes before linearized reading' (duration: 481.428896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.98675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:01.183909Z","time spent":"802.832453ms","remote":"127.0.0.1:57520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:10:01.986766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:01.505228Z","time spent":"481.530357ms","remote":"127.0.0.1:57726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:10:01.986948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.601895ms","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-30T11:10:01.986959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.766042ms","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-30T11:10:01.986972Z","caller":"traceutil/trace.go:171","msg":"trace[1697953351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"409.654706ms","start":"2026-04-30T11:10:01.57731Z","end":"2026-04-30T11:10:01.986964Z","steps":["trace[1697953351] 'agreement among raft nodes before linearized reading' (duration: 409.582315ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:01.98699Z","caller":"traceutil/trace.go:171","msg":"trace[665140044] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1270; }","duration":"418.836104ms","start":"2026-04-30T11:10:01.568145Z","end":"2026-04-30T11:10:01.986981Z","steps":["trace[665140044] 'agreement among raft nodes before linearized reading' (duration: 418.769242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:01.986997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:01.577289Z","time spent":"409.703198ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T11:10:01.98702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:01.568123Z","time spent":"418.889425ms","remote":"127.0.0.1:57560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-30T11:10:02.164403Z","caller":"traceutil/trace.go:171","msg":"trace[1838658505] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"173.509987ms","start":"2026-04-30T11:10:01.990868Z","end":"2026-04-30T11:10:02.164378Z","steps":["trace[1838658505] 'process raft request' (duration: 173.396884ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:02.164436Z","caller":"traceutil/trace.go:171","msg":"trace[788992226] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"173.534627ms","start":"2026-04-30T11:10:01.990861Z","end":"2026-04-30T11:10:02.164396Z","steps":["trace[788992226] 'process raft request' (duration: 173.24027ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:02.164541Z","caller":"traceutil/trace.go:171","msg":"trace[273160185] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"173.573319ms","start":"2026-04-30T11:10:01.990956Z","end":"2026-04-30T11:10:02.16453Z","steps":["trace[273160185] 'process raft request' (duration: 173.388764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:02.164737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.15127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:02.16477Z","caller":"traceutil/trace.go:171","msg":"trace[2042391196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"168.220872ms","start":"2026-04-30T11:10:01.996541Z","end":"2026-04-30T11:10:02.164762Z","steps":["trace[2042391196] 'agreement among raft nodes before linearized reading' (duration: 168.14053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:02.164861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.860259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:02.164946Z","caller":"traceutil/trace.go:171","msg":"trace[1564360675] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1275; }","duration":"115.052224ms","start":"2026-04-30T11:10:02.049877Z","end":"2026-04-30T11:10:02.164929Z","steps":["trace[1564360675] 'agreement among raft nodes before linearized reading' (duration: 114.803768ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:02.164974Z","caller":"traceutil/trace.go:171","msg":"trace[2145371449] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"172.188056ms","start":"2026-04-30T11:10:01.992779Z","end":"2026-04-30T11:10:02.164967Z","steps":["trace[2145371449] 'process raft request' (duration: 171.706674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:10.904805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231979,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-30T11:10:11.058504Z","caller":"traceutil/trace.go:171","msg":"trace[100092252] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"814.127678ms","start":"2026-04-30T11:10:10.244353Z","end":"2026-04-30T11:10:11.058481Z","steps":["trace[100092252] 'process raft request' (duration: 814.037316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:11.05865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:10.244336Z","time spent":"814.241922ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:10:11.058832Z","caller":"traceutil/trace.go:171","msg":"trace[1675082742] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"814.134229ms","start":"2026-04-30T11:10:10.244685Z","end":"2026-04-30T11:10:11.058819Z","steps":["trace[1675082742] 'process raft request' (duration: 813.759781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:11.058912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:10.24467Z","time spent":"814.19805ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:10:11.059218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.04954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T11:10:11.059268Z","caller":"traceutil/trace.go:171","msg":"trace[447211463] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:1358; }","duration":"655.128142ms","start":"2026-04-30T11:10:10.404119Z","end":"2026-04-30T11:10:11.059247Z","steps":["trace[447211463] 'agreement among raft nodes before linearized reading' (duration: 655.004659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:11.059307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:10.404096Z","time spent":"655.201154ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"info","ts":"2026-04-30T11:10:11.058555Z","caller":"traceutil/trace.go:171","msg":"trace[1787333007] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"814.560959ms","start":"2026-04-30T11:10:10.243973Z","end":"2026-04-30T11:10:11.058534Z","steps":["trace[1787333007] 'process raft request' (duration: 814.273192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:11.059607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:10.243932Z","time spent":"815.640514ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:10:11.058773Z","caller":"traceutil/trace.go:171","msg":"trace[621970690] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1405; }","duration":"654.58665ms","start":"2026-04-30T11:10:10.404173Z","end":"2026-04-30T11:10:11.05876Z","steps":["trace[621970690] 'read index received' (duration: 654.014677ms)","trace[621970690] 'applied index is now lower than readState.Index' (duration: 570.583µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:10:11.060357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.884328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T11:10:11.060375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.662318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:11.060418Z","caller":"traceutil/trace.go:171","msg":"trace[1513953839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"143.736099ms","start":"2026-04-30T11:10:10.916671Z","end":"2026-04-30T11:10:11.060407Z","steps":["trace[1513953839] 'agreement among raft nodes before linearized reading' (duration: 143.671338ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:11.060422Z","caller":"traceutil/trace.go:171","msg":"trace[1293194654] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1358; }","duration":"605.002701ms","start":"2026-04-30T11:10:10.455397Z","end":"2026-04-30T11:10:11.0604Z","steps":["trace[1293194654] 'agreement among raft nodes before linearized reading' (duration: 604.892759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:11.060464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:10.455373Z","time spent":"605.080292ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-30T11:10:11.562671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:10:12.063777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199345701231985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T11:10:12.168946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:11.060328Z","time spent":"1.108611642s","remote":"127.0.0.1:36548","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-30T11:10:12.184714Z","caller":"traceutil/trace.go:171","msg":"trace[1618881927] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1408; }","duration":"1.123012509s","start":"2026-04-30T11:10:11.061667Z","end":"2026-04-30T11:10:12.184679Z","steps":["trace[1618881927] 'read index received' (duration: 1.042669151s)","trace[1618881927] 'applied index is now lower than readState.Index' (duration: 80.342468ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:10:12.184758Z","caller":"traceutil/trace.go:171","msg":"trace[655960426] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"160.363048ms","start":"2026-04-30T11:10:12.024376Z","end":"2026-04-30T11:10:12.184739Z","steps":["trace[655960426] 'process raft request' (duration: 160.182214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.185117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123417988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:12.185215Z","caller":"traceutil/trace.go:171","msg":"trace[991787176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1359; }","duration":"1.123571702s","start":"2026-04-30T11:10:11.061625Z","end":"2026-04-30T11:10:12.185197Z","steps":["trace[991787176] 'agreement among raft nodes before linearized reading' (duration: 1.123150812s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.18526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:11.061611Z","time spent":"1.123640684s","remote":"127.0.0.1:36526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T11:10:12.2582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.102533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:10:12.25829Z","caller":"traceutil/trace.go:171","msg":"trace[1687500698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"175.224236ms","start":"2026-04-30T11:10:12.083036Z","end":"2026-04-30T11:10:12.25826Z","steps":["trace[1687500698] 'agreement among raft nodes before linearized reading' (duration: 175.084942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.258376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.374978ms","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-30T11:10:12.258454Z","caller":"traceutil/trace.go:171","msg":"trace[1406212657] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"877.512621ms","start":"2026-04-30T11:10:11.380924Z","end":"2026-04-30T11:10:12.258436Z","steps":["trace[1406212657] 'agreement among raft nodes before linearized reading' (duration: 877.283105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.258499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:11.380906Z","time spent":"877.584632ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-30T11:10:12.258705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.995381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-30T11:10:12.258779Z","caller":"traceutil/trace.go:171","msg":"trace[1002541507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1360; }","duration":"119.107024ms","start":"2026-04-30T11:10:12.139655Z","end":"2026-04-30T11:10:12.258762Z","steps":["trace[1002541507] 'agreement among raft nodes before linearized reading' (duration: 118.875129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.258724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.302577ms","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":"warn","ts":"2026-04-30T11:10:12.259117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005067442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-30T11:10:12.259163Z","caller":"traceutil/trace.go:171","msg":"trace[1717449168] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1360; }","duration":"1.005234845s","start":"2026-04-30T11:10:11.253917Z","end":"2026-04-30T11:10:12.259151Z","steps":["trace[1717449168] 'agreement among raft nodes before linearized reading' (duration: 1.00499837s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.259195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:11.253899Z","time spent":"1.005286827s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-30T11:10:12.259134Z","caller":"traceutil/trace.go:171","msg":"trace[1352382905] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1360; }","duration":"839.747858ms","start":"2026-04-30T11:10:11.419367Z","end":"2026-04-30T11:10:12.259115Z","steps":["trace[1352382905] 'agreement among raft nodes before linearized reading' (duration: 838.98754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.259515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:10:11.41935Z","time spent":"840.079655ms","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-30T11:10:12.364844Z","caller":"traceutil/trace.go:171","msg":"trace[1795986046] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"100.261443ms","start":"2026-04-30T11:10:12.264566Z","end":"2026-04-30T11:10:12.364827Z","steps":["trace[1795986046] 'process raft request' (duration: 98.350539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:12.364958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.027798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-30T11:10:12.365078Z","caller":"traceutil/trace.go:171","msg":"trace[94211934] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1363; }","duration":"100.166961ms","start":"2026-04-30T11:10:12.264895Z","end":"2026-04-30T11:10:12.365062Z","steps":["trace[94211934] 'agreement among raft nodes before linearized reading' (duration: 100.011197ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:14.017219Z","caller":"traceutil/trace.go:171","msg":"trace[515001672] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"271.123367ms","start":"2026-04-30T11:10:13.746032Z","end":"2026-04-30T11:10:14.017155Z","steps":["trace[515001672] 'process raft request' (duration: 270.867241ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:30.655627Z","caller":"traceutil/trace.go:171","msg":"trace[1837982146] linearizableReadLoop","detail":"{readStateIndex:1500; appliedIndex:1499; }","duration":"203.793504ms","start":"2026-04-30T11:10:30.451814Z","end":"2026-04-30T11:10:30.655607Z","steps":["trace[1837982146] 'read index received' (duration: 203.62801ms)","trace[1837982146] 'applied index is now lower than readState.Index' (duration: 164.654µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:10:30.655788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.415005ms","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-30T11:10:30.655817Z","caller":"traceutil/trace.go:171","msg":"trace[852421947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1445; }","duration":"203.474226ms","start":"2026-04-30T11:10:30.452335Z","end":"2026-04-30T11:10:30.655809Z","steps":["trace[852421947] 'agreement among raft nodes before linearized reading' (duration: 203.367223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:30.655813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.984588ms","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:531"} {"level":"info","ts":"2026-04-30T11:10:30.655881Z","caller":"traceutil/trace.go:171","msg":"trace[1765840884] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1445; }","duration":"204.05891ms","start":"2026-04-30T11:10:30.451806Z","end":"2026-04-30T11:10:30.655865Z","steps":["trace[1765840884] 'agreement among raft nodes before linearized reading' (duration: 203.870946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:30.656146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.553145ms","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-30T11:10:30.656225Z","caller":"traceutil/trace.go:171","msg":"trace[412493414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1445; }","duration":"199.677107ms","start":"2026-04-30T11:10:30.45653Z","end":"2026-04-30T11:10:30.656207Z","steps":["trace[412493414] 'agreement among raft nodes before linearized reading' (duration: 199.430161ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:36.934881Z","caller":"traceutil/trace.go:171","msg":"trace[717881110] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"197.892166ms","start":"2026-04-30T11:10:36.736942Z","end":"2026-04-30T11:10:36.934834Z","steps":["trace[717881110] 'process raft request' (duration: 187.098554ms)","trace[717881110] 'compare' (duration: 10.505115ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:10:36.935252Z","caller":"traceutil/trace.go:171","msg":"trace[1117788781] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"197.008945ms","start":"2026-04-30T11:10:36.738179Z","end":"2026-04-30T11:10:36.935188Z","steps":["trace[1117788781] 'process raft request' (duration: 196.541094ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:10:40.560072Z","caller":"traceutil/trace.go:171","msg":"trace[553534974] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"154.990783ms","start":"2026-04-30T11:10:40.40506Z","end":"2026-04-30T11:10:40.560051Z","steps":["trace[553534974] 'read index received' (duration: 154.792128ms)","trace[553534974] 'applied index is now lower than readState.Index' (duration: 197.745µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:10:40.560172Z","caller":"traceutil/trace.go:171","msg":"trace[767373833] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"181.088572ms","start":"2026-04-30T11:10:40.379052Z","end":"2026-04-30T11:10:40.56014Z","steps":["trace[767373833] 'process raft request' (duration: 180.864537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:40.560236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.00869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-30T11:10:40.56031Z","caller":"traceutil/trace.go:171","msg":"trace[148698622] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1491; }","duration":"154.122123ms","start":"2026-04-30T11:10:40.406174Z","end":"2026-04-30T11:10:40.560263Z","steps":["trace[148698622] 'agreement among raft nodes before linearized reading' (duration: 154.00792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:10:40.560362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.27606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T11:10:40.560423Z","caller":"traceutil/trace.go:171","msg":"trace[2092828091] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:1491; }","duration":"155.378021ms","start":"2026-04-30T11:10:40.405031Z","end":"2026-04-30T11:10:40.560409Z","steps":["trace[2092828091] 'agreement among raft nodes before linearized reading' (duration: 155.133026ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:11:02.990886Z","caller":"traceutil/trace.go:171","msg":"trace[1611604662] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"121.244544ms","start":"2026-04-30T11:11:02.869615Z","end":"2026-04-30T11:11:02.99086Z","steps":["trace[1611604662] 'process raft request' (duration: 121.07511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:11:07.583911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.140441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:11:07.584124Z","caller":"traceutil/trace.go:171","msg":"trace[1332049112] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"146.043683ms","start":"2026-04-30T11:11:07.438046Z","end":"2026-04-30T11:11:07.58409Z","steps":["trace[1332049112] 'process raft request' (duration: 39.539654ms)","trace[1332049112] 'compare' (duration: 106.009838ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:11:07.808785Z","caller":"traceutil/trace.go:171","msg":"trace[854148532] linearizableReadLoop","detail":"{readStateIndex:1678; appliedIndex:1676; }","duration":"179.762612ms","start":"2026-04-30T11:11:07.628973Z","end":"2026-04-30T11:11:07.808735Z","steps":["trace[854148532] 'read index received' (duration: 1.483364ms)","trace[854148532] 'applied index is now lower than readState.Index' (duration: 178.277628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:11:07.809034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.041109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-30T11:11:07.808995Z","caller":"traceutil/trace.go:171","msg":"trace[877763322] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"219.305826ms","start":"2026-04-30T11:11:07.589596Z","end":"2026-04-30T11:11:07.808901Z","steps":["trace[877763322] 'process raft request' (duration: 172.596264ms)","trace[877763322] 'compare' (duration: 46.270471ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:11:07.809106Z","caller":"traceutil/trace.go:171","msg":"trace[33503560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1616; }","duration":"180.167972ms","start":"2026-04-30T11:11:07.628919Z","end":"2026-04-30T11:11:07.809087Z","steps":["trace[33503560] 'agreement among raft nodes before linearized reading' (duration: 179.946847ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:11:09.555905Z","caller":"traceutil/trace.go:171","msg":"trace[1721354484] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"109.811507ms","start":"2026-04-30T11:11:09.44607Z","end":"2026-04-30T11:11:09.555882Z","steps":["trace[1721354484] 'process raft request' (duration: 103.672566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:11:12.0851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.334428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:11:12.085356Z","caller":"traceutil/trace.go:171","msg":"trace[753141541] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"159.114177ms","start":"2026-04-30T11:11:11.926214Z","end":"2026-04-30T11:11:12.085328Z","steps":["trace[753141541] 'compare' (duration: 154.223785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:11:25.702579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.578641ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-30T11:11:30.489402Z","caller":"traceutil/trace.go:171","msg":"trace[18164186] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"165.015012ms","start":"2026-04-30T11:11:30.324358Z","end":"2026-04-30T11:11:30.489373Z","steps":["trace[18164186] 'process raft request' (duration: 164.862959ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:11:30.719448Z","caller":"traceutil/trace.go:171","msg":"trace[445665357] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"127.417441ms","start":"2026-04-30T11:11:30.59199Z","end":"2026-04-30T11:11:30.719408Z","steps":["trace[445665357] 'process raft request' (duration: 104.533926ms)","trace[445665357] 'compare' (duration: 22.69364ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:11:38.111217Z","caller":"traceutil/trace.go:171","msg":"trace[2137621059] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1818; }","duration":"195.170573ms","start":"2026-04-30T11:11:37.916025Z","end":"2026-04-30T11:11:38.111195Z","steps":["trace[2137621059] 'read index received' (duration: 58.803447ms)","trace[2137621059] 'applied index is now lower than readState.Index' (duration: 136.366166ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:11:38.111329Z","caller":"traceutil/trace.go:171","msg":"trace[172599764] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"235.397066ms","start":"2026-04-30T11:11:37.875921Z","end":"2026-04-30T11:11:38.111319Z","steps":["trace[172599764] 'process raft request' (duration: 235.234492ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:11:38.111312Z","caller":"traceutil/trace.go:171","msg":"trace[57738122] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"235.404716ms","start":"2026-04-30T11:11:37.875879Z","end":"2026-04-30T11:11:38.111284Z","steps":["trace[57738122] 'process raft request' (duration: 235.12099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:11:38.111417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.784235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-30T11:11:38.111447Z","caller":"traceutil/trace.go:171","msg":"trace[337707568] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1753; }","duration":"112.863417ms","start":"2026-04-30T11:11:37.998575Z","end":"2026-04-30T11:11:38.111439Z","steps":["trace[337707568] 'agreement among raft nodes before linearized reading' (duration: 112.795525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:11:38.111508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.480651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:11:38.111565Z","caller":"traceutil/trace.go:171","msg":"trace[1766213381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1753; }","duration":"195.566812ms","start":"2026-04-30T11:11:37.915982Z","end":"2026-04-30T11:11:38.111549Z","steps":["trace[1766213381] 'agreement among raft nodes before linearized reading' (duration: 195.48788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:12:06.772863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.819019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T11:12:06.773123Z","caller":"traceutil/trace.go:171","msg":"trace[2058789410] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"391.358631ms","start":"2026-04-30T11:12:06.38174Z","end":"2026-04-30T11:12:06.773099Z","steps":["trace[2058789410] 'process raft request' (duration: 126.187463ms)","trace[2058789410] 'compare' (duration: 264.710387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:12:06.773266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:12:06.381721Z","time spent":"391.454912ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:12:06.773474Z","caller":"traceutil/trace.go:171","msg":"trace[458577008] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"391.172956ms","start":"2026-04-30T11:12:06.382283Z","end":"2026-04-30T11:12:06.773455Z","steps":["trace[458577008] 'process raft request' (duration: 390.693065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:12:06.77357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:12:06.382267Z","time spent":"391.250238ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T11:12:06.952376Z","caller":"traceutil/trace.go:171","msg":"trace[1668671138] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"174.459499ms","start":"2026-04-30T11:12:06.777898Z","end":"2026-04-30T11:12:06.952357Z","steps":["trace[1668671138] 'process raft request' (duration: 136.757264ms)","trace[1668671138] 'compare' (duration: 37.324746ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:12:17.186639Z","caller":"traceutil/trace.go:171","msg":"trace[1919375703] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"178.166234ms","start":"2026-04-30T11:12:17.00845Z","end":"2026-04-30T11:12:17.186616Z","steps":["trace[1919375703] 'process raft request' (duration: 178.0185ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:12:17.387215Z","caller":"traceutil/trace.go:171","msg":"trace[824891037] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"260.436239ms","start":"2026-04-30T11:12:17.126723Z","end":"2026-04-30T11:12:17.38716Z","steps":["trace[824891037] 'process raft request' (duration: 239.066827ms)","trace[824891037] 'compare' (duration: 21.27238ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:13:29.118679Z","caller":"traceutil/trace.go:171","msg":"trace[564521180] linearizableReadLoop","detail":"{readStateIndex:2379; appliedIndex:2378; }","duration":"344.947983ms","start":"2026-04-30T11:13:28.773707Z","end":"2026-04-30T11:13:29.118655Z","steps":["trace[564521180] 'read index received' (duration: 344.735708ms)","trace[564521180] 'applied index is now lower than readState.Index' (duration: 211.475µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:13:29.118792Z","caller":"traceutil/trace.go:171","msg":"trace[978220328] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"562.08774ms","start":"2026-04-30T11:13:28.556695Z","end":"2026-04-30T11:13:29.118783Z","steps":["trace[978220328] 'process raft request' (duration: 561.818114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:13:29.118946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:13:28.55667Z","time spent":"562.146342ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:13:29.119274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.559007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-30T11:13:29.120323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.964849ms","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-30T11:13:29.120458Z","caller":"traceutil/trace.go:171","msg":"trace[1872228699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2288; }","duration":"228.898281ms","start":"2026-04-30T11:13:28.891511Z","end":"2026-04-30T11:13:29.120409Z","steps":["trace[1872228699] 'agreement among raft nodes before linearized reading' (duration: 227.716894ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:13:29.121023Z","caller":"traceutil/trace.go:171","msg":"trace[763062112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2288; }","duration":"346.615762ms","start":"2026-04-30T11:13:28.773663Z","end":"2026-04-30T11:13:29.120279Z","steps":["trace[763062112] 'agreement among raft nodes before linearized reading' (duration: 345.500646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:13:29.121093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:13:28.773644Z","time spent":"347.431522ms","remote":"127.0.0.1:36864","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-30T11:13:34.319637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.102ms","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-30T11:13:34.319728Z","caller":"traceutil/trace.go:171","msg":"trace[247687005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2321; }","duration":"142.255063ms","start":"2026-04-30T11:13:34.177453Z","end":"2026-04-30T11:13:34.319708Z","steps":["trace[247687005] 'range keys from in-memory index tree' (duration: 141.926785ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:13:35.128015Z","caller":"traceutil/trace.go:171","msg":"trace[325363629] linearizableReadLoop","detail":"{readStateIndex:2416; appliedIndex:2415; }","duration":"209.776306ms","start":"2026-04-30T11:13:34.918213Z","end":"2026-04-30T11:13:35.127989Z","steps":["trace[325363629] 'read index received' (duration: 209.608262ms)","trace[325363629] 'applied index is now lower than readState.Index' (duration: 167.134µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:13:35.128049Z","caller":"traceutil/trace.go:171","msg":"trace[872949462] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"330.058357ms","start":"2026-04-30T11:13:34.797968Z","end":"2026-04-30T11:13:35.128026Z","steps":["trace[872949462] 'process raft request' (duration: 329.848462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:13:35.128172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T11:13:34.79795Z","time spent":"330.153339ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T11:13:35.12827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.040692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T11:13:35.128317Z","caller":"traceutil/trace.go:171","msg":"trace[1606347555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2323; }","duration":"210.144904ms","start":"2026-04-30T11:13:34.918161Z","end":"2026-04-30T11:13:35.128306Z","steps":["trace[1606347555] 'agreement among raft nodes before linearized reading' (duration: 210.004651ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:13:40.711606Z","caller":"traceutil/trace.go:171","msg":"trace[528015795] linearizableReadLoop","detail":"{readStateIndex:2446; appliedIndex:2444; }","duration":"183.550764ms","start":"2026-04-30T11:13:40.528029Z","end":"2026-04-30T11:13:40.711579Z","steps":["trace[528015795] 'read index received' (duration: 13.800611ms)","trace[528015795] 'applied index is now lower than readState.Index' (duration: 169.748973ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:13:40.711727Z","caller":"traceutil/trace.go:171","msg":"trace[395052922] transaction","detail":"{read_only:false; response_revision:2351; number_of_response:1; }","duration":"213.686207ms","start":"2026-04-30T11:13:40.498007Z","end":"2026-04-30T11:13:40.711694Z","steps":["trace[395052922] 'process raft request' (duration: 192.072293ms)","trace[395052922] 'compare' (duration: 21.342708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T11:13:40.71184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.76878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-30T11:13:40.711881Z","caller":"traceutil/trace.go:171","msg":"trace[1746971117] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:2351; }","duration":"183.871712ms","start":"2026-04-30T11:13:40.527995Z","end":"2026-04-30T11:13:40.711867Z","steps":["trace[1746971117] 'agreement among raft nodes before linearized reading' (duration: 183.731889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:13:40.711925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.065483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-04-30T11:13:40.711982Z","caller":"traceutil/trace.go:171","msg":"trace[1788553113] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:2351; }","duration":"134.166684ms","start":"2026-04-30T11:13:40.577802Z","end":"2026-04-30T11:13:40.711969Z","steps":["trace[1788553113] 'agreement among raft nodes before linearized reading' (duration: 134.046922ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T11:13:45.332758Z","caller":"traceutil/trace.go:171","msg":"trace[1951368857] linearizableReadLoop","detail":"{readStateIndex:2464; appliedIndex:2463; }","duration":"175.274982ms","start":"2026-04-30T11:13:45.157465Z","end":"2026-04-30T11:13:45.33274Z","steps":["trace[1951368857] 'read index received' (duration: 175.109018ms)","trace[1951368857] 'applied index is now lower than readState.Index' (duration: 165.384µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T11:13:45.332803Z","caller":"traceutil/trace.go:171","msg":"trace[616058130] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"186.904263ms","start":"2026-04-30T11:13:45.145878Z","end":"2026-04-30T11:13:45.332782Z","steps":["trace[616058130] 'process raft request' (duration: 186.741039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:13:45.332898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.413115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-04-30T11:13:45.332935Z","caller":"traceutil/trace.go:171","msg":"trace[1764243615] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:2369; }","duration":"175.481637ms","start":"2026-04-30T11:13:45.157442Z","end":"2026-04-30T11:13:45.332923Z","steps":["trace[1764243615] 'agreement among raft nodes before linearized reading' (duration: 175.393685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T11:13:45.333001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.393701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-04-30T11:13:45.333037Z","caller":"traceutil/trace.go:171","msg":"trace[1360635643] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:1; response_revision:2369; }","duration":"113.458393ms","start":"2026-04-30T11:13:45.219566Z","end":"2026-04-30T11:13:45.333025Z","steps":["trace[1360635643] 'agreement among raft nodes before linearized reading' (duration: 113.392281ms)"],"step_count":1}