{"level":"warn","ts":"2026-05-29T05:46:20.038318Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:46:20.038424Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-29T05:46:20.038496Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T05:46:20.038517Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-29T05:46:20.038539Z","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-05-29T05:46:20.039135Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-29T05:46:20.039198Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-29T05:46:20.048359Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.981147ms"} {"level":"info","ts":"2026-05-29T05:46:20.073228Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-29T05:46:20.073372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:46:20.073423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-29T05:46:20.073449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:46:20.073467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-29T05:46:20.073555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-29T05:46:20.095377Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:46:20.100598Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:46:20.102115Z","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-05-29T05:46:20.108338Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:46:20.108577Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:46:20.109203Z","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-05-29T05:46:20.109328Z","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-05-29T05:46:20.109347Z","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-05-29T05:46:20.110486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-29T05:46:20.110659Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-29T05:46:20.111671Z","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-05-29T05:46:20.111787Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-29T05:46:20.111848Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-29T05:46:20.111867Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:46:20.111902Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:46:20.875686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:46:20.875774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:46:20.875812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-29T05:46:20.875845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:46:20.875863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-29T05:46:20.875882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-29T05:46:20.875899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-29T05:46:20.885892Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:20.925918Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:46:20.926113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:46:20.926294Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:46:20.926365Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:46:20.926153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:46:20.930491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-29T05:46:20.931847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:46:21.011957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:21.013186Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:21.013371Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:46:22.403286Z","caller":"traceutil/trace.go:171","msg":"trace[1175760327] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"155.5692ms","start":"2026-05-29T05:46:22.247673Z","end":"2026-05-29T05:46:22.403242Z","steps":["trace[1175760327] 'process raft request' (duration: 155.181054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:22.403627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.323306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:22.403708Z","caller":"traceutil/trace.go:171","msg":"trace[2011599573] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"155.492379ms","start":"2026-05-29T05:46:22.248205Z","end":"2026-05-29T05:46:22.403697Z","steps":["trace[2011599573] 'agreement among raft nodes before linearized reading' (duration: 155.308406ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.403461Z","caller":"traceutil/trace.go:171","msg":"trace[2025945476] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:6; }","duration":"155.187064ms","start":"2026-05-29T05:46:22.248251Z","end":"2026-05-29T05:46:22.403438Z","steps":["trace[2025945476] 'read index received' (duration: 35.039157ms)","trace[2025945476] 'applied index is now lower than readState.Index' (duration: 120.147007ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:22.403832Z","caller":"traceutil/trace.go:171","msg":"trace[482194205] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"156.078028ms","start":"2026-05-29T05:46:22.247742Z","end":"2026-05-29T05:46:22.40382Z","steps":["trace[482194205] 'process raft request' (duration: 155.548029ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.403994Z","caller":"traceutil/trace.go:171","msg":"trace[1191483604] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"156.259312ms","start":"2026-05-29T05:46:22.247719Z","end":"2026-05-29T05:46:22.403979Z","steps":["trace[1191483604] 'process raft request' (duration: 155.423748ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.404046Z","caller":"traceutil/trace.go:171","msg":"trace[910976782] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"155.794834ms","start":"2026-05-29T05:46:22.248235Z","end":"2026-05-29T05:46:22.40403Z","steps":["trace[910976782] 'process raft request' (duration: 155.110502ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.404136Z","caller":"traceutil/trace.go:171","msg":"trace[1872796274] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"155.934976ms","start":"2026-05-29T05:46:22.248076Z","end":"2026-05-29T05:46:22.40401Z","steps":["trace[1872796274] 'process raft request' (duration: 155.247095ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.404187Z","caller":"traceutil/trace.go:171","msg":"trace[541209166] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"143.152062ms","start":"2026-05-29T05:46:22.261016Z","end":"2026-05-29T05:46:22.404168Z","steps":["trace[541209166] 'process raft request' (duration: 142.376748ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.405786Z","caller":"traceutil/trace.go:171","msg":"trace[741969821] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"119.76285ms","start":"2026-05-29T05:46:22.285933Z","end":"2026-05-29T05:46:22.405696Z","steps":["trace[741969821] 'process raft request' (duration: 119.646448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:22.40589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.060014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T05:46:22.405805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.291935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:22.405953Z","caller":"traceutil/trace.go:171","msg":"trace[1306689614] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"108.140725ms","start":"2026-05-29T05:46:22.297788Z","end":"2026-05-29T05:46:22.405928Z","steps":["trace[1306689614] 'agreement among raft nodes before linearized reading' (duration: 108.043593ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.40606Z","caller":"traceutil/trace.go:171","msg":"trace[1222042276] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"155.517209ms","start":"2026-05-29T05:46:22.250456Z","end":"2026-05-29T05:46:22.405973Z","steps":["trace[1222042276] 'agreement among raft nodes before linearized reading' (duration: 155.210244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:22.406414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.961997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:22.406458Z","caller":"traceutil/trace.go:171","msg":"trace[1015174645] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"114.028697ms","start":"2026-05-29T05:46:22.292418Z","end":"2026-05-29T05:46:22.406447Z","steps":["trace[1015174645] 'agreement among raft nodes before linearized reading' (duration: 113.963136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:22.66567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.842769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T05:46:22.665883Z","caller":"traceutil/trace.go:171","msg":"trace[2076489243] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"206.895363ms","start":"2026-05-29T05:46:22.458955Z","end":"2026-05-29T05:46:22.66585Z","steps":["trace[2076489243] 'process raft request' (duration: 85.131218ms)","trace[2076489243] 'compare' (duration: 120.687536ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:22.666189Z","caller":"traceutil/trace.go:171","msg":"trace[1462464951] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"206.373355ms","start":"2026-05-29T05:46:22.459765Z","end":"2026-05-29T05:46:22.666139Z","steps":["trace[1462464951] 'process raft request' (duration: 205.993738ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.666257Z","caller":"traceutil/trace.go:171","msg":"trace[529985688] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"204.681064ms","start":"2026-05-29T05:46:22.461568Z","end":"2026-05-29T05:46:22.666249Z","steps":["trace[529985688] 'process raft request' (duration: 204.40442ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.666282Z","caller":"traceutil/trace.go:171","msg":"trace[673938023] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"204.718195ms","start":"2026-05-29T05:46:22.461514Z","end":"2026-05-29T05:46:22.666232Z","steps":["trace[673938023] 'process raft request' (duration: 204.43221ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.666214Z","caller":"traceutil/trace.go:171","msg":"trace[925953861] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"174.730397ms","start":"2026-05-29T05:46:22.491465Z","end":"2026-05-29T05:46:22.666195Z","steps":["trace[925953861] 'process raft request' (duration: 174.680787ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.666618Z","caller":"traceutil/trace.go:171","msg":"trace[1784015135] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"205.134083ms","start":"2026-05-29T05:46:22.461473Z","end":"2026-05-29T05:46:22.666607Z","steps":["trace[1784015135] 'process raft request' (duration: 204.38052ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.66669Z","caller":"traceutil/trace.go:171","msg":"trace[1767419657] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"204.99852ms","start":"2026-05-29T05:46:22.46168Z","end":"2026-05-29T05:46:22.666679Z","steps":["trace[1767419657] 'process raft request' (duration: 204.320808ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.666763Z","caller":"traceutil/trace.go:171","msg":"trace[1380404093] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"205.304956ms","start":"2026-05-29T05:46:22.461448Z","end":"2026-05-29T05:46:22.666753Z","steps":["trace[1380404093] 'process raft request' (duration: 204.375849ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.666625Z","caller":"traceutil/trace.go:171","msg":"trace[1598766149] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"205.110311ms","start":"2026-05-29T05:46:22.461503Z","end":"2026-05-29T05:46:22.666614Z","steps":["trace[1598766149] 'process raft request' (duration: 204.373388ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.667181Z","caller":"traceutil/trace.go:171","msg":"trace[1231591952] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"202.980615ms","start":"2026-05-29T05:46:22.46419Z","end":"2026-05-29T05:46:22.667171Z","steps":["trace[1231591952] 'process raft request' (duration: 201.840836ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:22.667306Z","caller":"traceutil/trace.go:171","msg":"trace[319964354] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:24; }","duration":"205.775953ms","start":"2026-05-29T05:46:22.46152Z","end":"2026-05-29T05:46:22.667296Z","steps":["trace[319964354] 'read index received' (duration: 28.223517ms)","trace[319964354] 'applied index is now lower than readState.Index' (duration: 177.551436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:22.667489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.946137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:22.667562Z","caller":"traceutil/trace.go:171","msg":"trace[445480460] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:31; }","duration":"206.019408ms","start":"2026-05-29T05:46:22.461499Z","end":"2026-05-29T05:46:22.667518Z","steps":["trace[445480460] 'agreement among raft nodes before linearized reading' (duration: 205.896176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:22.667698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.172531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:22.667734Z","caller":"traceutil/trace.go:171","msg":"trace[1141993114] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:31; }","duration":"206.222671ms","start":"2026-05-29T05:46:22.4615Z","end":"2026-05-29T05:46:22.667723Z","steps":["trace[1141993114] 'agreement among raft nodes before linearized reading' (duration: 206.17522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:22.667878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.033338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:22.667915Z","caller":"traceutil/trace.go:171","msg":"trace[1684815747] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:31; }","duration":"181.092729ms","start":"2026-05-29T05:46:22.486811Z","end":"2026-05-29T05:46:22.667904Z","steps":["trace[1684815747] 'agreement among raft nodes before linearized reading' (duration: 181.045739ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:23.04176Z","caller":"traceutil/trace.go:171","msg":"trace[2119573696] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"369.585519ms","start":"2026-05-29T05:46:22.672146Z","end":"2026-05-29T05:46:23.041732Z","steps":["trace[2119573696] 'process raft request' (duration: 349.986334ms)","trace[2119573696] 'compare' (duration: 19.473913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:23.041902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.672126Z","time spent":"369.707071ms","remote":"127.0.0.1:38828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:46:23.080686Z","caller":"traceutil/trace.go:171","msg":"trace[766345025] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:35; }","duration":"356.393807ms","start":"2026-05-29T05:46:22.724277Z","end":"2026-05-29T05:46:23.080671Z","steps":["trace[766345025] 'read index received' (duration: 297.993549ms)","trace[766345025] 'applied index is now lower than readState.Index' (duration: 58.399568ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:23.081287Z","caller":"traceutil/trace.go:171","msg":"trace[1399894109] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"408.732269ms","start":"2026-05-29T05:46:22.67238Z","end":"2026-05-29T05:46:23.081112Z","steps":["trace[1399894109] 'process raft request' (duration: 408.19078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:23.081379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.672356Z","time spent":"408.981702ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:46:23.080838Z","caller":"traceutil/trace.go:171","msg":"trace[472573825] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"408.462644ms","start":"2026-05-29T05:46:22.672368Z","end":"2026-05-29T05:46:23.080831Z","steps":["trace[472573825] 'process raft request' (duration: 408.127608ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:23.081544Z","caller":"traceutil/trace.go:171","msg":"trace[21546498] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"409.091725ms","start":"2026-05-29T05:46:22.672441Z","end":"2026-05-29T05:46:23.081533Z","steps":["trace[21546498] 'process raft request' (duration: 408.146779ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:23.081587Z","caller":"traceutil/trace.go:171","msg":"trace[632285201] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"408.227159ms","start":"2026-05-29T05:46:22.673341Z","end":"2026-05-29T05:46:23.081568Z","steps":["trace[632285201] 'process raft request' (duration: 407.302344ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:23.081638Z","caller":"traceutil/trace.go:171","msg":"trace[435202122] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"408.415292ms","start":"2026-05-29T05:46:22.673208Z","end":"2026-05-29T05:46:23.081623Z","steps":["trace[435202122] 'process raft request' (duration: 407.414715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:23.081685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.673196Z","time spent":"408.471223ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:46:23.081715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.673317Z","time spent":"408.327701ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:46:23.081764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.672348Z","time spent":"409.249957ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:46:23.081597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.672434Z","time spent":"409.139645ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:46:23.081504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.202361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:46:23.081992Z","caller":"traceutil/trace.go:171","msg":"trace[159236053] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:37; }","duration":"357.750311ms","start":"2026-05-29T05:46:22.724224Z","end":"2026-05-29T05:46:23.081974Z","steps":["trace[159236053] 'agreement among raft nodes before linearized reading' (duration: 357.107799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:23.082062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:22.724206Z","time spent":"357.827252ms","remote":"127.0.0.1:38828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-05-29T05:46:23.220717Z","caller":"traceutil/trace.go:171","msg":"trace[1918343347] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"110.72619ms","start":"2026-05-29T05:46:23.109964Z","end":"2026-05-29T05:46:23.22069Z","steps":["trace[1918343347] 'process raft request' (duration: 53.571333ms)","trace[1918343347] 'compare' (duration: 56.932293ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:23.220871Z","caller":"traceutil/trace.go:171","msg":"trace[633950139] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"110.792412ms","start":"2026-05-29T05:46:23.110042Z","end":"2026-05-29T05:46:23.220835Z","steps":["trace[633950139] 'process raft request' (duration: 110.568418ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:23.22103Z","caller":"traceutil/trace.go:171","msg":"trace[1147812433] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"110.499946ms","start":"2026-05-29T05:46:23.110495Z","end":"2026-05-29T05:46:23.220995Z","steps":["trace[1147812433] 'process raft request' (duration: 110.179521ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:23.513987Z","caller":"traceutil/trace.go:171","msg":"trace[1791205298] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"138.812825ms","start":"2026-05-29T05:46:23.375153Z","end":"2026-05-29T05:46:23.513966Z","steps":["trace[1791205298] 'read index received' (duration: 70.648555ms)","trace[1791205298] 'applied index is now lower than readState.Index' (duration: 68.16323ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:23.514024Z","caller":"traceutil/trace.go:171","msg":"trace[1185862559] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"163.651032ms","start":"2026-05-29T05:46:23.350349Z","end":"2026-05-29T05:46:23.514Z","steps":["trace[1185862559] 'process raft request' (duration: 95.536623ms)","trace[1185862559] 'compare' (duration: 67.950687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:23.514177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.012788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b3f31b8a93fc44\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-05-29T05:46:23.514217Z","caller":"traceutil/trace.go:171","msg":"trace[981843773] range","detail":"{range_begin:/registry/events/default/instance.18b3f31b8a93fc44; range_end:; response_count:1; response_revision:99; }","duration":"139.103069ms","start":"2026-05-29T05:46:23.375103Z","end":"2026-05-29T05:46:23.514206Z","steps":["trace[981843773] 'agreement among raft nodes before linearized reading' (duration: 138.956226ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:35.675178Z","caller":"traceutil/trace.go:171","msg":"trace[369383501] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"382.811552ms","start":"2026-05-29T05:46:35.292343Z","end":"2026-05-29T05:46:35.675154Z","steps":["trace[369383501] 'process raft request' (duration: 382.625409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:35.67555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:35.292332Z","time spent":"382.932654ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:36.796733Z","caller":"traceutil/trace.go:171","msg":"trace[1634984741] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"1.237559315s","start":"2026-05-29T05:46:35.559148Z","end":"2026-05-29T05:46:36.796707Z","steps":["trace[1634984741] 'read index received' (duration: 115.82048ms)","trace[1634984741] 'applied index is now lower than readState.Index' (duration: 1.121737585s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:36.796818Z","caller":"traceutil/trace.go:171","msg":"trace[418433368] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.502556413s","start":"2026-05-29T05:46:35.294227Z","end":"2026-05-29T05:46:36.796784Z","steps":["trace[418433368] 'process raft request' (duration: 1.499289135s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.796888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237727369s","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-05-29T05:46:36.796923Z","caller":"traceutil/trace.go:171","msg":"trace[70048620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"1.23781143s","start":"2026-05-29T05:46:35.559102Z","end":"2026-05-29T05:46:36.796913Z","steps":["trace[70048620] 'agreement among raft nodes before linearized reading' (duration: 1.237728548s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.796963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:35.559083Z","time spent":"1.237871862s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:46:36.796959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:35.29421Z","time spent":"1.502675124s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:36.797672Z","caller":"traceutil/trace.go:171","msg":"trace[1599569422] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"712.933957ms","start":"2026-05-29T05:46:36.084696Z","end":"2026-05-29T05:46:36.79763Z","steps":["trace[1599569422] 'process raft request' (duration: 712.724712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.797749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.37145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-29T05:46:36.797842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:36.084677Z","time spent":"713.083639ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:36.797879Z","caller":"traceutil/trace.go:171","msg":"trace[1750815086] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:409; }","duration":"970.560693ms","start":"2026-05-29T05:46:35.82729Z","end":"2026-05-29T05:46:36.79785Z","steps":["trace[1750815086] 'agreement among raft nodes before linearized reading' (duration: 970.320299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.797939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:35.82727Z","time spent":"970.659145ms","remote":"127.0.0.1:38878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-29T05:46:36.798166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.567306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:46:36.798243Z","caller":"traceutil/trace.go:171","msg":"trace[1689595010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"836.049324ms","start":"2026-05-29T05:46:35.962171Z","end":"2026-05-29T05:46:36.79822Z","steps":["trace[1689595010] 'agreement among raft nodes before linearized reading' (duration: 835.569116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:36.798284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:35.962149Z","time spent":"836.123126ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:46:41.914652Z","caller":"traceutil/trace.go:171","msg":"trace[1623706196] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"210.906164ms","start":"2026-05-29T05:46:41.7037Z","end":"2026-05-29T05:46:41.914606Z","steps":["trace[1623706196] 'process raft request' (duration: 210.768451ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:47.406164Z","caller":"traceutil/trace.go:171","msg":"trace[729415922] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"471.372762ms","start":"2026-05-29T05:46:46.934757Z","end":"2026-05-29T05:46:47.40613Z","steps":["trace[729415922] 'read index received' (duration: 471.243439ms)","trace[729415922] 'applied index is now lower than readState.Index' (duration: 127.833µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:46:47.406327Z","caller":"traceutil/trace.go:171","msg":"trace[1018627398] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"546.813571ms","start":"2026-05-29T05:46:46.859501Z","end":"2026-05-29T05:46:47.406314Z","steps":["trace[1018627398] 'process raft request' (duration: 546.421413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.406501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:46.85948Z","time spent":"546.884333ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:46:47.406522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.734449ms","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-05-29T05:46:47.406679Z","caller":"traceutil/trace.go:171","msg":"trace[749191767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:435; }","duration":"471.929551ms","start":"2026-05-29T05:46:46.934724Z","end":"2026-05-29T05:46:47.406654Z","steps":["trace[749191767] 'agreement among raft nodes before linearized reading' (duration: 471.718138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.406754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:46.934707Z","time spent":"472.035374ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:46:47.748007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.778083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:46:47.748163Z","caller":"traceutil/trace.go:171","msg":"trace[1396175567] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"338.540682ms","start":"2026-05-29T05:46:47.409608Z","end":"2026-05-29T05:46:47.748148Z","steps":["trace[1396175567] 'process raft request' (duration: 338.479461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.748214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.409577Z","time spent":"338.608753ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:47.748382Z","caller":"traceutil/trace.go:171","msg":"trace[305263059] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"342.099774ms","start":"2026-05-29T05:46:47.406273Z","end":"2026-05-29T05:46:47.748373Z","steps":["trace[305263059] 'read index received' (duration: 235.919935ms)","trace[305263059] 'applied index is now lower than readState.Index' (duration: 106.178869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:47.748435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.130604ms","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-05-29T05:46:47.74849Z","caller":"traceutil/trace.go:171","msg":"trace[290339844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:437; }","duration":"799.210476ms","start":"2026-05-29T05:46:46.94927Z","end":"2026-05-29T05:46:47.748481Z","steps":["trace[290339844] 'agreement among raft nodes before linearized reading' (duration: 799.134975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:47.748515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:46.949252Z","time spent":"799.256716ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:46:47.748528Z","caller":"traceutil/trace.go:171","msg":"trace[488487533] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"718.514884ms","start":"2026-05-29T05:46:47.029899Z","end":"2026-05-29T05:46:47.748414Z","steps":["trace[488487533] 'process raft request' (duration: 612.272294ms)","trace[488487533] 'compare' (duration: 105.701411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:46:47.748824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:47.029871Z","time spent":"718.85518ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:46:50.382749Z","caller":"traceutil/trace.go:171","msg":"trace[382405532] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"118.645939ms","start":"2026-05-29T05:46:50.264082Z","end":"2026-05-29T05:46:50.382727Z","steps":["trace[382405532] 'process raft request' (duration: 118.533757ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:46:50.751672Z","caller":"traceutil/trace.go:171","msg":"trace[226332592] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"362.466603ms","start":"2026-05-29T05:46:50.389181Z","end":"2026-05-29T05:46:50.751647Z","steps":["trace[226332592] 'process raft request' (duration: 362.080157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:46:50.751783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:46:50.389163Z","time spent":"362.568466ms","remote":"127.0.0.1:39138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:06.45819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.554171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:06.458473Z","caller":"traceutil/trace.go:171","msg":"trace[1532283565] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"419.63466ms","start":"2026-05-29T05:47:06.038815Z","end":"2026-05-29T05:47:06.45845Z","steps":["trace[1532283565] 'read index received' (duration: 205.60627ms)","trace[1532283565] 'applied index is now lower than readState.Index' (duration: 214.02596ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:06.458654Z","caller":"traceutil/trace.go:171","msg":"trace[1186387198] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"422.781256ms","start":"2026-05-29T05:47:06.035795Z","end":"2026-05-29T05:47:06.458576Z","steps":["trace[1186387198] 'process raft request' (duration: 208.715916ms)","trace[1186387198] 'compare' (duration: 213.420439ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:06.458896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.066748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-05-29T05:47:06.459132Z","caller":"traceutil/trace.go:171","msg":"trace[1478117740] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:642; }","duration":"420.321592ms","start":"2026-05-29T05:47:06.03879Z","end":"2026-05-29T05:47:06.459111Z","steps":["trace[1478117740] 'agreement among raft nodes before linearized reading' (duration: 419.841694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:06.459278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.038776Z","time spent":"420.481935ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":98736,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-29T05:47:06.458944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.035782Z","time spent":"422.994919ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:06.734982Z","caller":"traceutil/trace.go:171","msg":"trace[1559357897] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"672.070006ms","start":"2026-05-29T05:47:06.062887Z","end":"2026-05-29T05:47:06.734957Z","steps":["trace[1559357897] 'process raft request' (duration: 672.018295ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:06.73508Z","caller":"traceutil/trace.go:171","msg":"trace[1713611124] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"694.128903ms","start":"2026-05-29T05:47:06.040891Z","end":"2026-05-29T05:47:06.73502Z","steps":["trace[1713611124] 'process raft request' (duration: 686.376517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:06.735147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.062869Z","time spent":"672.196759ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:06.735374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.040858Z","time spent":"694.323388ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:06.797037Z","caller":"traceutil/trace.go:171","msg":"trace[532237827] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"317.299149ms","start":"2026-05-29T05:47:06.479697Z","end":"2026-05-29T05:47:06.796996Z","steps":["trace[532237827] 'read index received' (duration: 247.583651ms)","trace[532237827] 'applied index is now lower than readState.Index' (duration: 69.714668ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:06.797163Z","caller":"traceutil/trace.go:171","msg":"trace[1391649545] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"335.460189ms","start":"2026-05-29T05:47:06.461678Z","end":"2026-05-29T05:47:06.797138Z","steps":["trace[1391649545] 'process raft request' (duration: 335.202865ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:06.797211Z","caller":"traceutil/trace.go:171","msg":"trace[1850722987] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"131.469645ms","start":"2026-05-29T05:47:06.665728Z","end":"2026-05-29T05:47:06.797198Z","steps":["trace[1850722987] 'process raft request' (duration: 131.228421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:06.797335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.46165Z","time spent":"335.570221ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:47:06.797381Z","caller":"traceutil/trace.go:171","msg":"trace[83733429] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"533.618748ms","start":"2026-05-29T05:47:06.263753Z","end":"2026-05-29T05:47:06.797372Z","steps":["trace[83733429] 'process raft request' (duration: 532.963357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:06.797427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.714057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-05-29T05:47:06.797484Z","caller":"traceutil/trace.go:171","msg":"trace[1445584021] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:647; }","duration":"317.827728ms","start":"2026-05-29T05:47:06.479641Z","end":"2026-05-29T05:47:06.797469Z","steps":["trace[1445584021] 'agreement among raft nodes before linearized reading' (duration: 317.602144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:06.797501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.263731Z","time spent":"533.700239ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:47:06.797527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:06.479621Z","time spent":"317.8975ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65459,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-29T05:47:06.951282Z","caller":"traceutil/trace.go:171","msg":"trace[653141808] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"154.102184ms","start":"2026-05-29T05:47:06.797165Z","end":"2026-05-29T05:47:06.951267Z","steps":["trace[653141808] 'read index received' (duration: 153.90339ms)","trace[653141808] 'applied index is now lower than readState.Index' (duration: 197.954µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:06.951479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.912904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:06.951707Z","caller":"traceutil/trace.go:171","msg":"trace[1884035498] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"193.074771ms","start":"2026-05-29T05:47:06.758616Z","end":"2026-05-29T05:47:06.951691Z","steps":["trace[1884035498] 'process raft request' (duration: 192.515471ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:06.951754Z","caller":"traceutil/trace.go:171","msg":"trace[1107183075] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"181.315103ms","start":"2026-05-29T05:47:06.770413Z","end":"2026-05-29T05:47:06.951728Z","steps":["trace[1107183075] 'process raft request' (duration: 180.815845ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:06.951575Z","caller":"traceutil/trace.go:171","msg":"trace[1809346635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"186.134558ms","start":"2026-05-29T05:47:06.76542Z","end":"2026-05-29T05:47:06.951554Z","steps":["trace[1809346635] 'agreement among raft nodes before linearized reading' (duration: 185.966276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:06.951862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.577738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-05-29T05:47:06.952116Z","caller":"traceutil/trace.go:171","msg":"trace[165931758] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:649; }","duration":"136.081638ms","start":"2026-05-29T05:47:06.816026Z","end":"2026-05-29T05:47:06.952107Z","steps":["trace[165931758] 'agreement among raft nodes before linearized reading' (duration: 135.513067ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:12.410283Z","caller":"traceutil/trace.go:171","msg":"trace[1284938767] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"207.932512ms","start":"2026-05-29T05:47:12.202329Z","end":"2026-05-29T05:47:12.410262Z","steps":["trace[1284938767] 'process raft request' (duration: 206.198012ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:13.207672Z","caller":"traceutil/trace.go:171","msg":"trace[1214933583] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"113.152982ms","start":"2026-05-29T05:47:13.094485Z","end":"2026-05-29T05:47:13.207638Z","steps":["trace[1214933583] 'process raft request' (duration: 112.916798ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:13.365768Z","caller":"traceutil/trace.go:171","msg":"trace[144481045] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"151.891614ms","start":"2026-05-29T05:47:13.213845Z","end":"2026-05-29T05:47:13.365737Z","steps":["trace[144481045] 'process raft request' (duration: 109.528628ms)","trace[144481045] 'compare' (duration: 42.245194ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:13.883563Z","caller":"traceutil/trace.go:171","msg":"trace[40004076] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"280.009301ms","start":"2026-05-29T05:47:13.603533Z","end":"2026-05-29T05:47:13.883543Z","steps":["trace[40004076] 'process raft request' (duration: 279.878519ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:13.965514Z","caller":"traceutil/trace.go:171","msg":"trace[895234690] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:732; }","duration":"197.939777ms","start":"2026-05-29T05:47:13.767551Z","end":"2026-05-29T05:47:13.965491Z","steps":["trace[895234690] 'read index received' (duration: 115.923292ms)","trace[895234690] 'applied index is now lower than readState.Index' (duration: 82.015375ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:13.965639Z","caller":"traceutil/trace.go:171","msg":"trace[2099341318] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"331.452397ms","start":"2026-05-29T05:47:13.634175Z","end":"2026-05-29T05:47:13.965628Z","steps":["trace[2099341318] 'process raft request' (duration: 292.562962ms)","trace[2099341318] 'compare' (duration: 38.633001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:13.965755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:13.63415Z","time spent":"331.520078ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:13.965975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.419485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:13.966012Z","caller":"traceutil/trace.go:171","msg":"trace[2144816640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"198.495366ms","start":"2026-05-29T05:47:13.767507Z","end":"2026-05-29T05:47:13.966002Z","steps":["trace[2144816640] 'agreement among raft nodes before linearized reading' (duration: 198.437785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:15.021919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215190236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:47:15.215932Z","caller":"traceutil/trace.go:171","msg":"trace[72582288] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"695.010442ms","start":"2026-05-29T05:47:14.520863Z","end":"2026-05-29T05:47:15.215873Z","steps":["trace[72582288] 'read index received' (duration: 694.749097ms)","trace[72582288] 'applied index is now lower than readState.Index' (duration: 259.865µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:15.215953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:14.255899Z","time spent":"960.046539ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T05:47:15.216144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.265276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:47:15.216188Z","caller":"traceutil/trace.go:171","msg":"trace[1892135015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"695.365617ms","start":"2026-05-29T05:47:14.520812Z","end":"2026-05-29T05:47:15.216178Z","steps":["trace[1892135015] 'agreement among raft nodes before linearized reading' (duration: 695.196234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:15.216228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:14.520793Z","time spent":"695.427339ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:47:15.871188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215190239,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:47:16.014125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.352466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:16.014368Z","caller":"traceutil/trace.go:171","msg":"trace[799990201] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:737; }","duration":"643.484682ms","start":"2026-05-29T05:47:15.370865Z","end":"2026-05-29T05:47:16.01435Z","steps":["trace[799990201] 'read index received' (duration: 606.419789ms)","trace[799990201] 'applied index is now lower than readState.Index' (duration: 37.063903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:16.014599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.643773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:16.014645Z","caller":"traceutil/trace.go:171","msg":"trace[1549252649] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"793.834879ms","start":"2026-05-29T05:47:15.220636Z","end":"2026-05-29T05:47:16.014471Z","steps":["trace[1549252649] 'process raft request' (duration: 793.609796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:16.014666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.385325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:47:16.014678Z","caller":"traceutil/trace.go:171","msg":"trace[829481911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"246.777316ms","start":"2026-05-29T05:47:15.76788Z","end":"2026-05-29T05:47:16.014657Z","steps":["trace[829481911] 'agreement among raft nodes before linearized reading' (duration: 246.637023ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:16.014669Z","caller":"traceutil/trace.go:171","msg":"trace[1853403713] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"797.046296ms","start":"2026-05-29T05:47:15.217572Z","end":"2026-05-29T05:47:16.014619Z","steps":["trace[1853403713] 'process raft request' (duration: 101.061499ms)","trace[1853403713] 'compare' (duration: 695.169813ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:47:16.01472Z","caller":"traceutil/trace.go:171","msg":"trace[1417770511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"126.491327ms","start":"2026-05-29T05:47:15.888214Z","end":"2026-05-29T05:47:16.014706Z","steps":["trace[1417770511] 'agreement among raft nodes before linearized reading' (duration: 126.328795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:16.014812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.94791ms","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":"warn","ts":"2026-05-29T05:47:16.014803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:15.22062Z","time spent":"794.100495ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:47:16.014847Z","caller":"traceutil/trace.go:171","msg":"trace[625920064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:713; }","duration":"644.020232ms","start":"2026-05-29T05:47:15.370817Z","end":"2026-05-29T05:47:16.014837Z","steps":["trace[625920064] 'agreement among raft nodes before linearized reading' (duration: 643.637445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:16.014852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:15.217549Z","time spent":"797.203379ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:16.014878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:15.370798Z","time spent":"644.071612ms","remote":"127.0.0.1:38952","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":"info","ts":"2026-05-29T05:47:31.959279Z","caller":"traceutil/trace.go:171","msg":"trace[528958890] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"354.220498ms","start":"2026-05-29T05:47:31.605033Z","end":"2026-05-29T05:47:31.959253Z","steps":["trace[528958890] 'read index received' (duration: 354.049535ms)","trace[528958890] 'applied index is now lower than readState.Index' (duration: 169.893µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:31.959777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.717907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:47:31.959836Z","caller":"traceutil/trace.go:171","msg":"trace[1622517102] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:824; }","duration":"354.830169ms","start":"2026-05-29T05:47:31.604993Z","end":"2026-05-29T05:47:31.959823Z","steps":["trace[1622517102] 'agreement among raft nodes before linearized reading' (duration: 354.676046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:31.959875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:31.60497Z","time spent":"354.894621ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:47:31.959847Z","caller":"traceutil/trace.go:171","msg":"trace[1392339788] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"764.304955ms","start":"2026-05-29T05:47:31.19551Z","end":"2026-05-29T05:47:31.959815Z","steps":["trace[1392339788] 'process raft request' (duration: 763.601682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:31.96Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:31.195492Z","time spent":"764.434198ms","remote":"127.0.0.1:38878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:47:31.95983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.803574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:47:31.960099Z","caller":"traceutil/trace.go:171","msg":"trace[479268946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"192.09729ms","start":"2026-05-29T05:47:31.76797Z","end":"2026-05-29T05:47:31.960067Z","steps":["trace[479268946] 'agreement among raft nodes before linearized reading' (duration: 191.816864ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:42.488648Z","caller":"traceutil/trace.go:171","msg":"trace[973718759] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"243.64474ms","start":"2026-05-29T05:47:42.244978Z","end":"2026-05-29T05:47:42.488623Z","steps":["trace[973718759] 'process raft request' (duration: 243.599449ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:42.488754Z","caller":"traceutil/trace.go:171","msg":"trace[1910056413] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"246.856888ms","start":"2026-05-29T05:47:42.241834Z","end":"2026-05-29T05:47:42.488691Z","steps":["trace[1910056413] 'process raft request' (duration: 246.675085ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:42.488648Z","caller":"traceutil/trace.go:171","msg":"trace[1623368134] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"246.860549ms","start":"2026-05-29T05:47:42.241756Z","end":"2026-05-29T05:47:42.488617Z","steps":["trace[1623368134] 'process raft request' (duration: 246.594064ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:47:42.488741Z","caller":"traceutil/trace.go:171","msg":"trace[1821888808] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"243.940885ms","start":"2026-05-29T05:47:42.244789Z","end":"2026-05-29T05:47:42.48873Z","steps":["trace[1821888808] 'process raft request' (duration: 243.758212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:47:42.719113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.514466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:47:42.71922Z","caller":"traceutil/trace.go:171","msg":"trace[1695968791] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"390.332634ms","start":"2026-05-29T05:47:42.328868Z","end":"2026-05-29T05:47:42.7192Z","steps":["trace[1695968791] 'process raft request' (duration: 285.649695ms)","trace[1695968791] 'compare' (duration: 104.406834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:47:42.719282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:47:42.32885Z","time spent":"390.402005ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:00.726019Z","caller":"traceutil/trace.go:171","msg":"trace[1524948509] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"140.040621ms","start":"2026-05-29T05:48:00.585958Z","end":"2026-05-29T05:48:00.725999Z","steps":["trace[1524948509] 'process raft request' (duration: 139.291257ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:00.726102Z","caller":"traceutil/trace.go:171","msg":"trace[955088006] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"138.91103ms","start":"2026-05-29T05:48:00.587171Z","end":"2026-05-29T05:48:00.726082Z","steps":["trace[955088006] 'process raft request' (duration: 138.799768ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:00.730001Z","caller":"traceutil/trace.go:171","msg":"trace[522667656] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"126.513622ms","start":"2026-05-29T05:48:00.603476Z","end":"2026-05-29T05:48:00.72999Z","steps":["trace[522667656] 'process raft request' (duration: 126.442071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:07.11854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215190989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:07.618796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215190989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:08.119292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215190989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:08.619782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215190989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:08.710644Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.328057773s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:48:08.711083Z","caller":"traceutil/trace.go:171","msg":"trace[1300200135] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"2.328569823s","start":"2026-05-29T05:48:06.382487Z","end":"2026-05-29T05:48:08.711056Z","steps":["trace[1300200135] 'process raft request' (duration: 2.32839595s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.71125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:06.382462Z","time spent":"2.328698325s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:08.712874Z","caller":"traceutil/trace.go:171","msg":"trace[1189708178] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1078; }","duration":"2.094856296s","start":"2026-05-29T05:48:06.617995Z","end":"2026-05-29T05:48:08.712851Z","steps":["trace[1189708178] 'read index received' (duration: 2.09288126s)","trace[1189708178] 'applied index is now lower than readState.Index' (duration: 1.973946ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:08.713016Z","caller":"traceutil/trace.go:171","msg":"trace[135207585] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"1.88894172s","start":"2026-05-29T05:48:06.824063Z","end":"2026-05-29T05:48:08.713004Z","steps":["trace[135207585] 'process raft request' (duration: 1.888655725s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.713204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:06.824042Z","time spent":"1.889062703s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:08.713256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.263018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:48:08.713273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.910838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:48:08.713311Z","caller":"traceutil/trace.go:171","msg":"trace[32096957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"946.35158ms","start":"2026-05-29T05:48:07.766947Z","end":"2026-05-29T05:48:08.713299Z","steps":["trace[32096957] 'agreement among raft nodes before linearized reading' (duration: 946.268868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.713429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958614345s","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-05-29T05:48:08.713442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:07.766933Z","time spent":"946.497503ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:48:08.713484Z","caller":"traceutil/trace.go:171","msg":"trace[1965037415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"1.958677427s","start":"2026-05-29T05:48:06.754789Z","end":"2026-05-29T05:48:08.713467Z","steps":["trace[1965037415] 'agreement among raft nodes before linearized reading' (duration: 1.958571245s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.713329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968347274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-29T05:48:08.71353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:06.754781Z","time spent":"1.958740588s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T05:48:08.713569Z","caller":"traceutil/trace.go:171","msg":"trace[1467597359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"1.96864247s","start":"2026-05-29T05:48:06.744913Z","end":"2026-05-29T05:48:08.713556Z","steps":["trace[1467597359] 'agreement among raft nodes before linearized reading' (duration: 1.968274423s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.713621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:06.744895Z","time spent":"1.968713001s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:08.71336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.095352415s","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-05-29T05:48:08.713322Z","caller":"traceutil/trace.go:171","msg":"trace[508770225] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1041; }","duration":"890.987761ms","start":"2026-05-29T05:48:07.822323Z","end":"2026-05-29T05:48:08.713311Z","steps":["trace[508770225] 'agreement among raft nodes before linearized reading' (duration: 890.900648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.713438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.95998537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:48:08.713734Z","caller":"traceutil/trace.go:171","msg":"trace[1637286053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"1.960298346s","start":"2026-05-29T05:48:06.753424Z","end":"2026-05-29T05:48:08.713722Z","steps":["trace[1637286053] 'agreement among raft nodes before linearized reading' (duration: 1.95995632s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:08.713728Z","caller":"traceutil/trace.go:171","msg":"trace[361559908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1041; }","duration":"2.095759143s","start":"2026-05-29T05:48:06.617948Z","end":"2026-05-29T05:48:08.713707Z","steps":["trace[361559908] 'agreement among raft nodes before linearized reading' (duration: 2.095251573s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:08.71378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:06.753413Z","time spent":"1.960356087s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:08.713795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:06.617922Z","time spent":"2.095854054s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:48:08.713737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:07.822312Z","time spent":"891.403168ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:48:30.015071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.912587ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:48:30.015181Z","caller":"traceutil/trace.go:171","msg":"trace[1578373935] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"247.603056ms","start":"2026-05-29T05:48:29.76756Z","end":"2026-05-29T05:48:30.015163Z","steps":["trace[1578373935] 'read index received' (duration: 120.565957ms)","trace[1578373935] 'applied index is now lower than readState.Index' (duration: 127.035649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:48:30.015253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.685737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:48:30.01528Z","caller":"traceutil/trace.go:171","msg":"trace[244052356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"247.756829ms","start":"2026-05-29T05:48:29.767513Z","end":"2026-05-29T05:48:30.01527Z","steps":["trace[244052356] 'agreement among raft nodes before linearized reading' (duration: 247.695778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.268206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215191169,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:31.664713Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.285203826s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:48:31.665136Z","caller":"traceutil/trace.go:171","msg":"trace[1611009166] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"1.285678465s","start":"2026-05-29T05:48:30.379414Z","end":"2026-05-29T05:48:31.665092Z","steps":["trace[1611009166] 'process raft request' (duration: 1.285506152s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.665316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.379394Z","time spent":"1.285852838s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:31.691247Z","caller":"traceutil/trace.go:171","msg":"trace[15183794] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"923.073368ms","start":"2026-05-29T05:48:30.768155Z","end":"2026-05-29T05:48:31.691229Z","steps":["trace[15183794] 'read index received' (duration: 896.836734ms)","trace[15183794] 'applied index is now lower than readState.Index' (duration: 26.235744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:48:31.691398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.233181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:48:31.691471Z","caller":"traceutil/trace.go:171","msg":"trace[583583134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"923.343912ms","start":"2026-05-29T05:48:30.768114Z","end":"2026-05-29T05:48:31.691458Z","steps":["trace[583583134] 'agreement among raft nodes before linearized reading' (duration: 923.238241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.691576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.768096Z","time spent":"923.469415ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:48:31.691621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.492508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:48:31.69147Z","caller":"traceutil/trace.go:171","msg":"trace[99517878] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"874.994044ms","start":"2026-05-29T05:48:30.816465Z","end":"2026-05-29T05:48:31.691459Z","steps":["trace[99517878] 'process raft request' (duration: 874.645527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.691707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.145424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:48:31.69174Z","caller":"traceutil/trace.go:171","msg":"trace[1088012220] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1113; }","duration":"484.225875ms","start":"2026-05-29T05:48:31.207507Z","end":"2026-05-29T05:48:31.691733Z","steps":["trace[1088012220] 'agreement among raft nodes before linearized reading' (duration: 484.173785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.691774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:31.207487Z","time spent":"484.277297ms","remote":"127.0.0.1:38796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:48:31.69179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.527623ms","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-05-29T05:48:31.691812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.816449Z","time spent":"875.255898ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:31.691737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.939791ms","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":"warn","ts":"2026-05-29T05:48:31.691829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.485752ms","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":"warn","ts":"2026-05-29T05:48:31.691612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.638847ms","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-05-29T05:48:31.691923Z","caller":"traceutil/trace.go:171","msg":"trace[921074181] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1113; }","duration":"733.634176ms","start":"2026-05-29T05:48:30.958269Z","end":"2026-05-29T05:48:31.691903Z","steps":["trace[921074181] 'agreement among raft nodes before linearized reading' (duration: 733.438262ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:31.691944Z","caller":"traceutil/trace.go:171","msg":"trace[628888018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"825.035623ms","start":"2026-05-29T05:48:30.866897Z","end":"2026-05-29T05:48:31.691933Z","steps":["trace[628888018] 'agreement among raft nodes before linearized reading' (duration: 824.498423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.691972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.958257Z","time spent":"733.703657ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:48:31.691988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.866882Z","time spent":"825.096745ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:48:31.692016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.489094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-29T05:48:31.691839Z","caller":"traceutil/trace.go:171","msg":"trace[750797918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"817.579444ms","start":"2026-05-29T05:48:30.874237Z","end":"2026-05-29T05:48:31.691817Z","steps":["trace[750797918] 'agreement among raft nodes before linearized reading' (duration: 817.492432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.692106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.874228Z","time spent":"817.871989ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:48:31.692121Z","caller":"traceutil/trace.go:171","msg":"trace[1264447575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"648.652837ms","start":"2026-05-29T05:48:31.043442Z","end":"2026-05-29T05:48:31.692095Z","steps":["trace[1264447575] 'agreement among raft nodes before linearized reading' (duration: 648.322351ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:31.691663Z","caller":"traceutil/trace.go:171","msg":"trace[2140934076] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1113; }","duration":"645.557349ms","start":"2026-05-29T05:48:31.046096Z","end":"2026-05-29T05:48:31.691653Z","steps":["trace[2140934076] 'agreement among raft nodes before linearized reading' (duration: 645.433037ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:31.691903Z","caller":"traceutil/trace.go:171","msg":"trace[1124421756] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1113; }","duration":"775.182265ms","start":"2026-05-29T05:48:30.916705Z","end":"2026-05-29T05:48:31.691887Z","steps":["trace[1124421756] 'agreement among raft nodes before linearized reading' (duration: 774.89584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.692184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:31.046086Z","time spent":"646.078398ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:48:31.691708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.247387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:48:31.692235Z","caller":"traceutil/trace.go:171","msg":"trace[33429069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"809.816637ms","start":"2026-05-29T05:48:30.882409Z","end":"2026-05-29T05:48:31.692226Z","steps":["trace[33429069] 'agreement among raft nodes before linearized reading' (duration: 809.237337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.692269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.882388Z","time spent":"809.874448ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:31.692192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:31.043412Z","time spent":"648.7692ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:31.69239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:30.916693Z","time spent":"775.530302ms","remote":"127.0.0.1:38828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:48:31.692497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.709948ms","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-05-29T05:48:31.692545Z","caller":"traceutil/trace.go:171","msg":"trace[619976627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"657.943092ms","start":"2026-05-29T05:48:31.034588Z","end":"2026-05-29T05:48:31.692531Z","steps":["trace[619976627] 'agreement among raft nodes before linearized reading' (duration: 656.916932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:31.692662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:31.034577Z","time spent":"658.071394ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:31.9598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.640524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:48:31.960154Z","caller":"traceutil/trace.go:171","msg":"trace[2056366839] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"263.565165ms","start":"2026-05-29T05:48:31.696563Z","end":"2026-05-29T05:48:31.960128Z","steps":["trace[2056366839] 'process raft request' (duration: 80.450392ms)","trace[2056366839] 'compare' (duration: 182.382099ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:31.960383Z","caller":"traceutil/trace.go:171","msg":"trace[1875166194] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"263.670717ms","start":"2026-05-29T05:48:31.696674Z","end":"2026-05-29T05:48:31.960345Z","steps":["trace[1875166194] 'process raft request' (duration: 263.29299ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:31.960423Z","caller":"traceutil/trace.go:171","msg":"trace[632076852] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"262.69624ms","start":"2026-05-29T05:48:31.697708Z","end":"2026-05-29T05:48:31.960404Z","steps":["trace[632076852] 'process raft request' (duration: 262.565048ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:31.960544Z","caller":"traceutil/trace.go:171","msg":"trace[198058563] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"263.77787ms","start":"2026-05-29T05:48:31.696744Z","end":"2026-05-29T05:48:31.960522Z","steps":["trace[198058563] 'process raft request' (duration: 263.422923ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:31.960621Z","caller":"traceutil/trace.go:171","msg":"trace[192269135] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"263.667267ms","start":"2026-05-29T05:48:31.696895Z","end":"2026-05-29T05:48:31.960562Z","steps":["trace[192269135] 'process raft request' (duration: 263.330381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.465628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215191181,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:48:34.490917Z","caller":"traceutil/trace.go:171","msg":"trace[1646246944] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"526.204175ms","start":"2026-05-29T05:48:33.964689Z","end":"2026-05-29T05:48:34.490893Z","steps":["trace[1646246944] 'read index received' (duration: 526.006301ms)","trace[1646246944] 'applied index is now lower than readState.Index' (duration: 196.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:34.491049Z","caller":"traceutil/trace.go:171","msg":"trace[105399573] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"793.904019ms","start":"2026-05-29T05:48:33.69711Z","end":"2026-05-29T05:48:34.491014Z","steps":["trace[105399573] 'process raft request' (duration: 793.638994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.491239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.522401ms","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-05-29T05:48:34.491316Z","caller":"traceutil/trace.go:171","msg":"trace[1048529742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1119; }","duration":"526.647614ms","start":"2026-05-29T05:48:33.964656Z","end":"2026-05-29T05:48:34.491304Z","steps":["trace[1048529742] 'agreement among raft nodes before linearized reading' (duration: 526.421439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.491361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.600164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-29T05:48:34.491379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.987822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T05:48:34.491426Z","caller":"traceutil/trace.go:171","msg":"trace[1073575235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1119; }","duration":"525.679335ms","start":"2026-05-29T05:48:33.965735Z","end":"2026-05-29T05:48:34.491415Z","steps":["trace[1073575235] 'agreement among raft nodes before linearized reading' (duration: 525.539282ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:34.491448Z","caller":"traceutil/trace.go:171","msg":"trace[676521992] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1119; }","duration":"232.099294ms","start":"2026-05-29T05:48:34.259331Z","end":"2026-05-29T05:48:34.49143Z","steps":["trace[676521992] 'agreement among raft nodes before linearized reading' (duration: 231.86105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.491465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:33.965726Z","time spent":"525.730926ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:34.491225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:33.69709Z","time spent":"794.024791ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:34.491578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:33.964642Z","time spent":"526.911549ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:48:34.491372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.079683ms","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-05-29T05:48:34.491824Z","caller":"traceutil/trace.go:171","msg":"trace[1106791885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1119; }","duration":"526.560701ms","start":"2026-05-29T05:48:33.96524Z","end":"2026-05-29T05:48:34.4918Z","steps":["trace[1106791885] 'agreement among raft nodes before linearized reading' (duration: 526.040982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.491245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.032272ms","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-05-29T05:48:34.491896Z","caller":"traceutil/trace.go:171","msg":"trace[2016068497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1119; }","duration":"526.732565ms","start":"2026-05-29T05:48:33.965151Z","end":"2026-05-29T05:48:34.491884Z","steps":["trace[2016068497] 'agreement among raft nodes before linearized reading' (duration: 525.96518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.491908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:33.965214Z","time spent":"526.668774ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:48:34.491957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:33.96513Z","time spent":"526.798505ms","remote":"127.0.0.1:38952","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-05-29T05:48:34.492052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.175114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:48:34.492117Z","caller":"traceutil/trace.go:171","msg":"trace[45934407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1119; }","duration":"526.264765ms","start":"2026-05-29T05:48:33.965836Z","end":"2026-05-29T05:48:34.492101Z","steps":["trace[45934407] 'agreement among raft nodes before linearized reading' (duration: 526.099632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.492369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:33.965824Z","time spent":"526.529441ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:34.859905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:34.492863Z","time spent":"367.034212ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T05:48:34.879489Z","caller":"traceutil/trace.go:171","msg":"trace[843802933] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"380.23818ms","start":"2026-05-29T05:48:34.499225Z","end":"2026-05-29T05:48:34.879464Z","steps":["trace[843802933] 'process raft request' (duration: 380.164279ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:34.879898Z","caller":"traceutil/trace.go:171","msg":"trace[1961289773] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"380.187489ms","start":"2026-05-29T05:48:34.499687Z","end":"2026-05-29T05:48:34.879874Z","steps":["trace[1961289773] 'process raft request' (duration: 379.74377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.880109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:34.49968Z","time spent":"380.30289ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:34.880179Z","caller":"traceutil/trace.go:171","msg":"trace[824264048] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1163; }","duration":"112.263661ms","start":"2026-05-29T05:48:34.767895Z","end":"2026-05-29T05:48:34.880159Z","steps":["trace[824264048] 'read index received' (duration: 13.720048ms)","trace[824264048] 'applied index is now lower than readState.Index' (duration: 98.542543ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:34.880269Z","caller":"traceutil/trace.go:171","msg":"trace[49582907] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"380.413253ms","start":"2026-05-29T05:48:34.499817Z","end":"2026-05-29T05:48:34.880231Z","steps":["trace[49582907] 'process raft request' (duration: 379.968425ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:34.880293Z","caller":"traceutil/trace.go:171","msg":"trace[1724584180] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"379.640399ms","start":"2026-05-29T05:48:34.500637Z","end":"2026-05-29T05:48:34.880278Z","steps":["trace[1724584180] 'process raft request' (duration: 379.382884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.880335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.423933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:48:34.880407Z","caller":"traceutil/trace.go:171","msg":"trace[1692205126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"112.550757ms","start":"2026-05-29T05:48:34.767845Z","end":"2026-05-29T05:48:34.880396Z","steps":["trace[1692205126] 'agreement among raft nodes before linearized reading' (duration: 112.387133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.880377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:34.500632Z","time spent":"379.70055ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:34.879747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:34.49921Z","time spent":"380.404894ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:34.880381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:34.499809Z","time spent":"380.516745ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:34.880566Z","caller":"traceutil/trace.go:171","msg":"trace[1251617484] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"381.095046ms","start":"2026-05-29T05:48:34.498408Z","end":"2026-05-29T05:48:34.879503Z","steps":["trace[1251617484] 'process raft request' (duration: 380.838631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:34.881356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:34.498385Z","time spent":"382.490363ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:36.625801Z","caller":"traceutil/trace.go:171","msg":"trace[2045805945] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"126.04081ms","start":"2026-05-29T05:48:36.499734Z","end":"2026-05-29T05:48:36.625775Z","steps":["trace[2045805945] 'process raft request' (duration: 125.870117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:37.127837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.295615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:48:37.127912Z","caller":"traceutil/trace.go:171","msg":"trace[559059507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"243.418228ms","start":"2026-05-29T05:48:36.884476Z","end":"2026-05-29T05:48:37.127894Z","steps":["trace[559059507] 'range keys from in-memory index tree' (duration: 243.114662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:37.128057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.245183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:48:37.128135Z","caller":"traceutil/trace.go:171","msg":"trace[1350908280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1127; }","duration":"360.360097ms","start":"2026-05-29T05:48:36.767757Z","end":"2026-05-29T05:48:37.128117Z","steps":["trace[1350908280] 'range keys from in-memory index tree' (duration: 360.173452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:37.128171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:36.76774Z","time spent":"360.422668ms","remote":"127.0.0.1:38704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:48:37.128238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.55343ms","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-05-29T05:48:37.128271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.701362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:48:37.12831Z","caller":"traceutil/trace.go:171","msg":"trace[2141140642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"243.778384ms","start":"2026-05-29T05:48:36.88452Z","end":"2026-05-29T05:48:37.128298Z","steps":["trace[2141140642] 'range keys from in-memory index tree' (duration: 243.59787ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:37.128311Z","caller":"traceutil/trace.go:171","msg":"trace[419117361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1127; }","duration":"242.680993ms","start":"2026-05-29T05:48:36.885611Z","end":"2026-05-29T05:48:37.128292Z","steps":["trace[419117361] 'range keys from in-memory index tree' (duration: 242.401188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:37.128336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.974599ms","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-05-29T05:48:37.128418Z","caller":"traceutil/trace.go:171","msg":"trace[535492728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"243.094242ms","start":"2026-05-29T05:48:36.885305Z","end":"2026-05-29T05:48:37.128399Z","steps":["trace[535492728] 'range keys from in-memory index tree' (duration: 242.776895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:37.128454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.886597ms","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-05-29T05:48:37.128533Z","caller":"traceutil/trace.go:171","msg":"trace[145184381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"243.025049ms","start":"2026-05-29T05:48:36.885488Z","end":"2026-05-29T05:48:37.128513Z","steps":["trace[145184381] 'range keys from in-memory index tree' (duration: 242.721404ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:38.867688Z","caller":"traceutil/trace.go:171","msg":"trace[1059782902] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"236.054078ms","start":"2026-05-29T05:48:38.631585Z","end":"2026-05-29T05:48:38.867639Z","steps":["trace[1059782902] 'process raft request' (duration: 235.63309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:53.588915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215191431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:54.04073Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.124610757s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:48:54.041153Z","caller":"traceutil/trace.go:171","msg":"trace[281404311] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"952.862147ms","start":"2026-05-29T05:48:53.088264Z","end":"2026-05-29T05:48:54.041126Z","steps":["trace[281404311] 'read index received' (duration: 952.638543ms)","trace[281404311] 'applied index is now lower than readState.Index' (duration: 222.664µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:48:54.041544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.156107ms","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-05-29T05:48:54.041548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.247965ms","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":"warn","ts":"2026-05-29T05:48:54.041559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.346068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:48:54.04161Z","caller":"traceutil/trace.go:171","msg":"trace[213918517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"825.258288ms","start":"2026-05-29T05:48:53.216334Z","end":"2026-05-29T05:48:54.041592Z","steps":["trace[213918517] 'agreement among raft nodes before linearized reading' (duration: 825.063295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.041604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.988372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-29T05:48:54.041621Z","caller":"traceutil/trace.go:171","msg":"trace[1087827212] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1230; }","duration":"953.376478ms","start":"2026-05-29T05:48:53.088228Z","end":"2026-05-29T05:48:54.041605Z","steps":["trace[1087827212] 'agreement among raft nodes before linearized reading' (duration: 953.207024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.041642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:53.216305Z","time spent":"825.33129ms","remote":"127.0.0.1:38952","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-05-29T05:48:54.041638Z","caller":"traceutil/trace.go:171","msg":"trace[1538512426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"275.473871ms","start":"2026-05-29T05:48:53.766143Z","end":"2026-05-29T05:48:54.041617Z","steps":["trace[1538512426] 'agreement among raft nodes before linearized reading' (duration: 275.357399ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:54.041653Z","caller":"traceutil/trace.go:171","msg":"trace[1712012506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"784.062353ms","start":"2026-05-29T05:48:53.257576Z","end":"2026-05-29T05:48:54.041638Z","steps":["trace[1712012506] 'agreement among raft nodes before linearized reading' (duration: 783.90085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.04167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:53.088212Z","time spent":"953.447329ms","remote":"127.0.0.1:50986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:48:54.041677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.171549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-29T05:48:54.041693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:53.257566Z","time spent":"784.115054ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:48:54.041727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.943783ms","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-05-29T05:48:54.04174Z","caller":"traceutil/trace.go:171","msg":"trace[2030570569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"782.25989ms","start":"2026-05-29T05:48:53.259462Z","end":"2026-05-29T05:48:54.041722Z","steps":["trace[2030570569] 'agreement among raft nodes before linearized reading' (duration: 782.070436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.041784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:53.259447Z","time spent":"782.328921ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T05:48:54.041782Z","caller":"traceutil/trace.go:171","msg":"trace[1136679346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"790.030086ms","start":"2026-05-29T05:48:53.251739Z","end":"2026-05-29T05:48:54.041769Z","steps":["trace[1136679346] 'agreement among raft nodes before linearized reading' (duration: 789.808692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.04182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:53.251725Z","time spent":"790.087686ms","remote":"127.0.0.1:38952","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-05-29T05:48:54.041521Z","caller":"traceutil/trace.go:171","msg":"trace[1465917055] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.125488273s","start":"2026-05-29T05:48:52.916002Z","end":"2026-05-29T05:48:54.04149Z","steps":["trace[1465917055] 'process raft request' (duration: 1.124923392s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.042168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:52.915979Z","time spent":"1.126130755s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:54.042186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.426203ms","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-05-29T05:48:54.042238Z","caller":"traceutil/trace.go:171","msg":"trace[1255968018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"782.495094ms","start":"2026-05-29T05:48:53.259728Z","end":"2026-05-29T05:48:54.042223Z","steps":["trace[1255968018] 'agreement among raft nodes before linearized reading' (duration: 781.686979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:54.042291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:53.259724Z","time spent":"782.555115ms","remote":"127.0.0.1:38952","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-05-29T05:48:54.76252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215191435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:48:55.172875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126679796s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:48:55.173187Z","caller":"traceutil/trace.go:171","msg":"trace[2041985292] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.127025392s","start":"2026-05-29T05:48:54.046142Z","end":"2026-05-29T05:48:55.173167Z","steps":["trace[2041985292] 'process raft request' (duration: 1.126872579s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:55.173313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.046132Z","time spent":"1.127094074s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:55.173699Z","caller":"traceutil/trace.go:171","msg":"trace[1071325060] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1280; }","duration":"912.189962ms","start":"2026-05-29T05:48:54.261498Z","end":"2026-05-29T05:48:55.173688Z","steps":["trace[1071325060] 'read index received' (duration: 911.52334ms)","trace[1071325060] 'applied index is now lower than readState.Index' (duration: 665.942µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:48:55.173727Z","caller":"traceutil/trace.go:171","msg":"trace[76195171] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.126589963s","start":"2026-05-29T05:48:54.047127Z","end":"2026-05-29T05:48:55.173717Z","steps":["trace[76195171] 'process raft request' (duration: 1.126521112s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:55.173746Z","caller":"traceutil/trace.go:171","msg":"trace[1612397299] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.126484051s","start":"2026-05-29T05:48:54.047256Z","end":"2026-05-29T05:48:55.17374Z","steps":["trace[1612397299] 'process raft request' (duration: 1.12641168s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:48:55.173724Z","caller":"traceutil/trace.go:171","msg":"trace[1918113758] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"1.127017512s","start":"2026-05-29T05:48:54.046678Z","end":"2026-05-29T05:48:55.173696Z","steps":["trace[1918113758] 'process raft request' (duration: 1.1269162s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:55.173773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.047117Z","time spent":"1.126631874s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:55.173779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.047249Z","time spent":"1.126507891s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:55.173824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.046656Z","time spent":"1.127113803s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:48:55.173863Z","caller":"traceutil/trace.go:171","msg":"trace[1408229332] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.127035811s","start":"2026-05-29T05:48:54.046819Z","end":"2026-05-29T05:48:55.173855Z","steps":["trace[1408229332] 'process raft request' (duration: 1.126811677s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:55.173871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.414466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T05:48:55.173892Z","caller":"traceutil/trace.go:171","msg":"trace[303398874] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1235; }","duration":"912.489688ms","start":"2026-05-29T05:48:54.261397Z","end":"2026-05-29T05:48:55.173887Z","steps":["trace[303398874] 'agreement among raft nodes before linearized reading' (duration: 912.420436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:55.173901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.046787Z","time spent":"1.127093493s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:48:55.173906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.261367Z","time spent":"912.535538ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"warn","ts":"2026-05-29T05:48:55.17393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.737738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:48:55.173946Z","caller":"traceutil/trace.go:171","msg":"trace[1947160806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"406.78468ms","start":"2026-05-29T05:48:54.767158Z","end":"2026-05-29T05:48:55.173943Z","steps":["trace[1947160806] 'agreement among raft nodes before linearized reading' (duration: 406.759479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:48:55.173959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:48:54.76714Z","time spent":"406.81442ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:49:04.416049Z","caller":"traceutil/trace.go:171","msg":"trace[1683138990] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"139.812839ms","start":"2026-05-29T05:49:04.276206Z","end":"2026-05-29T05:49:04.416019Z","steps":["trace[1683138990] 'read index received' (duration: 50.601612ms)","trace[1683138990] 'applied index is now lower than readState.Index' (duration: 89.209457ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:04.416081Z","caller":"traceutil/trace.go:171","msg":"trace[1601862746] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"140.943729ms","start":"2026-05-29T05:49:04.27511Z","end":"2026-05-29T05:49:04.416054Z","steps":["trace[1601862746] 'process raft request' (duration: 51.753293ms)","trace[1601862746] 'compare' (duration: 88.994423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:04.416286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.043904ms","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:7"} {"level":"info","ts":"2026-05-29T05:49:04.416373Z","caller":"traceutil/trace.go:171","msg":"trace[462984471] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1264; }","duration":"140.170706ms","start":"2026-05-29T05:49:04.276181Z","end":"2026-05-29T05:49:04.416352Z","steps":["trace[462984471] 'agreement among raft nodes before linearized reading' (duration: 139.943221ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:09.650619Z","caller":"traceutil/trace.go:171","msg":"trace[1752042892] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"392.135633ms","start":"2026-05-29T05:49:09.258462Z","end":"2026-05-29T05:49:09.650597Z","steps":["trace[1752042892] 'process raft request' (duration: 392.013911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:09.650822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:09.258453Z","time spent":"392.294227ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:09.678313Z","caller":"traceutil/trace.go:171","msg":"trace[528405848] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"387.14733ms","start":"2026-05-29T05:49:09.291158Z","end":"2026-05-29T05:49:09.678305Z","steps":["trace[528405848] 'read index received' (duration: 359.47253ms)","trace[528405848] 'applied index is now lower than readState.Index' (duration: 27.67418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:09.6784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.237952ms","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-05-29T05:49:09.678422Z","caller":"traceutil/trace.go:171","msg":"trace[934385211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1287; }","duration":"387.283902ms","start":"2026-05-29T05:49:09.291133Z","end":"2026-05-29T05:49:09.678417Z","steps":["trace[934385211] 'agreement among raft nodes before linearized reading' (duration: 387.214161ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:09.678387Z","caller":"traceutil/trace.go:171","msg":"trace[752096] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"419.837994ms","start":"2026-05-29T05:49:09.258521Z","end":"2026-05-29T05:49:09.678359Z","steps":["trace[752096] 'process raft request' (duration: 419.744433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:09.678438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:09.291123Z","time spent":"387.312224ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:49:09.678514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.57134ms","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-05-29T05:49:09.678531Z","caller":"traceutil/trace.go:171","msg":"trace[463608061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"368.609381ms","start":"2026-05-29T05:49:09.309916Z","end":"2026-05-29T05:49:09.678526Z","steps":["trace[463608061] 'agreement among raft nodes before linearized reading' (duration: 368.56352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:09.678543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:09.309906Z","time spent":"368.633992ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:09.678546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:09.258503Z","time spent":"419.949347ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:09.67873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.042384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:49:09.678785Z","caller":"traceutil/trace.go:171","msg":"trace[834021422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"340.098515ms","start":"2026-05-29T05:49:09.338674Z","end":"2026-05-29T05:49:09.678773Z","steps":["trace[834021422] 'agreement among raft nodes before linearized reading' (duration: 339.926652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:09.678819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:09.338575Z","time spent":"340.237297ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T05:49:09.787316Z","caller":"traceutil/trace.go:171","msg":"trace[2147026815] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"105.300891ms","start":"2026-05-29T05:49:09.681993Z","end":"2026-05-29T05:49:09.787294Z","steps":["trace[2147026815] 'process raft request' (duration: 105.210319ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:09.787522Z","caller":"traceutil/trace.go:171","msg":"trace[621621116] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"105.558066ms","start":"2026-05-29T05:49:09.681945Z","end":"2026-05-29T05:49:09.787503Z","steps":["trace[621621116] 'process raft request' (duration: 61.064349ms)","trace[621621116] 'compare' (duration: 44.052528ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:09.787901Z","caller":"traceutil/trace.go:171","msg":"trace[366758088] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"105.118776ms","start":"2026-05-29T05:49:09.682763Z","end":"2026-05-29T05:49:09.787882Z","steps":["trace[366758088] 'process raft request' (duration: 104.497075ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:11.258624Z","caller":"traceutil/trace.go:171","msg":"trace[1810014585] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"133.437488ms","start":"2026-05-29T05:49:11.125151Z","end":"2026-05-29T05:49:11.258588Z","steps":["trace[1810014585] 'process raft request' (duration: 133.293936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:11.638327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.729829ms","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":"info","ts":"2026-05-29T05:49:11.638424Z","caller":"traceutil/trace.go:171","msg":"trace[141910718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1303; }","duration":"172.925402ms","start":"2026-05-29T05:49:11.465479Z","end":"2026-05-29T05:49:11.638404Z","steps":["trace[141910718] 'count revisions from in-memory index tree' (duration: 172.589106ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:11.950582Z","caller":"traceutil/trace.go:171","msg":"trace[1081984426] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"134.333706ms","start":"2026-05-29T05:49:11.816207Z","end":"2026-05-29T05:49:11.950541Z","steps":["trace[1081984426] 'process raft request' (duration: 46.798599ms)","trace[1081984426] 'compare' (duration: 87.317853ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:11.95064Z","caller":"traceutil/trace.go:171","msg":"trace[297410924] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"134.380357ms","start":"2026-05-29T05:49:11.816225Z","end":"2026-05-29T05:49:11.950605Z","steps":["trace[297410924] 'process raft request' (duration: 134.220134ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:11.950908Z","caller":"traceutil/trace.go:171","msg":"trace[159704663] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"134.410017ms","start":"2026-05-29T05:49:11.816485Z","end":"2026-05-29T05:49:11.950895Z","steps":["trace[159704663] 'process raft request' (duration: 134.01803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:14.766336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699562215191634,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:49:14.832069Z","caller":"traceutil/trace.go:171","msg":"trace[314658078] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"570.314494ms","start":"2026-05-29T05:49:14.261728Z","end":"2026-05-29T05:49:14.832043Z","steps":["trace[314658078] 'read index received' (duration: 570.07429ms)","trace[314658078] 'applied index is now lower than readState.Index' (duration: 238.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:14.832261Z","caller":"traceutil/trace.go:171","msg":"trace[1116265068] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"646.310713ms","start":"2026-05-29T05:49:14.185905Z","end":"2026-05-29T05:49:14.832216Z","steps":["trace[1116265068] 'process raft request' (duration: 645.968387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:14.83246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.708942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T05:49:14.832539Z","caller":"traceutil/trace.go:171","msg":"trace[501600499] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1323; }","duration":"570.837884ms","start":"2026-05-29T05:49:14.261684Z","end":"2026-05-29T05:49:14.832522Z","steps":["trace[501600499] 'agreement among raft nodes before linearized reading' (duration: 570.583479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:14.832584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:14.261667Z","time spent":"570.909196ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"warn","ts":"2026-05-29T05:49:14.832607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:14.185886Z","time spent":"646.508417ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:15.521533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:14.833853Z","time spent":"687.65759ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T05:49:16.943024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.326004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:49:16.943105Z","caller":"traceutil/trace.go:171","msg":"trace[42139784] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1330; }","duration":"127.422876ms","start":"2026-05-29T05:49:16.815665Z","end":"2026-05-29T05:49:16.943087Z","steps":["trace[42139784] 'count revisions from in-memory index tree' (duration: 127.234523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:16.94315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.911028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:16.943182Z","caller":"traceutil/trace.go:171","msg":"trace[579770926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"175.968128ms","start":"2026-05-29T05:49:16.767207Z","end":"2026-05-29T05:49:16.943175Z","steps":["trace[579770926] 'range keys from in-memory index tree' (duration: 175.864146ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:17.432722Z","caller":"traceutil/trace.go:171","msg":"trace[1398976210] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"593.913427ms","start":"2026-05-29T05:49:16.838777Z","end":"2026-05-29T05:49:17.43269Z","steps":["trace[1398976210] 'process raft request' (duration: 593.771515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:17.432903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:16.838755Z","time spent":"594.0525ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:18.477515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.429158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:49:18.47761Z","caller":"traceutil/trace.go:171","msg":"trace[353033322] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1385; }","duration":"1.319616733s","start":"2026-05-29T05:49:17.157976Z","end":"2026-05-29T05:49:18.477593Z","steps":["trace[353033322] 'read index received' (duration: 274.575053ms)","trace[353033322] 'applied index is now lower than readState.Index' (duration: 1.04504046s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:18.477689Z","caller":"traceutil/trace.go:171","msg":"trace[334502104] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"1.364974665s","start":"2026-05-29T05:49:17.112695Z","end":"2026-05-29T05:49:18.477669Z","steps":["trace[334502104] 'process raft request' (duration: 946.308623ms)","trace[334502104] 'compare' (duration: 418.085192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:18.477726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.465088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:18.477755Z","caller":"traceutil/trace.go:171","msg":"trace[727121729] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1332; }","duration":"853.555671ms","start":"2026-05-29T05:49:17.624191Z","end":"2026-05-29T05:49:18.477746Z","steps":["trace[727121729] 'agreement among raft nodes before linearized reading' (duration: 853.500999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.477788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.62417Z","time spent":"853.610581ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:49:18.477781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.112672Z","time spent":"1.365065108s","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:18.477869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.73595ms","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-05-29T05:49:18.477933Z","caller":"traceutil/trace.go:171","msg":"trace[1960473201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"488.840053ms","start":"2026-05-29T05:49:17.989076Z","end":"2026-05-29T05:49:18.477916Z","steps":["trace[1960473201] 'agreement among raft nodes before linearized reading' (duration: 488.639078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.477975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.989039Z","time spent":"488.926715ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:49:18.478121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.018011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:49:18.478173Z","caller":"traceutil/trace.go:171","msg":"trace[1431605492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"476.063932ms","start":"2026-05-29T05:49:18.002084Z","end":"2026-05-29T05:49:18.478147Z","steps":["trace[1431605492] 'agreement among raft nodes before linearized reading' (duration: 475.94738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.478209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.00208Z","time spent":"476.119223ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:18.478225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.150543ms","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-05-29T05:49:18.478255Z","caller":"traceutil/trace.go:171","msg":"trace[624220057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"476.198735ms","start":"2026-05-29T05:49:18.002047Z","end":"2026-05-29T05:49:18.478246Z","steps":["trace[624220057] 'agreement among raft nodes before linearized reading' (duration: 476.118083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.478281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.002037Z","time spent":"476.237865ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:18.478376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.321938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-29T05:49:18.478403Z","caller":"traceutil/trace.go:171","msg":"trace[1759731863] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1332; }","duration":"416.381819ms","start":"2026-05-29T05:49:18.062013Z","end":"2026-05-29T05:49:18.478395Z","steps":["trace[1759731863] 'agreement among raft nodes before linearized reading' (duration: 416.305128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.478429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.061995Z","time spent":"416.42719ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T05:49:18.478544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.154973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:18.478579Z","caller":"traceutil/trace.go:171","msg":"trace[1826382583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"711.190723ms","start":"2026-05-29T05:49:17.767378Z","end":"2026-05-29T05:49:18.478568Z","steps":["trace[1826382583] 'agreement among raft nodes before linearized reading' (duration: 711.132072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.478605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.767334Z","time spent":"711.264974ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:49:18.478964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320985318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:18.479015Z","caller":"traceutil/trace.go:171","msg":"trace[1744068501] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1332; }","duration":"1.32106648s","start":"2026-05-29T05:49:17.157929Z","end":"2026-05-29T05:49:18.478995Z","steps":["trace[1744068501] 'agreement among raft nodes before linearized reading' (duration: 1.320952228s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.47897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.183152ms","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-05-29T05:49:18.479046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.157908Z","time spent":"1.321129911s","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:49:18.479047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.935788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:49:18.479084Z","caller":"traceutil/trace.go:171","msg":"trace[1409709176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"560.011721ms","start":"2026-05-29T05:49:17.919061Z","end":"2026-05-29T05:49:18.479073Z","steps":["trace[1409709176] 'agreement among raft nodes before linearized reading' (duration: 558.8877ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:18.479053Z","caller":"traceutil/trace.go:171","msg":"trace[840602746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"653.302914ms","start":"2026-05-29T05:49:17.825739Z","end":"2026-05-29T05:49:18.479042Z","steps":["trace[840602746] 'agreement among raft nodes before linearized reading' (duration: 653.156371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.479113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.919041Z","time spent":"560.064921ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:18.479145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:17.825718Z","time spent":"653.416587ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:49:18.771308Z","caller":"traceutil/trace.go:171","msg":"trace[1709204447] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"288.75721ms","start":"2026-05-29T05:49:18.482522Z","end":"2026-05-29T05:49:18.771279Z","steps":["trace[1709204447] 'process raft request' (duration: 208.332648ms)","trace[1709204447] 'compare' (duration: 80.306589ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:18.827211Z","caller":"traceutil/trace.go:171","msg":"trace[631832987] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"344.59873ms","start":"2026-05-29T05:49:18.482587Z","end":"2026-05-29T05:49:18.827186Z","steps":["trace[631832987] 'process raft request' (duration: 344.402936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.827377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.48257Z","time spent":"344.720582ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:18.827511Z","caller":"traceutil/trace.go:171","msg":"trace[949537324] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"344.266693ms","start":"2026-05-29T05:49:18.483235Z","end":"2026-05-29T05:49:18.827502Z","steps":["trace[949537324] 'process raft request' (duration: 343.909297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.827617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.483224Z","time spent":"344.354015ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:18.827687Z","caller":"traceutil/trace.go:171","msg":"trace[143210301] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"344.363525ms","start":"2026-05-29T05:49:18.4833Z","end":"2026-05-29T05:49:18.827664Z","steps":["trace[143210301] 'process raft request' (duration: 344.05468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.827812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.483292Z","time spent":"344.452487ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:18.827887Z","caller":"traceutil/trace.go:171","msg":"trace[1711180479] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"344.265563ms","start":"2026-05-29T05:49:18.483603Z","end":"2026-05-29T05:49:18.827869Z","steps":["trace[1711180479] 'process raft request' (duration: 343.903965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.827999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.483594Z","time spent":"344.346335ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:18.827994Z","caller":"traceutil/trace.go:171","msg":"trace[2068544178] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"344.235913ms","start":"2026-05-29T05:49:18.483743Z","end":"2026-05-29T05:49:18.827979Z","steps":["trace[2068544178] 'process raft request' (duration: 343.828395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:18.82851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:18.483737Z","time spent":"344.354925ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:19.188739Z","caller":"traceutil/trace.go:171","msg":"trace[1604439325] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"166.435559ms","start":"2026-05-29T05:49:19.022275Z","end":"2026-05-29T05:49:19.18871Z","steps":["trace[1604439325] 'process raft request' (duration: 93.968648ms)","trace[1604439325] 'compare' (duration: 72.303138ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:19.269486Z","caller":"traceutil/trace.go:171","msg":"trace[593169064] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"170.237431ms","start":"2026-05-29T05:49:19.099217Z","end":"2026-05-29T05:49:19.269455Z","steps":["trace[593169064] 'process raft request' (duration: 170.065548ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:19.338721Z","caller":"traceutil/trace.go:171","msg":"trace[513447852] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"144.810493ms","start":"2026-05-29T05:49:19.193879Z","end":"2026-05-29T05:49:19.33869Z","steps":["trace[513447852] 'process raft request' (duration: 109.523899ms)","trace[513447852] 'compare' (duration: 35.10056ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:20.943519Z","caller":"traceutil/trace.go:171","msg":"trace[1319789681] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1401; }","duration":"111.029479ms","start":"2026-05-29T05:49:20.832469Z","end":"2026-05-29T05:49:20.943499Z","steps":["trace[1319789681] 'read index received' (duration: 110.808974ms)","trace[1319789681] 'applied index is now lower than readState.Index' (duration: 219.235µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:20.943598Z","caller":"traceutil/trace.go:171","msg":"trace[197941351] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"164.121917ms","start":"2026-05-29T05:49:20.779457Z","end":"2026-05-29T05:49:20.943579Z","steps":["trace[197941351] 'process raft request' (duration: 163.886462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:20.943731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.239793ms","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-05-29T05:49:20.943786Z","caller":"traceutil/trace.go:171","msg":"trace[120525836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"111.334944ms","start":"2026-05-29T05:49:20.832439Z","end":"2026-05-29T05:49:20.943774Z","steps":["trace[120525836] 'agreement among raft nodes before linearized reading' (duration: 111.158171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:20.943818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.923856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:49:20.943856Z","caller":"traceutil/trace.go:171","msg":"trace[1842309331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"110.979266ms","start":"2026-05-29T05:49:20.832866Z","end":"2026-05-29T05:49:20.943845Z","steps":["trace[1842309331] 'agreement among raft nodes before linearized reading' (duration: 110.902625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:20.943744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.254293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:49:20.943947Z","caller":"traceutil/trace.go:171","msg":"trace[883368241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"111.479996ms","start":"2026-05-29T05:49:20.832419Z","end":"2026-05-29T05:49:20.943899Z","steps":["trace[883368241] 'agreement among raft nodes before linearized reading' (duration: 111.199651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:20.943944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.957916ms","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-05-29T05:49:20.944015Z","caller":"traceutil/trace.go:171","msg":"trace[4098799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"111.058648ms","start":"2026-05-29T05:49:20.832938Z","end":"2026-05-29T05:49:20.943997Z","steps":["trace[4098799] 'agreement among raft nodes before linearized reading' (duration: 110.878005ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:21.228913Z","caller":"traceutil/trace.go:171","msg":"trace[1913125485] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"278.351843ms","start":"2026-05-29T05:49:20.950535Z","end":"2026-05-29T05:49:21.228887Z","steps":["trace[1913125485] 'process raft request' (duration: 278.301522ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:21.228932Z","caller":"traceutil/trace.go:171","msg":"trace[1788758197] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"279.75724ms","start":"2026-05-29T05:49:20.949141Z","end":"2026-05-29T05:49:21.228898Z","steps":["trace[1788758197] 'process raft request' (duration: 279.656288ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:21.228923Z","caller":"traceutil/trace.go:171","msg":"trace[1795438943] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"280.875351ms","start":"2026-05-29T05:49:20.948012Z","end":"2026-05-29T05:49:21.228887Z","steps":["trace[1795438943] 'process raft request' (duration: 210.332955ms)","trace[1795438943] 'compare' (duration: 70.331133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:24.053918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.955336ms","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-05-29T05:49:24.054003Z","caller":"traceutil/trace.go:171","msg":"trace[861270032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"821.054029ms","start":"2026-05-29T05:49:23.232921Z","end":"2026-05-29T05:49:24.053975Z","steps":["trace[861270032] 'range keys from in-memory index tree' (duration: 820.819264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.054087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:23.232914Z","time spent":"821.156601ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:49:24.054629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.900044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:49:24.055333Z","caller":"traceutil/trace.go:171","msg":"trace[1554111264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"822.632948ms","start":"2026-05-29T05:49:23.232672Z","end":"2026-05-29T05:49:24.055305Z","steps":["trace[1554111264] 'range keys from in-memory index tree' (duration: 821.774222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.055492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:23.232647Z","time spent":"822.786711ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:24.055681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.586887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:49:24.054783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.071858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:49:24.055764Z","caller":"traceutil/trace.go:171","msg":"trace[1984015545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"287.897653ms","start":"2026-05-29T05:49:23.767849Z","end":"2026-05-29T05:49:24.055746Z","steps":["trace[1984015545] 'agreement among raft nodes before linearized reading' (duration: 287.447835ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:24.055782Z","caller":"traceutil/trace.go:171","msg":"trace[935001181] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"554.360454ms","start":"2026-05-29T05:49:23.501386Z","end":"2026-05-29T05:49:24.055746Z","steps":["trace[935001181] 'process raft request' (duration: 544.217503ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:24.055255Z","caller":"traceutil/trace.go:171","msg":"trace[1474767043] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"287.308602ms","start":"2026-05-29T05:49:23.767892Z","end":"2026-05-29T05:49:24.0552Z","steps":["trace[1474767043] 'read index received' (duration: 277.691421ms)","trace[1474767043] 'applied index is now lower than readState.Index' (duration: 9.615751ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:24.055815Z","caller":"traceutil/trace.go:171","msg":"trace[1272041496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"823.116137ms","start":"2026-05-29T05:49:23.232678Z","end":"2026-05-29T05:49:24.055794Z","steps":["trace[1272041496] 'range keys from in-memory index tree' (duration: 821.847764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.057001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:23.232618Z","time spent":"824.36013ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T05:49:24.05599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:23.501358Z","time spent":"554.466856ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:49:24.056074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.881511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-29T05:49:24.057468Z","caller":"traceutil/trace.go:171","msg":"trace[2092037972] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1362; }","duration":"224.320139ms","start":"2026-05-29T05:49:23.833129Z","end":"2026-05-29T05:49:24.057449Z","steps":["trace[2092037972] 'agreement among raft nodes before linearized reading' (duration: 222.812901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.623042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.963699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:49:24.623292Z","caller":"traceutil/trace.go:171","msg":"trace[280511075] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"562.306424ms","start":"2026-05-29T05:49:24.060959Z","end":"2026-05-29T05:49:24.623265Z","steps":["trace[280511075] 'process raft request' (duration: 152.007109ms)","trace[280511075] 'compare' (duration: 409.748444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:24.623382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:24.060918Z","time spent":"562.425176ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:24.623793Z","caller":"traceutil/trace.go:171","msg":"trace[18909790] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"562.794233ms","start":"2026-05-29T05:49:24.060968Z","end":"2026-05-29T05:49:24.623762Z","steps":["trace[18909790] 'process raft request' (duration: 562.194192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.623967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:24.060916Z","time spent":"562.949816ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:24.629299Z","caller":"traceutil/trace.go:171","msg":"trace[1022317472] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"366.199007ms","start":"2026-05-29T05:49:24.263063Z","end":"2026-05-29T05:49:24.629262Z","steps":["trace[1022317472] 'read index received' (duration: 365.81289ms)","trace[1022317472] 'applied index is now lower than readState.Index' (duration: 384.917µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:24.629362Z","caller":"traceutil/trace.go:171","msg":"trace[1595533075] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"567.127444ms","start":"2026-05-29T05:49:24.062204Z","end":"2026-05-29T05:49:24.629331Z","steps":["trace[1595533075] 'process raft request' (duration: 566.761257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.629478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:24.062185Z","time spent":"567.239367ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:24.629477Z","caller":"traceutil/trace.go:171","msg":"trace[1848580455] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"566.39073ms","start":"2026-05-29T05:49:24.063071Z","end":"2026-05-29T05:49:24.629462Z","steps":["trace[1848580455] 'process raft request' (duration: 566.136496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.629854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.903254ms","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:507"} {"level":"warn","ts":"2026-05-29T05:49:24.629893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:24.063055Z","time spent":"566.673245ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:24.629937Z","caller":"traceutil/trace.go:171","msg":"trace[1084544331] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1366; }","duration":"271.040947ms","start":"2026-05-29T05:49:24.358874Z","end":"2026-05-29T05:49:24.629915Z","steps":["trace[1084544331] 'agreement among raft nodes before linearized reading' (duration: 270.773092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.630156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.106984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T05:49:24.630195Z","caller":"traceutil/trace.go:171","msg":"trace[1731905557] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1366; }","duration":"367.200645ms","start":"2026-05-29T05:49:24.262982Z","end":"2026-05-29T05:49:24.630182Z","steps":["trace[1731905557] 'agreement among raft nodes before linearized reading' (duration: 367.086043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:24.630225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:24.262946Z","time spent":"367.270427ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"info","ts":"2026-05-29T05:49:25.081713Z","caller":"traceutil/trace.go:171","msg":"trace[1471128504] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"118.676542ms","start":"2026-05-29T05:49:24.963009Z","end":"2026-05-29T05:49:25.081686Z","steps":["trace[1471128504] 'process raft request' (duration: 118.402067ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:26.535388Z","caller":"traceutil/trace.go:171","msg":"trace[593407414] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"472.963358ms","start":"2026-05-29T05:49:26.062386Z","end":"2026-05-29T05:49:26.53535Z","steps":["trace[593407414] 'process raft request' (duration: 472.621582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:26.535605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:49:26.062357Z","time spent":"473.148633ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:49:30.959377Z","caller":"traceutil/trace.go:171","msg":"trace[29818256] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"125.136846ms","start":"2026-05-29T05:49:30.834215Z","end":"2026-05-29T05:49:30.959352Z","steps":["trace[29818256] 'read index received' (duration: 25.673204ms)","trace[29818256] 'applied index is now lower than readState.Index' (duration: 99.462072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:30.959665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.421782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-29T05:49:30.959881Z","caller":"traceutil/trace.go:171","msg":"trace[375895238] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1412; }","duration":"125.66732ms","start":"2026-05-29T05:49:30.834191Z","end":"2026-05-29T05:49:30.959859Z","steps":["trace[375895238] 'agreement among raft nodes before linearized reading' (duration: 125.269965ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:31.147924Z","caller":"traceutil/trace.go:171","msg":"trace[395957165] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"183.169896ms","start":"2026-05-29T05:49:30.964729Z","end":"2026-05-29T05:49:31.147899Z","steps":["trace[395957165] 'process raft request' (duration: 182.8808ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:33.39431Z","caller":"traceutil/trace.go:171","msg":"trace[724849477] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"176.578384ms","start":"2026-05-29T05:49:33.217704Z","end":"2026-05-29T05:49:33.394283Z","steps":["trace[724849477] 'process raft request' (duration: 176.411447ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:33.411977Z","caller":"traceutil/trace.go:171","msg":"trace[255824742] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"147.706863ms","start":"2026-05-29T05:49:33.26424Z","end":"2026-05-29T05:49:33.411947Z","steps":["trace[255824742] 'process raft request' (duration: 147.660204ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:33.412033Z","caller":"traceutil/trace.go:171","msg":"trace[1247261817] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"194.086379ms","start":"2026-05-29T05:49:33.217922Z","end":"2026-05-29T05:49:33.412009Z","steps":["trace[1247261817] 'process raft request' (duration: 193.840383ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:39.667931Z","caller":"traceutil/trace.go:171","msg":"trace[1285573928] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"208.83848ms","start":"2026-05-29T05:49:39.459062Z","end":"2026-05-29T05:49:39.667901Z","steps":["trace[1285573928] 'process raft request' (duration: 165.480634ms)","trace[1285573928] 'compare' (duration: 42.802044ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:39.870725Z","caller":"traceutil/trace.go:171","msg":"trace[266316345] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"103.022594ms","start":"2026-05-29T05:49:39.767679Z","end":"2026-05-29T05:49:39.870701Z","steps":["trace[266316345] 'read index received' (duration: 17.656852ms)","trace[266316345] 'applied index is now lower than readState.Index' (duration: 85.363082ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:39.870724Z","caller":"traceutil/trace.go:171","msg":"trace[835762281] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"198.45837ms","start":"2026-05-29T05:49:39.672231Z","end":"2026-05-29T05:49:39.870689Z","steps":["trace[835762281] 'process raft request' (duration: 113.191157ms)","trace[835762281] 'compare' (duration: 85.042157ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:39.981646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.002326ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-29T05:49:39.981805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.10837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:39.981893Z","caller":"traceutil/trace.go:171","msg":"trace[912327053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1453; }","duration":"214.271557ms","start":"2026-05-29T05:49:39.767597Z","end":"2026-05-29T05:49:39.981869Z","steps":["trace[912327053] 'agreement among raft nodes before linearized reading' (duration: 103.233591ms)","trace[912327053] 'range keys from in-memory index tree' (duration: 110.898358ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:39.982285Z","caller":"traceutil/trace.go:171","msg":"trace[798653584] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"111.440501ms","start":"2026-05-29T05:49:39.870794Z","end":"2026-05-29T05:49:39.982235Z","steps":["trace[798653584] 'read index received' (duration: 66.579µs)","trace[798653584] 'applied index is now lower than readState.Index' (duration: 111.371902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:39.982512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.086911ms","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-05-29T05:49:39.982605Z","caller":"traceutil/trace.go:171","msg":"trace[1813926526] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1453; }","duration":"121.252079ms","start":"2026-05-29T05:49:39.86134Z","end":"2026-05-29T05:49:39.982592Z","steps":["trace[1813926526] 'agreement among raft nodes before linearized reading' (duration: 121.000572ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:40.956999Z","caller":"traceutil/trace.go:171","msg":"trace[1296095842] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"161.826313ms","start":"2026-05-29T05:49:40.795148Z","end":"2026-05-29T05:49:40.956974Z","steps":["trace[1296095842] 'read index received' (duration: 161.656156ms)","trace[1296095842] 'applied index is now lower than readState.Index' (duration: 168.937µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:40.957112Z","caller":"traceutil/trace.go:171","msg":"trace[1057320458] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"256.900985ms","start":"2026-05-29T05:49:40.700178Z","end":"2026-05-29T05:49:40.957079Z","steps":["trace[1057320458] 'process raft request' (duration: 256.639378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:40.957598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.151959ms","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-05-29T05:49:40.957622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.996311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-29T05:49:40.957649Z","caller":"traceutil/trace.go:171","msg":"trace[1186367094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1455; }","duration":"162.543443ms","start":"2026-05-29T05:49:40.795093Z","end":"2026-05-29T05:49:40.957636Z","steps":["trace[1186367094] 'agreement among raft nodes before linearized reading' (duration: 162.078149ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:40.957658Z","caller":"traceutil/trace.go:171","msg":"trace[1241702613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1455; }","duration":"156.09285ms","start":"2026-05-29T05:49:40.801555Z","end":"2026-05-29T05:49:40.957647Z","steps":["trace[1241702613] 'agreement among raft nodes before linearized reading' (duration: 155.750165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:49:40.957696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.376416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:49:40.957775Z","caller":"traceutil/trace.go:171","msg":"trace[278229502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1455; }","duration":"156.522013ms","start":"2026-05-29T05:49:40.801234Z","end":"2026-05-29T05:49:40.957756Z","steps":["trace[278229502] 'agreement among raft nodes before linearized reading' (duration: 156.0669ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:41.076319Z","caller":"traceutil/trace.go:171","msg":"trace[910702890] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"115.20609ms","start":"2026-05-29T05:49:40.961089Z","end":"2026-05-29T05:49:41.076295Z","steps":["trace[910702890] 'process raft request' (duration: 115.035592ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:41.115143Z","caller":"traceutil/trace.go:171","msg":"trace[2020388552] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"152.836624ms","start":"2026-05-29T05:49:40.96228Z","end":"2026-05-29T05:49:41.115117Z","steps":["trace[2020388552] 'process raft request' (duration: 152.666446ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:41.115422Z","caller":"traceutil/trace.go:171","msg":"trace[1083731750] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"152.594487ms","start":"2026-05-29T05:49:40.962779Z","end":"2026-05-29T05:49:41.115374Z","steps":["trace[1083731750] 'process raft request' (duration: 152.292971ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:43.665339Z","caller":"traceutil/trace.go:171","msg":"trace[100339945] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"185.001601ms","start":"2026-05-29T05:49:43.480309Z","end":"2026-05-29T05:49:43.665311Z","steps":["trace[100339945] 'process raft request' (duration: 184.842913ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:43.719878Z","caller":"traceutil/trace.go:171","msg":"trace[1473257989] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"222.20461ms","start":"2026-05-29T05:49:43.497656Z","end":"2026-05-29T05:49:43.71986Z","steps":["trace[1473257989] 'process raft request' (duration: 221.779176ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:45.91288Z","caller":"traceutil/trace.go:171","msg":"trace[511935137] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1540; }","duration":"147.690003ms","start":"2026-05-29T05:49:45.765164Z","end":"2026-05-29T05:49:45.912854Z","steps":["trace[511935137] 'read index received' (duration: 147.472818ms)","trace[511935137] 'applied index is now lower than readState.Index' (duration: 215.915µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:49:45.913076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.869796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:49:45.913184Z","caller":"traceutil/trace.go:171","msg":"trace[2063397571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1480; }","duration":"148.03478ms","start":"2026-05-29T05:49:45.76513Z","end":"2026-05-29T05:49:45.913158Z","steps":["trace[2063397571] 'agreement among raft nodes before linearized reading' (duration: 147.864997ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:45.913218Z","caller":"traceutil/trace.go:171","msg":"trace[1180341885] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"166.035455ms","start":"2026-05-29T05:49:45.747137Z","end":"2026-05-29T05:49:45.913173Z","steps":["trace[1180341885] 'process raft request' (duration: 165.556865ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:49:54.389425Z","caller":"traceutil/trace.go:171","msg":"trace[341297173] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"100.11198ms","start":"2026-05-29T05:49:54.289289Z","end":"2026-05-29T05:49:54.389401Z","steps":["trace[341297173] 'process raft request' (duration: 54.004466ms)","trace[341297173] 'compare' (duration: 46.002352ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:49:58.40067Z","caller":"traceutil/trace.go:171","msg":"trace[1849805572] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"143.603844ms","start":"2026-05-29T05:49:58.257044Z","end":"2026-05-29T05:49:58.400648Z","steps":["trace[1849805572] 'process raft request' (duration: 143.47803ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:07.312201Z","caller":"traceutil/trace.go:171","msg":"trace[1212643000] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"108.50184ms","start":"2026-05-29T05:50:07.203628Z","end":"2026-05-29T05:50:07.31213Z","steps":["trace[1212643000] 'process raft request' (duration: 108.315477ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:13.060351Z","caller":"traceutil/trace.go:171","msg":"trace[811170301] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"153.8213ms","start":"2026-05-29T05:50:12.906506Z","end":"2026-05-29T05:50:13.060328Z","steps":["trace[811170301] 'process raft request' (duration: 153.685137ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:13.337349Z","caller":"traceutil/trace.go:171","msg":"trace[1024705782] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"209.683192ms","start":"2026-05-29T05:50:13.127633Z","end":"2026-05-29T05:50:13.337316Z","steps":["trace[1024705782] 'process raft request' (duration: 110.681023ms)","trace[1024705782] 'compare' (duration: 98.876205ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:18.232369Z","caller":"traceutil/trace.go:171","msg":"trace[680233163] linearizableReadLoop","detail":"{readStateIndex:1692; appliedIndex:1691; }","duration":"241.235697ms","start":"2026-05-29T05:50:17.991087Z","end":"2026-05-29T05:50:18.232323Z","steps":["trace[680233163] 'read index received' (duration: 241.058374ms)","trace[680233163] 'applied index is now lower than readState.Index' (duration: 176.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:18.232404Z","caller":"traceutil/trace.go:171","msg":"trace[1019144727] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"301.33772ms","start":"2026-05-29T05:50:17.931035Z","end":"2026-05-29T05:50:18.232372Z","steps":["trace[1019144727] 'process raft request' (duration: 301.106505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:50:18.232557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:50:17.930986Z","time spent":"301.485743ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:50:18.232561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.44012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:50:18.232613Z","caller":"traceutil/trace.go:171","msg":"trace[1765121856] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1625; }","duration":"241.542353ms","start":"2026-05-29T05:50:17.991056Z","end":"2026-05-29T05:50:18.232599Z","steps":["trace[1765121856] 'agreement among raft nodes before linearized reading' (duration: 241.425291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:50:18.232727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.921921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-29T05:50:18.232823Z","caller":"traceutil/trace.go:171","msg":"trace[1129938147] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1625; }","duration":"167.061814ms","start":"2026-05-29T05:50:18.065739Z","end":"2026-05-29T05:50:18.232801Z","steps":["trace[1129938147] 'agreement among raft nodes before linearized reading' (duration: 166.806359ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:18.360203Z","caller":"traceutil/trace.go:171","msg":"trace[1051338611] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"122.461678ms","start":"2026-05-29T05:50:18.237717Z","end":"2026-05-29T05:50:18.360178Z","steps":["trace[1051338611] 'process raft request' (duration: 112.262023ms)","trace[1051338611] 'compare' (duration: 10.128634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:50:18.676697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.312868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-29T05:50:18.676877Z","caller":"traceutil/trace.go:171","msg":"trace[865594137] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1627; }","duration":"131.562043ms","start":"2026-05-29T05:50:18.545292Z","end":"2026-05-29T05:50:18.676854Z","steps":["trace[865594137] 'range keys from in-memory index tree' (duration: 131.087984ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:24.445649Z","caller":"traceutil/trace.go:171","msg":"trace[1386440515] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"141.521294ms","start":"2026-05-29T05:50:24.304106Z","end":"2026-05-29T05:50:24.445627Z","steps":["trace[1386440515] 'process raft request' (duration: 141.449003ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:24.445706Z","caller":"traceutil/trace.go:171","msg":"trace[1731070661] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"144.362189ms","start":"2026-05-29T05:50:24.301306Z","end":"2026-05-29T05:50:24.445668Z","steps":["trace[1731070661] 'process raft request' (duration: 49.608412ms)","trace[1731070661] 'compare' (duration: 94.40482ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:24.634448Z","caller":"traceutil/trace.go:171","msg":"trace[1400275674] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"169.571092ms","start":"2026-05-29T05:50:24.464855Z","end":"2026-05-29T05:50:24.634426Z","steps":["trace[1400275674] 'process raft request' (duration: 169.421969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:50:25.086088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.25561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-29T05:50:25.086167Z","caller":"traceutil/trace.go:171","msg":"trace[1786965324] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1657; }","duration":"104.390352ms","start":"2026-05-29T05:50:24.981759Z","end":"2026-05-29T05:50:25.086149Z","steps":["trace[1786965324] 'range keys from in-memory index tree' (duration: 104.065336ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:25.229754Z","caller":"traceutil/trace.go:171","msg":"trace[94248533] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"138.74588ms","start":"2026-05-29T05:50:25.090988Z","end":"2026-05-29T05:50:25.229734Z","steps":["trace[94248533] 'process raft request' (duration: 138.592167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:50:38.612479Z","caller":"traceutil/trace.go:171","msg":"trace[1181933654] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"102.050255ms","start":"2026-05-29T05:50:38.510403Z","end":"2026-05-29T05:50:38.612453Z","steps":["trace[1181933654] 'process raft request' (duration: 44.98882ms)","trace[1181933654] 'compare' (duration: 56.82223ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:55.928972Z","caller":"traceutil/trace.go:171","msg":"trace[1576273032] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"106.712036ms","start":"2026-05-29T05:50:55.822219Z","end":"2026-05-29T05:50:55.928931Z","steps":["trace[1576273032] 'process raft request' (duration: 43.421971ms)","trace[1576273032] 'compare' (duration: 63.144042ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:50:56.12936Z","caller":"traceutil/trace.go:171","msg":"trace[36380084] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"196.469594ms","start":"2026-05-29T05:50:55.93285Z","end":"2026-05-29T05:50:56.12932Z","steps":["trace[36380084] 'process raft request' (duration: 130.850134ms)","trace[36380084] 'compare' (duration: 65.492447ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:51:09.803259Z","caller":"traceutil/trace.go:171","msg":"trace[836287695] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"110.746315ms","start":"2026-05-29T05:51:09.692446Z","end":"2026-05-29T05:51:09.803192Z","steps":["trace[836287695] 'process raft request' (duration: 110.379047ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:51:12.049238Z","caller":"traceutil/trace.go:171","msg":"trace[2068738620] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"159.490419ms","start":"2026-05-29T05:51:11.889717Z","end":"2026-05-29T05:51:12.049208Z","steps":["trace[2068738620] 'read index received' (duration: 159.258384ms)","trace[2068738620] 'applied index is now lower than readState.Index' (duration: 230.885µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:51:12.049357Z","caller":"traceutil/trace.go:171","msg":"trace[100409604] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"240.046729ms","start":"2026-05-29T05:51:11.809276Z","end":"2026-05-29T05:51:12.049323Z","steps":["trace[100409604] 'process raft request' (duration: 239.719152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:51:12.049553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.816035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-29T05:51:12.049679Z","caller":"traceutil/trace.go:171","msg":"trace[2090960297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"159.973248ms","start":"2026-05-29T05:51:11.889664Z","end":"2026-05-29T05:51:12.049638Z","steps":["trace[2090960297] 'agreement among raft nodes before linearized reading' (duration: 159.700613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:51:12.220133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.830547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:51:12.220458Z","caller":"traceutil/trace.go:171","msg":"trace[117479777] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"166.551795ms","start":"2026-05-29T05:51:12.053862Z","end":"2026-05-29T05:51:12.220413Z","steps":["trace[117479777] 'process raft request' (duration: 10.33271ms)","trace[117479777] 'compare' (duration: 155.678685ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:51:12.220553Z","caller":"traceutil/trace.go:171","msg":"trace[1073368956] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"166.570675ms","start":"2026-05-29T05:51:12.053955Z","end":"2026-05-29T05:51:12.220526Z","steps":["trace[1073368956] 'process raft request' (duration: 166.30321ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:51:18.347847Z","caller":"traceutil/trace.go:171","msg":"trace[245254024] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"101.330021ms","start":"2026-05-29T05:51:18.246493Z","end":"2026-05-29T05:51:18.347823Z","steps":["trace[245254024] 'process raft request' (duration: 101.106347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:51:18.707082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.927906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:51:18.707219Z","caller":"traceutil/trace.go:171","msg":"trace[92940581] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"182.351351ms","start":"2026-05-29T05:51:18.524843Z","end":"2026-05-29T05:51:18.707194Z","steps":["trace[92940581] 'process raft request' (duration: 34.197232ms)","trace[92940581] 'compare' (duration: 147.799342ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:51:18.895913Z","caller":"traceutil/trace.go:171","msg":"trace[2044289985] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"103.125487ms","start":"2026-05-29T05:51:18.792759Z","end":"2026-05-29T05:51:18.895885Z","steps":["trace[2044289985] 'process raft request' (duration: 73.751338ms)","trace[2044289985] 'compare' (duration: 29.255067ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:51:34.745476Z","caller":"traceutil/trace.go:171","msg":"trace[953930106] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"129.58685ms","start":"2026-05-29T05:51:34.615873Z","end":"2026-05-29T05:51:34.74546Z","steps":["trace[953930106] 'process raft request' (duration: 129.467078ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:51:34.747587Z","caller":"traceutil/trace.go:171","msg":"trace[530735791] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"131.141738ms","start":"2026-05-29T05:51:34.616435Z","end":"2026-05-29T05:51:34.747577Z","steps":["trace[530735791] 'process raft request' (duration: 131.045566ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:51:34.747777Z","caller":"traceutil/trace.go:171","msg":"trace[412152851] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"131.253511ms","start":"2026-05-29T05:51:34.6165Z","end":"2026-05-29T05:51:34.747753Z","steps":["trace[412152851] 'process raft request' (duration: 131.034786ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:51:50.736158Z","caller":"traceutil/trace.go:171","msg":"trace[1013545409] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"287.658726ms","start":"2026-05-29T05:51:50.448463Z","end":"2026-05-29T05:51:50.736122Z","steps":["trace[1013545409] 'process raft request' (duration: 287.42813ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:52:03.938979Z","caller":"traceutil/trace.go:171","msg":"trace[1156257819] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"113.554868ms","start":"2026-05-29T05:52:03.825398Z","end":"2026-05-29T05:52:03.938953Z","steps":["trace[1156257819] 'process raft request' (duration: 113.429995ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:52:39.14203Z","caller":"traceutil/trace.go:171","msg":"trace[244657007] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"128.666355ms","start":"2026-05-29T05:52:39.013328Z","end":"2026-05-29T05:52:39.141995Z","steps":["trace[244657007] 'process raft request' (duration: 128.457531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:52:39.352704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.619698ms","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-05-29T05:52:39.352826Z","caller":"traceutil/trace.go:171","msg":"trace[1253125435] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2269; }","duration":"115.819892ms","start":"2026-05-29T05:52:39.236979Z","end":"2026-05-29T05:52:39.352799Z","steps":["trace[1253125435] 'count revisions from in-memory index tree' (duration: 115.471035ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:52:39.539555Z","caller":"traceutil/trace.go:171","msg":"trace[201904645] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"113.997127ms","start":"2026-05-29T05:52:39.425514Z","end":"2026-05-29T05:52:39.539512Z","steps":["trace[201904645] 'process raft request' (duration: 28.751704ms)","trace[201904645] 'compare' (duration: 84.991418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:52:39.785895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.639697ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:52:39.786584Z","caller":"traceutil/trace.go:171","msg":"trace[824517356] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"242.638551ms","start":"2026-05-29T05:52:39.543919Z","end":"2026-05-29T05:52:39.786558Z","steps":["trace[824517356] 'process raft request' (duration: 242.333985ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:52:41.557116Z","caller":"traceutil/trace.go:171","msg":"trace[301601895] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"136.05983ms","start":"2026-05-29T05:52:41.42103Z","end":"2026-05-29T05:52:41.55709Z","steps":["trace[301601895] 'process raft request' (duration: 135.778124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:52:59.716531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.157115ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:53:03.700495Z","caller":"traceutil/trace.go:171","msg":"trace[342284587] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"105.888281ms","start":"2026-05-29T05:53:03.594572Z","end":"2026-05-29T05:53:03.70046Z","steps":["trace[342284587] 'process raft request' (duration: 105.779479ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:04.214221Z","caller":"traceutil/trace.go:171","msg":"trace[1081815911] transaction","detail":"{read_only:false; response_revision:2380; number_of_response:1; }","duration":"292.426031ms","start":"2026-05-29T05:53:03.921772Z","end":"2026-05-29T05:53:04.214198Z","steps":["trace[1081815911] 'process raft request' (duration: 292.274128ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:04.347037Z","caller":"traceutil/trace.go:171","msg":"trace[1499039115] linearizableReadLoop","detail":"{readStateIndex:2481; appliedIndex:2480; }","duration":"318.906646ms","start":"2026-05-29T05:53:04.028087Z","end":"2026-05-29T05:53:04.346994Z","steps":["trace[1499039115] 'read index received' (duration: 186.219154ms)","trace[1499039115] 'applied index is now lower than readState.Index' (duration: 132.685562ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:04.34728Z","caller":"traceutil/trace.go:171","msg":"trace[60038190] transaction","detail":"{read_only:false; response_revision:2381; number_of_response:1; }","duration":"424.794907ms","start":"2026-05-29T05:53:03.922456Z","end":"2026-05-29T05:53:04.347251Z","steps":["trace[60038190] 'process raft request' (duration: 365.941102ms)","trace[60038190] 'compare' (duration: 58.386456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:04.347345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.242532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:53:04.347423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:03.922444Z","time spent":"424.903199ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:53:04.34743Z","caller":"traceutil/trace.go:171","msg":"trace[1841243273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2381; }","duration":"319.340124ms","start":"2026-05-29T05:53:04.028069Z","end":"2026-05-29T05:53:04.347409Z","steps":["trace[1841243273] 'agreement among raft nodes before linearized reading' (duration: 319.164741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:04.347489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:04.027997Z","time spent":"319.476267ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:53:04.347505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.966974ms","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-05-29T05:53:04.34758Z","caller":"traceutil/trace.go:171","msg":"trace[662720168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2381; }","duration":"175.085717ms","start":"2026-05-29T05:53:04.172481Z","end":"2026-05-29T05:53:04.347567Z","steps":["trace[662720168] 'agreement among raft nodes before linearized reading' (duration: 174.843152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:04.347788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.268829ms","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-05-29T05:53:04.347846Z","caller":"traceutil/trace.go:171","msg":"trace[1993767700] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2381; }","duration":"123.34438ms","start":"2026-05-29T05:53:04.22447Z","end":"2026-05-29T05:53:04.347815Z","steps":["trace[1993767700] 'agreement among raft nodes before linearized reading' (duration: 123.226808ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:04.485329Z","caller":"traceutil/trace.go:171","msg":"trace[1879977435] transaction","detail":"{read_only:false; response_revision:2382; number_of_response:1; }","duration":"131.990039ms","start":"2026-05-29T05:53:04.353313Z","end":"2026-05-29T05:53:04.485303Z","steps":["trace[1879977435] 'process raft request' (duration: 121.165459ms)","trace[1879977435] 'compare' (duration: 10.556965ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:04.485783Z","caller":"traceutil/trace.go:171","msg":"trace[533152317] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"131.53417ms","start":"2026-05-29T05:53:04.354216Z","end":"2026-05-29T05:53:04.48575Z","steps":["trace[533152317] 'process raft request' (duration: 131.04225ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:04.485838Z","caller":"traceutil/trace.go:171","msg":"trace[1702855814] transaction","detail":"{read_only:false; response_revision:2384; number_of_response:1; }","duration":"129.823986ms","start":"2026-05-29T05:53:04.35599Z","end":"2026-05-29T05:53:04.485814Z","steps":["trace[1702855814] 'process raft request' (duration: 129.696334ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:20.212821Z","caller":"traceutil/trace.go:171","msg":"trace[2050070102] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"300.132049ms","start":"2026-05-29T05:53:19.912661Z","end":"2026-05-29T05:53:20.212793Z","steps":["trace[2050070102] 'process raft request' (duration: 299.971266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:20.212985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:19.912629Z","time spent":"300.279272ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:53:20.602728Z","caller":"traceutil/trace.go:171","msg":"trace[1044234093] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2580; }","duration":"162.915661ms","start":"2026-05-29T05:53:20.439791Z","end":"2026-05-29T05:53:20.602707Z","steps":["trace[1044234093] 'read index received' (duration: 162.722038ms)","trace[1044234093] 'applied index is now lower than readState.Index' (duration: 192.703µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:20.602804Z","caller":"traceutil/trace.go:171","msg":"trace[1619462452] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"170.908776ms","start":"2026-05-29T05:53:20.431868Z","end":"2026-05-29T05:53:20.602777Z","steps":["trace[1619462452] 'process raft request' (duration: 170.703682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:20.602961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.151976ms","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-05-29T05:53:20.603004Z","caller":"traceutil/trace.go:171","msg":"trace[1842829188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2476; }","duration":"163.232047ms","start":"2026-05-29T05:53:20.439763Z","end":"2026-05-29T05:53:20.602995Z","steps":["trace[1842829188] 'agreement among raft nodes before linearized reading' (duration: 163.091594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:20.603246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.761949ms","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:508"} {"level":"info","ts":"2026-05-29T05:53:20.603284Z","caller":"traceutil/trace.go:171","msg":"trace[623832366] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2476; }","duration":"158.886071ms","start":"2026-05-29T05:53:20.444387Z","end":"2026-05-29T05:53:20.603273Z","steps":["trace[623832366] 'agreement among raft nodes before linearized reading' (duration: 158.739538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:20.96482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.625903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:53:20.964914Z","caller":"traceutil/trace.go:171","msg":"trace[2039227608] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2584; }","duration":"197.069264ms","start":"2026-05-29T05:53:20.76783Z","end":"2026-05-29T05:53:20.964899Z","steps":["trace[2039227608] 'read index received' (duration: 50.273548ms)","trace[2039227608] 'applied index is now lower than readState.Index' (duration: 146.794546ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:20.964989Z","caller":"traceutil/trace.go:171","msg":"trace[1604233658] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"277.669814ms","start":"2026-05-29T05:53:20.687308Z","end":"2026-05-29T05:53:20.964978Z","steps":["trace[1604233658] 'process raft request' (duration: 130.800596ms)","trace[1604233658] 'compare' (duration: 146.513081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:20.96516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.301809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:53:20.96525Z","caller":"traceutil/trace.go:171","msg":"trace[632873601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2480; }","duration":"197.426022ms","start":"2026-05-29T05:53:20.7678Z","end":"2026-05-29T05:53:20.965226Z","steps":["trace[632873601] 'agreement among raft nodes before linearized reading' (duration: 197.236659ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:22.57296Z","caller":"traceutil/trace.go:171","msg":"trace[1460758738] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"141.31377ms","start":"2026-05-29T05:53:22.431611Z","end":"2026-05-29T05:53:22.572924Z","steps":["trace[1460758738] 'process raft request' (duration: 141.068285ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:22.572889Z","caller":"traceutil/trace.go:171","msg":"trace[612551530] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2593; }","duration":"105.097985ms","start":"2026-05-29T05:53:22.467767Z","end":"2026-05-29T05:53:22.572865Z","steps":["trace[612551530] 'read index received' (duration: 104.893101ms)","trace[612551530] 'applied index is now lower than readState.Index' (duration: 203.924µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:22.57324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.448641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-05-29T05:53:22.573354Z","caller":"traceutil/trace.go:171","msg":"trace[1335062168] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2489; }","duration":"105.587674ms","start":"2026-05-29T05:53:22.467717Z","end":"2026-05-29T05:53:22.573305Z","steps":["trace[1335062168] 'agreement among raft nodes before linearized reading' (duration: 105.314709ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:28.22321Z","caller":"traceutil/trace.go:171","msg":"trace[1335222637] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"154.616788ms","start":"2026-05-29T05:53:28.068554Z","end":"2026-05-29T05:53:28.22317Z","steps":["trace[1335222637] 'process raft request' (duration: 69.541244ms)","trace[1335222637] 'compare' (duration: 84.937912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:28.808295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.284281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:53:28.808366Z","caller":"traceutil/trace.go:171","msg":"trace[60118450] linearizableReadLoop","detail":"{readStateIndex:2624; appliedIndex:2623; }","duration":"146.687214ms","start":"2026-05-29T05:53:28.661666Z","end":"2026-05-29T05:53:28.808353Z","steps":["trace[60118450] 'read index received' (duration: 40.561µs)","trace[60118450] 'applied index is now lower than readState.Index' (duration: 146.645863ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:28.808719Z","caller":"traceutil/trace.go:171","msg":"trace[864529181] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"548.84273ms","start":"2026-05-29T05:53:28.259863Z","end":"2026-05-29T05:53:28.808705Z","steps":["trace[864529181] 'process raft request' (duration: 319.084449ms)","trace[864529181] 'compare' (duration: 228.985226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:28.808778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:28.259846Z","time spent":"548.905921ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:53:28.809108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.853958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-29T05:53:28.809138Z","caller":"traceutil/trace.go:171","msg":"trace[46794382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2518; }","duration":"147.50621ms","start":"2026-05-29T05:53:28.661624Z","end":"2026-05-29T05:53:28.80913Z","steps":["trace[46794382] 'agreement among raft nodes before linearized reading' (duration: 146.826937ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:29.017391Z","caller":"traceutil/trace.go:171","msg":"trace[505949183] linearizableReadLoop","detail":"{readStateIndex:2626; appliedIndex:2624; }","duration":"204.801446ms","start":"2026-05-29T05:53:28.812571Z","end":"2026-05-29T05:53:29.017373Z","steps":["trace[505949183] 'read index received' (duration: 72.698024ms)","trace[505949183] 'applied index is now lower than readState.Index' (duration: 132.102502ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:29.01742Z","caller":"traceutil/trace.go:171","msg":"trace[818174038] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"204.982509ms","start":"2026-05-29T05:53:28.812406Z","end":"2026-05-29T05:53:29.017389Z","steps":["trace[818174038] 'process raft request' (duration: 124.233718ms)","trace[818174038] 'compare' (duration: 80.523237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:29.017506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.922588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:53:29.017888Z","caller":"traceutil/trace.go:171","msg":"trace[241556168] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2521; }","duration":"205.343696ms","start":"2026-05-29T05:53:28.812533Z","end":"2026-05-29T05:53:29.017877Z","steps":["trace[241556168] 'agreement among raft nodes before linearized reading' (duration: 204.914468ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:29.017496Z","caller":"traceutil/trace.go:171","msg":"trace[1972887355] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"204.691723ms","start":"2026-05-29T05:53:28.812775Z","end":"2026-05-29T05:53:29.017466Z","steps":["trace[1972887355] 'process raft request' (duration: 204.56067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:30.041887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.144274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-29T05:53:30.042032Z","caller":"traceutil/trace.go:171","msg":"trace[1280563028] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2523; }","duration":"460.338848ms","start":"2026-05-29T05:53:29.581652Z","end":"2026-05-29T05:53:30.041991Z","steps":["trace[1280563028] 'range keys from in-memory index tree' (duration: 459.869899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:30.042106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:29.581632Z","time spent":"460.456481ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T05:53:30.057001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.606332ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:53:30.057331Z","caller":"traceutil/trace.go:171","msg":"trace[1890247263] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"199.578544ms","start":"2026-05-29T05:53:29.857731Z","end":"2026-05-29T05:53:30.05731Z","steps":["trace[1890247263] 'process raft request' (duration: 199.37425ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:30.057319Z","caller":"traceutil/trace.go:171","msg":"trace[895565891] linearizableReadLoop","detail":"{readStateIndex:2630; appliedIndex:2629; }","duration":"370.327796ms","start":"2026-05-29T05:53:29.686904Z","end":"2026-05-29T05:53:30.057232Z","steps":["trace[895565891] 'read index received' (duration: 221.49748ms)","trace[895565891] 'applied index is now lower than readState.Index' (duration: 148.826306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:30.057636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.641443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T05:53:30.057697Z","caller":"traceutil/trace.go:171","msg":"trace[1221549837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2524; }","duration":"370.844507ms","start":"2026-05-29T05:53:29.686839Z","end":"2026-05-29T05:53:30.057683Z","steps":["trace[1221549837] 'agreement among raft nodes before linearized reading' (duration: 370.53151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:30.057742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:29.686816Z","time spent":"370.916838ms","remote":"127.0.0.1:38952","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-05-29T05:53:30.985641Z","caller":"traceutil/trace.go:171","msg":"trace[853868388] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"169.887356ms","start":"2026-05-29T05:53:30.815722Z","end":"2026-05-29T05:53:30.98561Z","steps":["trace[853868388] 'process raft request' (duration: 97.028228ms)","trace[853868388] 'compare' (duration: 72.632604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:40.994206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.038203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-05-29T05:53:40.994292Z","caller":"traceutil/trace.go:171","msg":"trace[1705392180] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:2579; }","duration":"477.163646ms","start":"2026-05-29T05:53:40.517107Z","end":"2026-05-29T05:53:40.994271Z","steps":["trace[1705392180] 'range keys from in-memory index tree' (duration: 476.955752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:40.994334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:40.517088Z","time spent":"477.235807ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":718,"request content":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" "} {"level":"warn","ts":"2026-05-29T05:53:40.994186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.008815ms","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:508"} {"level":"info","ts":"2026-05-29T05:53:40.994669Z","caller":"traceutil/trace.go:171","msg":"trace[310488774] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2579; }","duration":"232.536115ms","start":"2026-05-29T05:53:40.762092Z","end":"2026-05-29T05:53:40.994628Z","steps":["trace[310488774] 'range keys from in-memory index tree' (duration: 231.803461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:40.994201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.951436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:53:40.994782Z","caller":"traceutil/trace.go:171","msg":"trace[1624885310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2579; }","duration":"227.564058ms","start":"2026-05-29T05:53:40.767188Z","end":"2026-05-29T05:53:40.994752Z","steps":["trace[1624885310] 'range keys from in-memory index tree' (duration: 226.882695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:40.99456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.211346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:882"} {"level":"info","ts":"2026-05-29T05:53:40.994925Z","caller":"traceutil/trace.go:171","msg":"trace[2095903061] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:2579; }","duration":"477.569204ms","start":"2026-05-29T05:53:40.517305Z","end":"2026-05-29T05:53:40.994874Z","steps":["trace[2095903061] 'range keys from in-memory index tree' (duration: 477.116325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:40.995095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:40.517287Z","time spent":"477.742406ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":905,"request content":"key:\"/registry/clusterroles/system:controller:expand-controller\" "} {"level":"warn","ts":"2026-05-29T05:53:41.387358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.448621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:53:41.387514Z","caller":"traceutil/trace.go:171","msg":"trace[2060844750] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"218.809099ms","start":"2026-05-29T05:53:41.168681Z","end":"2026-05-29T05:53:41.38749Z","steps":["trace[2060844750] 'process raft request' (duration: 94.143913ms)","trace[2060844750] 'compare' (duration: 124.343949ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:41.387881Z","caller":"traceutil/trace.go:171","msg":"trace[1963024798] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"218.735546ms","start":"2026-05-29T05:53:41.16913Z","end":"2026-05-29T05:53:41.387866Z","steps":["trace[1963024798] 'process raft request' (duration: 218.324849ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:41.387982Z","caller":"traceutil/trace.go:171","msg":"trace[1814282150] linearizableReadLoop","detail":"{readStateIndex:2694; appliedIndex:2692; }","duration":"150.55756ms","start":"2026-05-29T05:53:41.237396Z","end":"2026-05-29T05:53:41.387953Z","steps":["trace[1814282150] 'read index received' (duration: 25.367634ms)","trace[1814282150] 'applied index is now lower than readState.Index' (duration: 125.187836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:41.388253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.686826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:949"} {"level":"warn","ts":"2026-05-29T05:53:41.388305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.779942ms","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-05-29T05:53:41.388306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.905176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:944"} {"level":"info","ts":"2026-05-29T05:53:41.388347Z","caller":"traceutil/trace.go:171","msg":"trace[116097774] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2586; }","duration":"143.799478ms","start":"2026-05-29T05:53:41.244533Z","end":"2026-05-29T05:53:41.388333Z","steps":["trace[116097774] 'agreement among raft nodes before linearized reading' (duration: 143.570624ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:41.38836Z","caller":"traceutil/trace.go:171","msg":"trace[2135828787] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2586; }","duration":"150.988388ms","start":"2026-05-29T05:53:41.237359Z","end":"2026-05-29T05:53:41.388347Z","steps":["trace[2135828787] 'agreement among raft nodes before linearized reading' (duration: 150.693273ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:41.388386Z","caller":"traceutil/trace.go:171","msg":"trace[2110046147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2586; }","duration":"115.883975ms","start":"2026-05-29T05:53:41.272457Z","end":"2026-05-29T05:53:41.388341Z","steps":["trace[2110046147] 'agreement among raft nodes before linearized reading' (duration: 115.739482ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:41.554353Z","caller":"traceutil/trace.go:171","msg":"trace[725775444] linearizableReadLoop","detail":"{readStateIndex:2696; appliedIndex:2695; }","duration":"160.863641ms","start":"2026-05-29T05:53:41.39346Z","end":"2026-05-29T05:53:41.554324Z","steps":["trace[725775444] 'read index received' (duration: 155.546837ms)","trace[725775444] 'applied index is now lower than readState.Index' (duration: 5.315534ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:53:41.554435Z","caller":"traceutil/trace.go:171","msg":"trace[1748160797] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"161.071775ms","start":"2026-05-29T05:53:41.39334Z","end":"2026-05-29T05:53:41.554412Z","steps":["trace[1748160797] 'process raft request' (duration: 155.553437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:41.554587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.102035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:944"} {"level":"info","ts":"2026-05-29T05:53:41.554643Z","caller":"traceutil/trace.go:171","msg":"trace[1041652391] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2587; }","duration":"161.186187ms","start":"2026-05-29T05:53:41.39344Z","end":"2026-05-29T05:53:41.554626Z","steps":["trace[1041652391] 'agreement among raft nodes before linearized reading' (duration: 161.014083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:41.554875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.33371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-05-29T05:53:41.554946Z","caller":"traceutil/trace.go:171","msg":"trace[1320898264] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2587; }","duration":"161.428951ms","start":"2026-05-29T05:53:41.393499Z","end":"2026-05-29T05:53:41.554928Z","steps":["trace[1320898264] 'agreement among raft nodes before linearized reading' (duration: 161.034954ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:45.231724Z","caller":"traceutil/trace.go:171","msg":"trace[1117682521] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"135.016798ms","start":"2026-05-29T05:53:45.096684Z","end":"2026-05-29T05:53:45.231701Z","steps":["trace[1117682521] 'process raft request' (duration: 134.862275ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:45.233616Z","caller":"traceutil/trace.go:171","msg":"trace[1090151316] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"136.023818ms","start":"2026-05-29T05:53:45.09756Z","end":"2026-05-29T05:53:45.233584Z","steps":["trace[1090151316] 'process raft request' (duration: 135.840133ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:45.233503Z","caller":"traceutil/trace.go:171","msg":"trace[1088557867] linearizableReadLoop","detail":"{readStateIndex:2710; appliedIndex:2708; }","duration":"115.534729ms","start":"2026-05-29T05:53:45.117953Z","end":"2026-05-29T05:53:45.233487Z","steps":["trace[1088557867] 'read index received' (duration: 113.595411ms)","trace[1088557867] 'applied index is now lower than readState.Index' (duration: 1.938508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:45.233805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.838314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-29T05:53:45.233866Z","caller":"traceutil/trace.go:171","msg":"trace[1664769867] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:2600; }","duration":"115.944287ms","start":"2026-05-29T05:53:45.117903Z","end":"2026-05-29T05:53:45.233847Z","steps":["trace[1664769867] 'agreement among raft nodes before linearized reading' (duration: 115.839435ms)"],"step_count":1}