{"level":"warn","ts":"2026-07-24T04:42:23.822034Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:42:23.822273Z","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-07-24T04:42:23.822418Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:42:23.822448Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-24T04:42:23.822492Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T04:42:23.823435Z","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-07-24T04:42:23.823513Z","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-07-24T04:42:23.827801Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.096624ms"} {"level":"info","ts":"2026-07-24T04:42:23.837346Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-07-24T04:42:23.837418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:42:23.837439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-07-24T04:42:23.837449Z","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-07-24T04:42:23.837457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-07-24T04:42:23.837494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-07-24T04:42:23.844891Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:42:23.845878Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:42:23.846899Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-24T04:42:23.848425Z","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-07-24T04:42:23.848536Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T04:42:23.848623Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T04:42:23.848631Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T04:42:23.848641Z","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-07-24T04:42:23.85182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-07-24T04:42:23.852012Z","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-07-24T04:42:23.852678Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T04:42:23.852814Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-24T04:42:23.85284Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-24T04:42:23.852963Z","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-07-24T04:42:23.852993Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:42:24.5379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:42:24.537939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:42:24.537963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-07-24T04:42:24.537975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:42:24.53798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-24T04:42:24.537989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-07-24T04:42:24.537996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-24T04:42:24.545225Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:24.54659Z","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-07-24T04:42:24.546665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:42:24.54671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:42:24.548288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-07-24T04:42:24.548962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:42:24.548991Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:42:24.549569Z","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-07-24T04:42:24.549755Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:24.5498Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:24.549789Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:42:26.346622Z","caller":"traceutil/trace.go:171","msg":"trace[1864651766] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"125.663163ms","start":"2026-07-24T04:42:26.220865Z","end":"2026-07-24T04:42:26.346528Z","steps":["trace[1864651766] 'process raft request' (duration: 125.492129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:01.696906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.941341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:43:01.697117Z","caller":"traceutil/trace.go:171","msg":"trace[404919577] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"162.544406ms","start":"2026-07-24T04:43:01.534563Z","end":"2026-07-24T04:43:01.697107Z","steps":["trace[404919577] 'process raft request' (duration: 162.498475ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:01.6973Z","caller":"traceutil/trace.go:171","msg":"trace[720972324] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"192.931417ms","start":"2026-07-24T04:43:01.504319Z","end":"2026-07-24T04:43:01.697251Z","steps":["trace[720972324] 'process raft request' (duration: 90.279692ms)","trace[720972324] 'compare' (duration: 101.846869ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:01.697329Z","caller":"traceutil/trace.go:171","msg":"trace[1576685805] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"192.781814ms","start":"2026-07-24T04:43:01.504516Z","end":"2026-07-24T04:43:01.697298Z","steps":["trace[1576685805] 'process raft request' (duration: 192.481638ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:07.795242Z","caller":"traceutil/trace.go:171","msg":"trace[1660819831] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"119.915637ms","start":"2026-07-24T04:43:07.675301Z","end":"2026-07-24T04:43:07.795217Z","steps":["trace[1660819831] 'process raft request' (duration: 119.390737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:08.050292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.705158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:08.051006Z","caller":"traceutil/trace.go:171","msg":"trace[268500833] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:636; }","duration":"158.541985ms","start":"2026-07-24T04:43:07.892438Z","end":"2026-07-24T04:43:08.05098Z","steps":["trace[268500833] 'range keys from in-memory index tree' (duration: 157.274259ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:08.423906Z","caller":"traceutil/trace.go:171","msg":"trace[1369115559] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"159.862112ms","start":"2026-07-24T04:43:08.26401Z","end":"2026-07-24T04:43:08.423872Z","steps":["trace[1369115559] 'read index received' (duration: 159.575376ms)","trace[1369115559] 'applied index is now lower than readState.Index' (duration: 285.135µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:08.42414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.093937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:08.424289Z","caller":"traceutil/trace.go:171","msg":"trace[1313257092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"160.303681ms","start":"2026-07-24T04:43:08.26395Z","end":"2026-07-24T04:43:08.424253Z","steps":["trace[1313257092] 'agreement among raft nodes before linearized reading' (duration: 160.051926ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:08.424024Z","caller":"traceutil/trace.go:171","msg":"trace[1993976950] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"265.280182ms","start":"2026-07-24T04:43:08.158696Z","end":"2026-07-24T04:43:08.423976Z","steps":["trace[1993976950] 'process raft request' (duration: 264.923464ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:08.601436Z","caller":"traceutil/trace.go:171","msg":"trace[857817627] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"159.442893ms","start":"2026-07-24T04:43:08.441961Z","end":"2026-07-24T04:43:08.601404Z","steps":["trace[857817627] 'process raft request' (duration: 158.686897ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:23.430986Z","caller":"traceutil/trace.go:171","msg":"trace[368178546] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"114.802122ms","start":"2026-07-24T04:43:23.316162Z","end":"2026-07-24T04:43:23.430965Z","steps":["trace[368178546] 'process raft request' (duration: 114.68509ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:56.783022Z","caller":"traceutil/trace.go:171","msg":"trace[1744979466] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"109.105567ms","start":"2026-07-24T04:43:56.67387Z","end":"2026-07-24T04:43:56.782976Z","steps":["trace[1744979466] 'process raft request' (duration: 108.965855ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:24.312241Z","caller":"traceutil/trace.go:171","msg":"trace[1711281800] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"165.131214ms","start":"2026-07-24T04:44:24.147064Z","end":"2026-07-24T04:44:24.312196Z","steps":["trace[1711281800] 'process raft request' (duration: 164.852268ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:25.058079Z","caller":"traceutil/trace.go:171","msg":"trace[1223555056] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"121.59505ms","start":"2026-07-24T04:44:24.936403Z","end":"2026-07-24T04:44:25.057998Z","steps":["trace[1223555056] 'process raft request' (duration: 121.352595ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:40.556117Z","caller":"traceutil/trace.go:171","msg":"trace[724410212] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"144.92331ms","start":"2026-07-24T04:44:40.411171Z","end":"2026-07-24T04:44:40.556094Z","steps":["trace[724410212] 'read index received' (duration: 144.805527ms)","trace[724410212] 'applied index is now lower than readState.Index' (duration: 116.553µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:44:40.55631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.116213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T04:44:40.556334Z","caller":"traceutil/trace.go:171","msg":"trace[511014671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1203; }","duration":"145.176544ms","start":"2026-07-24T04:44:40.411151Z","end":"2026-07-24T04:44:40.556327Z","steps":["trace[511014671] 'agreement among raft nodes before linearized reading' (duration: 145.043922ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:40.556457Z","caller":"traceutil/trace.go:171","msg":"trace[1026581923] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"152.936948ms","start":"2026-07-24T04:44:40.403469Z","end":"2026-07-24T04:44:40.556406Z","steps":["trace[1026581923] 'process raft request' (duration: 152.511449ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:40.663346Z","caller":"traceutil/trace.go:171","msg":"trace[771147370] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"101.447517ms","start":"2026-07-24T04:44:40.561872Z","end":"2026-07-24T04:44:40.663319Z","steps":["trace[771147370] 'process raft request' (duration: 100.908196ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:53.582554Z","caller":"traceutil/trace.go:171","msg":"trace[1932974453] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"106.299141ms","start":"2026-07-24T04:44:53.476221Z","end":"2026-07-24T04:44:53.58252Z","steps":["trace[1932974453] 'process raft request' (duration: 106.105177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:44:56.974468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.280554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:44:56.974596Z","caller":"traceutil/trace.go:171","msg":"trace[367729974] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"222.936374ms","start":"2026-07-24T04:44:56.751637Z","end":"2026-07-24T04:44:56.974573Z","steps":["trace[367729974] 'process raft request' (duration: 40.471026ms)","trace[367729974] 'compare' (duration: 181.892366ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:44:57.680636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.229003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:44:57.680733Z","caller":"traceutil/trace.go:171","msg":"trace[262076296] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"196.749741ms","start":"2026-07-24T04:44:57.483966Z","end":"2026-07-24T04:44:57.680715Z","steps":["trace[262076296] 'read index received' (duration: 35.451µs)","trace[262076296] 'applied index is now lower than readState.Index' (duration: 196.71335ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:44:57.680884Z","caller":"traceutil/trace.go:171","msg":"trace[1159871568] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"270.375634ms","start":"2026-07-24T04:44:57.410501Z","end":"2026-07-24T04:44:57.680877Z","steps":["trace[1159871568] 'process raft request' (duration: 37.816853ms)","trace[1159871568] 'compare' (duration: 232.128011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:44:57.681674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.023616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:44:57.681787Z","caller":"traceutil/trace.go:171","msg":"trace[1351531924] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1280; }","duration":"197.851923ms","start":"2026-07-24T04:44:57.483916Z","end":"2026-07-24T04:44:57.681768Z","steps":["trace[1351531924] 'agreement among raft nodes before linearized reading' (duration: 197.029526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:44:57.698689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.894374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T04:44:57.698888Z","caller":"traceutil/trace.go:171","msg":"trace[1972891484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"106.085578ms","start":"2026-07-24T04:44:57.592705Z","end":"2026-07-24T04:44:57.698791Z","steps":["trace[1972891484] 'agreement among raft nodes before linearized reading' (duration: 105.778221ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:57.866181Z","caller":"traceutil/trace.go:171","msg":"trace[1001272275] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"179.429956ms","start":"2026-07-24T04:44:57.686694Z","end":"2026-07-24T04:44:57.866124Z","steps":["trace[1001272275] 'process raft request' (duration: 179.203102ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:57.883775Z","caller":"traceutil/trace.go:171","msg":"trace[2134658304] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"181.741613ms","start":"2026-07-24T04:44:57.701994Z","end":"2026-07-24T04:44:57.883736Z","steps":["trace[2134658304] 'process raft request' (duration: 181.523848ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:57.88394Z","caller":"traceutil/trace.go:171","msg":"trace[1325582320] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"181.360665ms","start":"2026-07-24T04:44:57.70244Z","end":"2026-07-24T04:44:57.883801Z","steps":["trace[1325582320] 'process raft request' (duration: 181.184921ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:12.353814Z","caller":"traceutil/trace.go:171","msg":"trace[6851740] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"208.442418ms","start":"2026-07-24T04:45:12.145326Z","end":"2026-07-24T04:45:12.353768Z","steps":["trace[6851740] 'process raft request' (duration: 208.130072ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:36.497442Z","caller":"traceutil/trace.go:171","msg":"trace[364472851] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"100.926931ms","start":"2026-07-24T04:49:36.396487Z","end":"2026-07-24T04:49:36.497414Z","steps":["trace[364472851] 'process raft request' (duration: 53.351639ms)","trace[364472851] 'compare' (duration: 47.48244ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:38.942129Z","caller":"traceutil/trace.go:171","msg":"trace[1981781900] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"129.616217ms","start":"2026-07-24T04:49:38.812461Z","end":"2026-07-24T04:49:38.942077Z","steps":["trace[1981781900] 'process raft request' (duration: 39.806452ms)","trace[1981781900] 'compare' (duration: 89.621031ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:40.556563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.434071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:49:40.556657Z","caller":"traceutil/trace.go:171","msg":"trace[855915518] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2635; }","duration":"120.605543ms","start":"2026-07-24T04:49:40.436029Z","end":"2026-07-24T04:49:40.556634Z","steps":["trace[855915518] 'count revisions from in-memory index tree' (duration: 120.311177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:40.556845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.36836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:49:40.556893Z","caller":"traceutil/trace.go:171","msg":"trace[925559566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2635; }","duration":"101.457492ms","start":"2026-07-24T04:49:40.45542Z","end":"2026-07-24T04:49:40.556878Z","steps":["trace[925559566] 'range keys from in-memory index tree' (duration: 101.175167ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:41.52092Z","caller":"traceutil/trace.go:171","msg":"trace[2071622198] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"112.525689ms","start":"2026-07-24T04:49:41.408347Z","end":"2026-07-24T04:49:41.520873Z","steps":["trace[2071622198] 'process raft request' (duration: 112.279813ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:41.641647Z","caller":"traceutil/trace.go:171","msg":"trace[199825412] linearizableReadLoop","detail":"{readStateIndex:2751; appliedIndex:2748; }","duration":"113.51726ms","start":"2026-07-24T04:49:41.528104Z","end":"2026-07-24T04:49:41.641622Z","steps":["trace[199825412] 'read index received' (duration: 22.290696ms)","trace[199825412] 'applied index is now lower than readState.Index' (duration: 91.225304ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:41.641665Z","caller":"traceutil/trace.go:171","msg":"trace[1746452273] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"114.296555ms","start":"2026-07-24T04:49:41.527333Z","end":"2026-07-24T04:49:41.641629Z","steps":["trace[1746452273] 'process raft request' (duration: 22.980379ms)","trace[1746452273] 'compare' (duration: 90.935478ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:41.641824Z","caller":"traceutil/trace.go:171","msg":"trace[2113915734] transaction","detail":"{read_only:false; response_revision:2641; number_of_response:1; }","duration":"114.113541ms","start":"2026-07-24T04:49:41.527593Z","end":"2026-07-24T04:49:41.641706Z","steps":["trace[2113915734] 'process raft request' (duration: 113.896227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:41.641859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.736614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2581"} {"level":"info","ts":"2026-07-24T04:49:41.642047Z","caller":"traceutil/trace.go:171","msg":"trace[1990407165] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2642; }","duration":"113.974319ms","start":"2026-07-24T04:49:41.528058Z","end":"2026-07-24T04:49:41.642032Z","steps":["trace[1990407165] 'agreement among raft nodes before linearized reading' (duration: 113.660713ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:41.641917Z","caller":"traceutil/trace.go:171","msg":"trace[1371236927] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"113.854636ms","start":"2026-07-24T04:49:41.528034Z","end":"2026-07-24T04:49:41.641889Z","steps":["trace[1371236927] 'process raft request' (duration: 113.5377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:42.455185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.271957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:49:42.455276Z","caller":"traceutil/trace.go:171","msg":"trace[144086990] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2642; }","duration":"450.38077ms","start":"2026-07-24T04:49:42.004873Z","end":"2026-07-24T04:49:42.455254Z","steps":["trace[144086990] 'count revisions from in-memory index tree' (duration: 450.182615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:42.455229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.230606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:49:42.455324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:42.00482Z","time spent":"450.493182ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:49:42.455389Z","caller":"traceutil/trace.go:171","msg":"trace[1319269835] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2642; }","duration":"168.43695ms","start":"2026-07-24T04:49:42.286928Z","end":"2026-07-24T04:49:42.455364Z","steps":["trace[1319269835] 'range keys from in-memory index tree' (duration: 168.101273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:42.455344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.590087ms","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-07-24T04:49:42.455455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.00574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-24T04:49:42.455481Z","caller":"traceutil/trace.go:171","msg":"trace[1575994952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2642; }","duration":"553.787472ms","start":"2026-07-24T04:49:41.901662Z","end":"2026-07-24T04:49:42.45545Z","steps":["trace[1575994952] 'range keys from in-memory index tree' (duration: 553.356462ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:42.455562Z","caller":"traceutil/trace.go:171","msg":"trace[434457781] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2642; }","duration":"283.158164ms","start":"2026-07-24T04:49:42.172377Z","end":"2026-07-24T04:49:42.455535Z","steps":["trace[434457781] 'range keys from in-memory index tree' (duration: 282.747795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:42.455568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.330465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-24T04:49:42.455569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:41.901645Z","time spent":"553.907254ms","remote":"127.0.0.1:57706","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-07-24T04:49:42.455616Z","caller":"traceutil/trace.go:171","msg":"trace[1877821049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2642; }","duration":"161.395086ms","start":"2026-07-24T04:49:42.294208Z","end":"2026-07-24T04:49:42.455603Z","steps":["trace[1877821049] 'range keys from in-memory index tree' (duration: 161.225723ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:42.603767Z","caller":"traceutil/trace.go:171","msg":"trace[1487657253] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"142.529762ms","start":"2026-07-24T04:49:42.46119Z","end":"2026-07-24T04:49:42.60372Z","steps":["trace[1487657253] 'process raft request' (duration: 142.338758ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:42.603755Z","caller":"traceutil/trace.go:171","msg":"trace[1085712337] linearizableReadLoop","detail":"{readStateIndex:2752; appliedIndex:2752; }","duration":"142.393119ms","start":"2026-07-24T04:49:42.461323Z","end":"2026-07-24T04:49:42.603716Z","steps":["trace[1085712337] 'read index received' (duration: 142.336577ms)","trace[1085712337] 'applied index is now lower than readState.Index' (duration: 53.311µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:42.60393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.566012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:49:42.60401Z","caller":"traceutil/trace.go:171","msg":"trace[274329248] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2643; }","duration":"142.707745ms","start":"2026-07-24T04:49:42.461282Z","end":"2026-07-24T04:49:42.60399Z","steps":["trace[274329248] 'agreement among raft nodes before linearized reading' (duration: 142.541262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:43.85323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.568106ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:49:43.853351Z","caller":"traceutil/trace.go:171","msg":"trace[819505824] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2643; }","duration":"317.697659ms","start":"2026-07-24T04:49:43.535635Z","end":"2026-07-24T04:49:43.853332Z","steps":["trace[819505824] 'range keys from in-memory index tree' (duration: 317.554746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:43.853528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121233171s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:49:43.853719Z","caller":"traceutil/trace.go:171","msg":"trace[390579797] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"1.392137215s","start":"2026-07-24T04:49:42.461566Z","end":"2026-07-24T04:49:43.853703Z","steps":["trace[390579797] 'process raft request' (duration: 270.662199ms)","trace[390579797] 'compare' (duration: 1.120988837s)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:43.853729Z","caller":"traceutil/trace.go:171","msg":"trace[249570976] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"1.391990713s","start":"2026-07-24T04:49:42.461718Z","end":"2026-07-24T04:49:43.853708Z","steps":["trace[249570976] 'process raft request' (duration: 1.3918763s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:43.853816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:42.461703Z","time spent":"1.392061214s","remote":"127.0.0.1:57706","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-07-24T04:49:43.853817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:42.461553Z","time spent":"1.392199566s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:49:43.853919Z","caller":"traceutil/trace.go:171","msg":"trace[908922129] linearizableReadLoop","detail":"{readStateIndex:2754; appliedIndex:2752; }","duration":"1.249933351s","start":"2026-07-24T04:49:42.603976Z","end":"2026-07-24T04:49:43.85391Z","steps":["trace[908922129] 'read index received' (duration: 128.297291ms)","trace[908922129] 'applied index is now lower than readState.Index' (duration: 1.12163498s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.123812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.153999ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T04:49:44.124522Z","caller":"traceutil/trace.go:171","msg":"trace[1074339347] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"1.121262552s","start":"2026-07-24T04:49:43.003237Z","end":"2026-07-24T04:49:44.1245Z","steps":["trace[1074339347] 'process raft request' (duration: 1.120686331s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:44.124561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538979734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:49:44.124619Z","caller":"traceutil/trace.go:171","msg":"trace[1984807326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2645; }","duration":"1.53924617s","start":"2026-07-24T04:49:42.585355Z","end":"2026-07-24T04:49:44.124601Z","steps":["trace[1984807326] 'agreement among raft nodes before linearized reading' (duration: 1.268626922s)","trace[1984807326] 'range keys from in-memory index tree' (duration: 269.774751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.124664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:42.585329Z","time spent":"1.539325411s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:49:44.124681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:43.003211Z","time spent":"1.121376415s","remote":"127.0.0.1:57706","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-07-24T04:49:44.124387Z","caller":"traceutil/trace.go:171","msg":"trace[2100290827] linearizableReadLoop","detail":"{readStateIndex:2756; appliedIndex:2754; }","duration":"270.395134ms","start":"2026-07-24T04:49:43.853938Z","end":"2026-07-24T04:49:44.124334Z","steps":["trace[2100290827] 'read index received' (duration: 57.901µs)","trace[2100290827] 'applied index is now lower than readState.Index' (duration: 270.335103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.562969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.902477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-24T04:49:44.563026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785659754s","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-07-24T04:49:44.563075Z","caller":"traceutil/trace.go:171","msg":"trace[788050890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2646; }","duration":"916.086892ms","start":"2026-07-24T04:49:43.646961Z","end":"2026-07-24T04:49:44.563048Z","steps":["trace[788050890] 'agreement among raft nodes before linearized reading' (duration: 477.481873ms)","trace[788050890] 'range keys from in-memory index tree' (duration: 438.271221ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:44.56311Z","caller":"traceutil/trace.go:171","msg":"trace[221410179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2646; }","duration":"1.785786357s","start":"2026-07-24T04:49:42.7773Z","end":"2026-07-24T04:49:44.563086Z","steps":["trace[221410179] 'agreement among raft nodes before linearized reading' (duration: 1.347457903s)","trace[221410179] 'range keys from in-memory index tree' (duration: 438.112048ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.563131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:43.646927Z","time spent":"916.192113ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:49:44.563162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:42.777279Z","time spent":"1.785874578s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:49:44.563189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301334981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:49:44.563212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369784909s","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":"info","ts":"2026-07-24T04:49:44.563262Z","caller":"traceutil/trace.go:171","msg":"trace[774193254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2646; }","duration":"1.36982976s","start":"2026-07-24T04:49:43.193414Z","end":"2026-07-24T04:49:44.563244Z","steps":["trace[774193254] 'agreement among raft nodes before linearized reading' (duration: 931.113779ms)","trace[774193254] 'count revisions from in-memory index tree' (duration: 438.63476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.563027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.104979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-24T04:49:44.563311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:43.19335Z","time spent":"1.369948382s","remote":"127.0.0.1:57378","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":"info","ts":"2026-07-24T04:49:44.563311Z","caller":"traceutil/trace.go:171","msg":"trace[520086609] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2646; }","duration":"862.446386ms","start":"2026-07-24T04:49:43.700852Z","end":"2026-07-24T04:49:44.563299Z","steps":["trace[520086609] 'agreement among raft nodes before linearized reading' (duration: 423.641413ms)","trace[520086609] 'range keys from in-memory index tree' (duration: 438.389534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.563348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:43.700827Z","time spent":"862.513918ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T04:49:44.563104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502300096s","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:525"} {"level":"info","ts":"2026-07-24T04:49:44.563398Z","caller":"traceutil/trace.go:171","msg":"trace[95741832] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2646; }","duration":"1.502658744s","start":"2026-07-24T04:49:43.060723Z","end":"2026-07-24T04:49:44.563382Z","steps":["trace[95741832] 'agreement among raft nodes before linearized reading' (duration: 1.06380834s)","trace[95741832] 'range keys from in-memory index tree' (duration: 438.349743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.563445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:43.060703Z","time spent":"1.502730765s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T04:49:44.563685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.162377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:49:44.563751Z","caller":"traceutil/trace.go:171","msg":"trace[158162319] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"433.567636ms","start":"2026-07-24T04:49:44.130169Z","end":"2026-07-24T04:49:44.563737Z","steps":["trace[158162319] 'process raft request' (duration: 329.285656ms)","trace[158162319] 'compare' (duration: 103.3334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.563807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.130114Z","time spent":"433.667089ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:49:44.563259Z","caller":"traceutil/trace.go:171","msg":"trace[422161905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2646; }","duration":"1.301384742s","start":"2026-07-24T04:49:43.261846Z","end":"2026-07-24T04:49:44.563231Z","steps":["trace[422161905] 'agreement among raft nodes before linearized reading' (duration: 862.67449ms)","trace[422161905] 'range keys from in-memory index tree' (duration: 438.64946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:44.564974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:43.261787Z","time spent":"1.303166688s","remote":"127.0.0.1:57418","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-07-24T04:49:45.074378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700799863518503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:49:45.381581Z","caller":"traceutil/trace.go:171","msg":"trace[935873026] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"813.537757ms","start":"2026-07-24T04:49:44.568008Z","end":"2026-07-24T04:49:45.381545Z","steps":["trace[935873026] 'process raft request' (duration: 813.357984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.381747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.567991Z","time spent":"813.672311ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:49:45.900683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.919557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:49:45.901152Z","caller":"traceutil/trace.go:171","msg":"trace[192359716] linearizableReadLoop","detail":"{readStateIndex:2761; appliedIndex:2757; }","duration":"1.327819602s","start":"2026-07-24T04:49:44.573315Z","end":"2026-07-24T04:49:45.901134Z","steps":["trace[192359716] 'read index received' (duration: 808.025475ms)","trace[192359716] 'applied index is now lower than readState.Index' (duration: 519.792997ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:45.901192Z","caller":"traceutil/trace.go:171","msg":"trace[1062434699] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"1.332843994s","start":"2026-07-24T04:49:44.568302Z","end":"2026-07-24T04:49:45.901146Z","steps":["trace[1062434699] 'process raft request' (duration: 1.008374186s)","trace[1062434699] 'compare' (duration: 323.803835ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:45.90131Z","caller":"traceutil/trace.go:171","msg":"trace[1024196073] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"1.332516947s","start":"2026-07-24T04:49:44.56878Z","end":"2026-07-24T04:49:45.901297Z","steps":["trace[1024196073] 'process raft request' (duration: 1.331991247s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.901378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.568769Z","time spent":"1.332569548s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:49:45.901381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.568287Z","time spent":"1.332985508s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:49:45.901511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.819905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:49:45.90154Z","caller":"traceutil/trace.go:171","msg":"trace[1216231059] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2651; }","duration":"794.944567ms","start":"2026-07-24T04:49:45.106587Z","end":"2026-07-24T04:49:45.901532Z","steps":["trace[1216231059] 'agreement among raft nodes before linearized reading' (duration: 794.887306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.901518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305831702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T04:49:45.901564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.571359ms","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":"warn","ts":"2026-07-24T04:49:45.90154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328173339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:49:45.901592Z","caller":"traceutil/trace.go:171","msg":"trace[1398756545] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2651; }","duration":"1.305920185s","start":"2026-07-24T04:49:44.595653Z","end":"2026-07-24T04:49:45.901574Z","steps":["trace[1398756545] 'agreement among raft nodes before linearized reading' (duration: 1.305765062s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:45.901631Z","caller":"traceutil/trace.go:171","msg":"trace[573201894] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2651; }","duration":"628.676501ms","start":"2026-07-24T04:49:45.272937Z","end":"2026-07-24T04:49:45.901613Z","steps":["trace[573201894] 'agreement among raft nodes before linearized reading' (duration: 628.500197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.90165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.595617Z","time spent":"1.306023326s","remote":"127.0.0.1:57790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:49:45.901623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.977136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:49:45.901661Z","caller":"traceutil/trace.go:171","msg":"trace[1478914531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2651; }","duration":"1.328359643s","start":"2026-07-24T04:49:44.57327Z","end":"2026-07-24T04:49:45.901629Z","steps":["trace[1478914531] 'agreement among raft nodes before linearized reading' (duration: 1.328107368s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.901712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293849667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-24T04:49:45.901738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.234417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:49:45.901571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:45.106566Z","time spent":"794.996638ms","remote":"127.0.0.1:57936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:49:45.901737Z","caller":"traceutil/trace.go:171","msg":"trace[2107969810] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2651; }","duration":"697.16492ms","start":"2026-07-24T04:49:45.204546Z","end":"2026-07-24T04:49:45.901711Z","steps":["trace[2107969810] 'agreement among raft nodes before linearized reading' (duration: 696.946435ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:45.901763Z","caller":"traceutil/trace.go:171","msg":"trace[196996674] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2651; }","duration":"1.293927478s","start":"2026-07-24T04:49:44.607821Z","end":"2026-07-24T04:49:45.901748Z","steps":["trace[196996674] 'agreement among raft nodes before linearized reading' (duration: 1.293792216s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.901756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.573255Z","time spent":"1.328483985s","remote":"127.0.0.1:57418","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-07-24T04:49:45.901773Z","caller":"traceutil/trace.go:171","msg":"trace[791896564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2651; }","duration":"193.307269ms","start":"2026-07-24T04:49:45.708455Z","end":"2026-07-24T04:49:45.901762Z","steps":["trace[791896564] 'agreement among raft nodes before linearized reading' (duration: 193.248648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.901808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.607797Z","time spent":"1.29400004s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-24T04:49:45.901808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:45.20452Z","time spent":"697.273711ms","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:49:45.901549Z","caller":"traceutil/trace.go:171","msg":"trace[185619149] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"1.3326528s","start":"2026-07-24T04:49:44.568874Z","end":"2026-07-24T04:49:45.901526Z","steps":["trace[185619149] 'process raft request' (duration: 1.332211891s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:45.901677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:45.272911Z","time spent":"628.754023ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T04:49:45.901936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:44.568842Z","time spent":"1.333058049s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:49:47.018845Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035060731s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:49:47.019394Z","caller":"traceutil/trace.go:171","msg":"trace[751345022] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"1.111828731s","start":"2026-07-24T04:49:45.907535Z","end":"2026-07-24T04:49:47.019364Z","steps":["trace[751345022] 'process raft request' (duration: 1.111615826s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:47.019468Z","caller":"traceutil/trace.go:171","msg":"trace[306201136] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"1.111876461s","start":"2026-07-24T04:49:45.907578Z","end":"2026-07-24T04:49:47.019454Z","steps":["trace[306201136] 'process raft request' (duration: 1.111705848s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:47.019543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:45.907522Z","time spent":"1.111940583s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:49:47.019566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:45.907572Z","time spent":"1.111944283s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:49:47.019419Z","caller":"traceutil/trace.go:171","msg":"trace[564810169] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"1.110533893s","start":"2026-07-24T04:49:45.908865Z","end":"2026-07-24T04:49:47.019399Z","steps":["trace[564810169] 'process raft request' (duration: 1.110462852s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:47.019742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:45.908851Z","time spent":"1.11085615s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:49:47.308731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.356187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:49:47.308946Z","caller":"traceutil/trace.go:171","msg":"trace[872905619] linearizableReadLoop","detail":"{readStateIndex:2766; appliedIndex:2762; }","duration":"740.100457ms","start":"2026-07-24T04:49:46.568824Z","end":"2026-07-24T04:49:47.308925Z","steps":["trace[872905619] 'read index received' (duration: 450.369498ms)","trace[872905619] 'applied index is now lower than readState.Index' (duration: 289.729769ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:49:47.309154Z","caller":"traceutil/trace.go:171","msg":"trace[313863765] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"631.618132ms","start":"2026-07-24T04:49:46.67752Z","end":"2026-07-24T04:49:47.309139Z","steps":["trace[313863765] 'process raft request' (duration: 631.339197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:47.309235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:46.677487Z","time spent":"631.702614ms","remote":"127.0.0.1:57706","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-07-24T04:49:47.309379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.495771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-24T04:49:47.309458Z","caller":"traceutil/trace.go:171","msg":"trace[1061350333] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2657; }","duration":"661.642984ms","start":"2026-07-24T04:49:46.647793Z","end":"2026-07-24T04:49:47.309436Z","steps":["trace[1061350333] 'agreement among raft nodes before linearized reading' (duration: 661.402029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:47.3095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:46.647762Z","time spent":"661.729296ms","remote":"127.0.0.1:57706","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-07-24T04:49:47.309497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.640687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:49:47.309602Z","caller":"traceutil/trace.go:171","msg":"trace[628582391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2657; }","duration":"740.76041ms","start":"2026-07-24T04:49:46.568813Z","end":"2026-07-24T04:49:47.309573Z","steps":["trace[628582391] 'agreement among raft nodes before linearized reading' (duration: 740.433123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:47.309654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:46.568766Z","time spent":"740.876312ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T04:49:47.309511Z","caller":"traceutil/trace.go:171","msg":"trace[2058677902] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"1.177133954s","start":"2026-07-24T04:49:46.131877Z","end":"2026-07-24T04:49:47.309011Z","steps":["trace[2058677902] 'process raft request' (duration: 1.060381858s)","trace[2058677902] 'compare' (duration: 116.169434ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:49:47.309856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:49:46.131846Z","time spent":"1.1779447s","remote":"127.0.0.1:57706","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-07-24T04:50:03.873419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.263408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:15 size:12285"} {"level":"info","ts":"2026-07-24T04:50:03.87351Z","caller":"traceutil/trace.go:171","msg":"trace[58835152] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:15; response_revision:2740; }","duration":"100.39212ms","start":"2026-07-24T04:50:03.773098Z","end":"2026-07-24T04:50:03.87349Z","steps":["trace[58835152] 'range keys from in-memory index tree' (duration: 100.025084ms)"],"step_count":1}