{"level":"warn","ts":"2026-09-19T01:51:34.629383Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:51:34.629477Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.220: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://162.253.55.220:2380","--initial-cluster=instance=https://162.253.55.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.220: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-09-19T01:51:34.629646Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:51:34.629663Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-19T01:51:34.629686Z","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-09-19T01:51:34.630535Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"]} {"level":"info","ts":"2026-09-19T01:51:34.630906Z","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://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.220: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-09-19T01:51:34.655184Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"23.254402ms"} {"level":"info","ts":"2026-09-19T01:51:34.673883Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c62f064ba268721","cluster-id":"65b58f2bbdfe5647"} {"level":"info","ts":"2026-09-19T01:51:34.674252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:51:34.674364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 0"} {"level":"info","ts":"2026-09-19T01:51:34.674454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c62f064ba268721 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:51:34.674499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 1"} {"level":"info","ts":"2026-09-19T01:51:34.674618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"warn","ts":"2026-09-19T01:51:34.716885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:51:34.719216Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:51:34.72333Z","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-09-19T01:51:34.731153Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c62f064ba268721","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:51:34.731809Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c62f064ba268721","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:51:34.731949Z","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-09-19T01:51:34.732017Z","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-09-19T01:51:34.732035Z","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-09-19T01:51:34.735885Z","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-09-19T01:51:34.736286Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-19T01:51:34.736342Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-19T01:51:34.736864Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:51:34.736792Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c62f064ba268721","initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:51:34.73752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"info","ts":"2026-09-19T01:51:34.737777Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","added-peer-id":"4c62f064ba268721","added-peer-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-19T01:51:35.075121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:51:35.075207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:51:35.075247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgPreVoteResp from 4c62f064ba268721 at term 1"} {"level":"info","ts":"2026-09-19T01:51:35.075264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:51:35.07527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgVoteResp from 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-19T01:51:35.075282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became leader at term 2"} {"level":"info","ts":"2026-09-19T01:51:35.075292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c62f064ba268721 elected leader 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-19T01:51:35.077775Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c62f064ba268721","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.220:2379]}","request-path":"/0/members/4c62f064ba268721/attributes","cluster-id":"65b58f2bbdfe5647","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:51:35.077829Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:51:35.078005Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:51:35.078046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:51:35.078028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:51:35.077981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:51:35.080019Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:51:35.080215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:51:35.080254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:51:35.080577Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.220:2379"} {"level":"info","ts":"2026-09-19T01:51:35.083919Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-19T01:51:38.502196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.644544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:51:38.502464Z","caller":"traceutil/trace.go:171","msg":"trace[1086039971] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"238.396333ms","start":"2026-09-19T01:51:38.264041Z","end":"2026-09-19T01:51:38.502437Z","steps":["trace[1086039971] 'process raft request' (duration: 117.839093ms)","trace[1086039971] 'compare' (duration: 119.437533ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:51:39.111423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.227389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:51:39.11167Z","caller":"traceutil/trace.go:171","msg":"trace[962754612] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"604.099522ms","start":"2026-09-19T01:51:38.507529Z","end":"2026-09-19T01:51:39.111629Z","steps":["trace[962754612] 'process raft request' (duration: 142.577658ms)","trace[962754612] 'compare' (duration: 460.988137ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:51:39.111756Z","caller":"traceutil/trace.go:171","msg":"trace[170932409] linearizableReadLoop","detail":"{readStateIndex:160; appliedIndex:158; }","duration":"311.16617ms","start":"2026-09-19T01:51:38.800571Z","end":"2026-09-19T01:51:39.111737Z","steps":["trace[170932409] 'read index received' (duration: 75.420319ms)","trace[170932409] 'applied index is now lower than readState.Index' (duration: 235.744511ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:51:39.111879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:51:38.507494Z","time spent":"604.2622ms","remote":"127.0.0.1:56150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":867,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:51:39.111956Z","caller":"traceutil/trace.go:171","msg":"trace[1085616458] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"464.056503ms","start":"2026-09-19T01:51:38.647868Z","end":"2026-09-19T01:51:39.111924Z","steps":["trace[1085616458] 'process raft request' (duration: 463.687464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:51:39.111985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.403073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:51:39.112033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:51:38.647811Z","time spent":"464.180548ms","remote":"127.0.0.1:56060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:51:39.112077Z","caller":"traceutil/trace.go:171","msg":"trace[306227467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:155; }","duration":"311.54583ms","start":"2026-09-19T01:51:38.800514Z","end":"2026-09-19T01:51:39.11206Z","steps":["trace[306227467] 'agreement among raft nodes before linearized reading' (duration: 311.307438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:51:39.112181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:51:38.800489Z","time spent":"311.680036ms","remote":"127.0.0.1:55828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T01:51:39.927527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.222503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:51:39.927783Z","caller":"traceutil/trace.go:171","msg":"trace[274580816] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"810.616486ms","start":"2026-09-19T01:51:39.117029Z","end":"2026-09-19T01:51:39.927645Z","steps":["trace[274580816] 'process raft request' (duration: 242.211614ms)","trace[274580816] 'compare' (duration: 568.101188ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:51:39.928012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:51:39.117015Z","time spent":"810.851578ms","remote":"127.0.0.1:56150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:51:41.743218Z","caller":"traceutil/trace.go:171","msg":"trace[2004261437] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"102.909724ms","start":"2026-09-19T01:51:41.640281Z","end":"2026-09-19T01:51:41.74319Z","steps":["trace[2004261437] 'process raft request' (duration: 102.714065ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:51:47.623018Z","caller":"traceutil/trace.go:171","msg":"trace[185726876] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"118.292129ms","start":"2026-09-19T01:51:47.504699Z","end":"2026-09-19T01:51:47.622991Z","steps":["trace[185726876] 'process raft request' (duration: 118.051708ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:51:56.423475Z","caller":"traceutil/trace.go:171","msg":"trace[454688143] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"124.372362ms","start":"2026-09-19T01:51:56.299079Z","end":"2026-09-19T01:51:56.423452Z","steps":["trace[454688143] 'process raft request' (duration: 124.218586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:06.452687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.502434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-19T01:52:28.573905Z","caller":"traceutil/trace.go:171","msg":"trace[182482964] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"165.032381ms","start":"2026-09-19T01:52:28.408853Z","end":"2026-09-19T01:52:28.573885Z","steps":["trace[182482964] 'process raft request' (duration: 164.98724ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:28.57424Z","caller":"traceutil/trace.go:171","msg":"trace[1956797846] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"169.689086ms","start":"2026-09-19T01:52:28.404504Z","end":"2026-09-19T01:52:28.574193Z","steps":["trace[1956797846] 'process raft request' (duration: 146.142806ms)","trace[1956797846] 'compare' (duration: 22.996944ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:28.576794Z","caller":"traceutil/trace.go:171","msg":"trace[478439838] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"159.040228ms","start":"2026-09-19T01:52:28.417735Z","end":"2026-09-19T01:52:28.576775Z","steps":["trace[478439838] 'process raft request' (duration: 158.850542ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:28.576967Z","caller":"traceutil/trace.go:171","msg":"trace[1739469287] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"124.257472ms","start":"2026-09-19T01:52:28.452694Z","end":"2026-09-19T01:52:28.576951Z","steps":["trace[1739469287] 'process raft request' (duration: 124.102788ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:28.831174Z","caller":"traceutil/trace.go:171","msg":"trace[1288465781] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"172.208329ms","start":"2026-09-19T01:52:28.658938Z","end":"2026-09-19T01:52:28.831146Z","steps":["trace[1288465781] 'process raft request' (duration: 163.416334ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:28.831551Z","caller":"traceutil/trace.go:171","msg":"trace[1926891620] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"135.020625ms","start":"2026-09-19T01:52:28.696509Z","end":"2026-09-19T01:52:28.83153Z","steps":["trace[1926891620] 'process raft request' (duration: 134.738897ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:28.831525Z","caller":"traceutil/trace.go:171","msg":"trace[1958764624] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"129.783002ms","start":"2026-09-19T01:52:28.701709Z","end":"2026-09-19T01:52:28.831492Z","steps":["trace[1958764624] 'process raft request' (duration: 129.700989ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:29.344693Z","caller":"traceutil/trace.go:171","msg":"trace[752646820] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"152.172517ms","start":"2026-09-19T01:52:29.19249Z","end":"2026-09-19T01:52:29.344662Z","steps":["trace[752646820] 'process raft request' (duration: 54.226953ms)","trace[752646820] 'compare' (duration: 97.747378ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:29.345459Z","caller":"traceutil/trace.go:171","msg":"trace[835320926] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"113.17564ms","start":"2026-09-19T01:52:29.232267Z","end":"2026-09-19T01:52:29.345443Z","steps":["trace[835320926] 'read index received' (duration: 14.489745ms)","trace[835320926] 'applied index is now lower than readState.Index' (duration: 98.684785ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:52:29.345671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.385436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-19T01:52:29.34571Z","caller":"traceutil/trace.go:171","msg":"trace[944119224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"113.472089ms","start":"2026-09-19T01:52:29.232228Z","end":"2026-09-19T01:52:29.3457Z","steps":["trace[944119224] 'agreement among raft nodes before linearized reading' (duration: 113.304034ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:29.34574Z","caller":"traceutil/trace.go:171","msg":"trace[1736437293] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"127.388117ms","start":"2026-09-19T01:52:29.218314Z","end":"2026-09-19T01:52:29.345702Z","steps":["trace[1736437293] 'process raft request' (duration: 126.982405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:32.145022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.941947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:52:32.14511Z","caller":"traceutil/trace.go:171","msg":"trace[730180828] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:646; }","duration":"128.061901ms","start":"2026-09-19T01:52:32.017023Z","end":"2026-09-19T01:52:32.145085Z","steps":["trace[730180828] 'range keys from in-memory index tree' (duration: 127.875095ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:35.108752Z","caller":"traceutil/trace.go:171","msg":"trace[329915128] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"202.702891ms","start":"2026-09-19T01:52:34.906023Z","end":"2026-09-19T01:52:35.108726Z","steps":["trace[329915128] 'read index received' (duration: 202.379472ms)","trace[329915128] 'applied index is now lower than readState.Index' (duration: 321.859µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:35.108848Z","caller":"traceutil/trace.go:171","msg":"trace[199046565] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"279.050082ms","start":"2026-09-19T01:52:34.829759Z","end":"2026-09-19T01:52:35.108809Z","steps":["trace[199046565] 'process raft request' (duration: 278.752664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:35.109211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.153283ms","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-09-19T01:52:35.109224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.971539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cl9hg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T01:52:35.10947Z","caller":"traceutil/trace.go:171","msg":"trace[1243699855] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cl9hg; range_end:; response_count:1; response_revision:665; }","duration":"111.269198ms","start":"2026-09-19T01:52:34.998173Z","end":"2026-09-19T01:52:35.109442Z","steps":["trace[1243699855] 'agreement among raft nodes before linearized reading' (duration: 110.775765ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:35.109276Z","caller":"traceutil/trace.go:171","msg":"trace[812408044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"203.272796ms","start":"2026-09-19T01:52:34.905988Z","end":"2026-09-19T01:52:35.10926Z","steps":["trace[812408044] 'agreement among raft nodes before linearized reading' (duration: 202.868885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:35.484688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.20078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:52:35.484796Z","caller":"traceutil/trace.go:171","msg":"trace[1038133067] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"284.765123ms","start":"2026-09-19T01:52:35.200004Z","end":"2026-09-19T01:52:35.484769Z","steps":["trace[1038133067] 'read index received' (duration: 144.957687ms)","trace[1038133067] 'applied index is now lower than readState.Index' (duration: 139.805826ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:35.484814Z","caller":"traceutil/trace.go:171","msg":"trace[1310350455] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"359.986144ms","start":"2026-09-19T01:52:35.124793Z","end":"2026-09-19T01:52:35.484779Z","steps":["trace[1310350455] 'process raft request' (duration: 220.231939ms)","trace[1310350455] 'compare' (duration: 138.900842ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:52:35.484907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:52:35.124779Z","time spent":"360.082866ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:52:35.485014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.983808ms","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-09-19T01:52:35.485054Z","caller":"traceutil/trace.go:171","msg":"trace[1777075422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"285.084941ms","start":"2026-09-19T01:52:35.199957Z","end":"2026-09-19T01:52:35.485042Z","steps":["trace[1777075422] 'agreement among raft nodes before linearized reading' (duration: 284.912156ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:35.697835Z","caller":"traceutil/trace.go:171","msg":"trace[364469224] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"205.332637ms","start":"2026-09-19T01:52:35.492465Z","end":"2026-09-19T01:52:35.697798Z","steps":["trace[364469224] 'process raft request' (duration: 205.07984ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:35.698603Z","caller":"traceutil/trace.go:171","msg":"trace[183502744] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"162.563621ms","start":"2026-09-19T01:52:35.536009Z","end":"2026-09-19T01:52:35.698573Z","steps":["trace[183502744] 'process raft request' (duration: 161.972096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:36.348066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240578744850337,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T01:52:36.526337Z","caller":"traceutil/trace.go:171","msg":"trace[809904298] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"753.629972ms","start":"2026-09-19T01:52:35.772687Z","end":"2026-09-19T01:52:36.526317Z","steps":["trace[809904298] 'process raft request' (duration: 753.495208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:36.526476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:52:35.772664Z","time spent":"753.757446ms","remote":"127.0.0.1:56322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:52:36.57815Z","caller":"traceutil/trace.go:171","msg":"trace[324758452] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"730.188495ms","start":"2026-09-19T01:52:35.847883Z","end":"2026-09-19T01:52:36.578071Z","steps":["trace[324758452] 'read index received' (duration: 678.40976ms)","trace[324758452] 'applied index is now lower than readState.Index' (duration: 51.777205ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:52:36.578274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.378981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:52:36.578443Z","caller":"traceutil/trace.go:171","msg":"trace[287991985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"730.635618ms","start":"2026-09-19T01:52:35.847791Z","end":"2026-09-19T01:52:36.578427Z","steps":["trace[287991985] 'agreement among raft nodes before linearized reading' (duration: 730.424242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:36.578514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:52:35.847765Z","time spent":"730.739391ms","remote":"127.0.0.1:55828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-19T01:52:38.342675Z","caller":"traceutil/trace.go:171","msg":"trace[1459015559] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"110.604515ms","start":"2026-09-19T01:52:38.232042Z","end":"2026-09-19T01:52:38.342646Z","steps":["trace[1459015559] 'process raft request' (duration: 109.915096ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:42.154574Z","caller":"traceutil/trace.go:171","msg":"trace[311813837] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"160.294068ms","start":"2026-09-19T01:52:41.994253Z","end":"2026-09-19T01:52:42.154548Z","steps":["trace[311813837] 'process raft request' (duration: 160.039731ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:42.235729Z","caller":"traceutil/trace.go:171","msg":"trace[1617910003] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"173.348616ms","start":"2026-09-19T01:52:42.06236Z","end":"2026-09-19T01:52:42.235709Z","steps":["trace[1617910003] 'process raft request' (duration: 160.982584ms)","trace[1617910003] 'compare' (duration: 12.065004ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:52:42.23599Z","caller":"traceutil/trace.go:171","msg":"trace[542686147] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"166.678207ms","start":"2026-09-19T01:52:42.069273Z","end":"2026-09-19T01:52:42.235951Z","steps":["trace[542686147] 'process raft request' (duration: 166.336959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:52:42.236456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.891673ms","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-09-19T01:52:42.236544Z","caller":"traceutil/trace.go:171","msg":"trace[279222562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"167.006155ms","start":"2026-09-19T01:52:42.069523Z","end":"2026-09-19T01:52:42.236529Z","steps":["trace[279222562] 'agreement among raft nodes before linearized reading' (duration: 166.479162ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:52:52.890732Z","caller":"traceutil/trace.go:171","msg":"trace[1951632646] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"107.540901ms","start":"2026-09-19T01:52:52.783154Z","end":"2026-09-19T01:52:52.890695Z","steps":["trace[1951632646] 'process raft request' (duration: 107.156172ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:15.103598Z","caller":"traceutil/trace.go:171","msg":"trace[1835363278] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"102.034108ms","start":"2026-09-19T01:53:15.001513Z","end":"2026-09-19T01:53:15.103547Z","steps":["trace[1835363278] 'process raft request' (duration: 101.16267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:25.590196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.439192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:53:25.59049Z","caller":"traceutil/trace.go:171","msg":"trace[1399524971] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"159.000138ms","start":"2026-09-19T01:53:25.431475Z","end":"2026-09-19T01:53:25.590475Z","steps":["trace[1399524971] 'process raft request' (duration: 55.166328ms)","trace[1399524971] 'compare' (duration: 103.195808ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:53:26.198331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.404335ms","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-09-19T01:53:26.198442Z","caller":"traceutil/trace.go:171","msg":"trace[1429070994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"175.556238ms","start":"2026-09-19T01:53:26.02286Z","end":"2026-09-19T01:53:26.198416Z","steps":["trace[1429070994] 'range keys from in-memory index tree' (duration: 175.194411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:26.198449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.885167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:53:26.19857Z","caller":"traceutil/trace.go:171","msg":"trace[1943679354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"347.099301ms","start":"2026-09-19T01:53:25.851444Z","end":"2026-09-19T01:53:26.198544Z","steps":["trace[1943679354] 'range keys from in-memory index tree' (duration: 346.728243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:26.198626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:25.851398Z","time spent":"347.219323ms","remote":"127.0.0.1:55828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T01:53:26.198805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.125716ms","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-09-19T01:53:26.198866Z","caller":"traceutil/trace.go:171","msg":"trace[2000069615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"292.259088ms","start":"2026-09-19T01:53:25.906591Z","end":"2026-09-19T01:53:26.19885Z","steps":["trace[2000069615] 'range keys from in-memory index tree' (duration: 291.976173ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:51.91485Z","caller":"traceutil/trace.go:171","msg":"trace[1965170631] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"126.085578ms","start":"2026-09-19T01:53:51.788727Z","end":"2026-09-19T01:53:51.914812Z","steps":["trace[1965170631] 'process raft request' (duration: 45.329308ms)","trace[1965170631] 'compare' (duration: 80.527576ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:53:55.339499Z","caller":"traceutil/trace.go:171","msg":"trace[1905381524] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"153.223274ms","start":"2026-09-19T01:53:55.186248Z","end":"2026-09-19T01:53:55.339471Z","steps":["trace[1905381524] 'process raft request' (duration: 153.068203ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:55.519304Z","caller":"traceutil/trace.go:171","msg":"trace[1316697374] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"112.308919ms","start":"2026-09-19T01:53:55.406959Z","end":"2026-09-19T01:53:55.519268Z","steps":["trace[1316697374] 'process raft request' (duration: 112.159546ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:10.960618Z","caller":"traceutil/trace.go:171","msg":"trace[1921070161] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"103.470873ms","start":"2026-09-19T01:54:10.857123Z","end":"2026-09-19T01:54:10.960594Z","steps":["trace[1921070161] 'process raft request' (duration: 103.27529ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:20.260215Z","caller":"traceutil/trace.go:171","msg":"trace[178591114] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"286.25299ms","start":"2026-09-19T01:54:19.973933Z","end":"2026-09-19T01:54:20.260186Z","steps":["trace[178591114] 'process raft request' (duration: 286.059367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:20.803919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.081919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:54:20.804274Z","caller":"traceutil/trace.go:171","msg":"trace[287335425] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2031; }","duration":"306.604438ms","start":"2026-09-19T01:54:20.497616Z","end":"2026-09-19T01:54:20.804221Z","steps":["trace[287335425] 'read index received' (duration: 146.054494ms)","trace[287335425] 'applied index is now lower than readState.Index' (duration: 160.548754ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:20.804367Z","caller":"traceutil/trace.go:171","msg":"trace[365760814] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"538.851722ms","start":"2026-09-19T01:54:20.265501Z","end":"2026-09-19T01:54:20.804353Z","steps":["trace[365760814] 'process raft request' (duration: 378.185516ms)","trace[365760814] 'compare' (duration: 159.922237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:20.804434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:20.265478Z","time spent":"538.916293ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:20.804548Z","caller":"traceutil/trace.go:171","msg":"trace[583152314] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"365.981058ms","start":"2026-09-19T01:54:20.438538Z","end":"2026-09-19T01:54:20.804519Z","steps":["trace[583152314] 'process raft request' (duration: 365.566922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:20.80475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.135607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:54:20.804801Z","caller":"traceutil/trace.go:171","msg":"trace[58874696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1957; }","duration":"307.236457ms","start":"2026-09-19T01:54:20.49755Z","end":"2026-09-19T01:54:20.804786Z","steps":["trace[58874696] 'agreement among raft nodes before linearized reading' (duration: 307.029584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:20.80484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:20.497519Z","time spent":"307.308859ms","remote":"127.0.0.1:40456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-19T01:54:20.805345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:20.438511Z","time spent":"366.147451ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:54:20.818867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.606765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d3ac2a9-84bc-41f7-b402-d17f6a2daba6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T01:54:20.81895Z","caller":"traceutil/trace.go:171","msg":"trace[1717289884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d3ac2a9-84bc-41f7-b402-d17f6a2daba6; range_end:; response_count:1; response_revision:1958; }","duration":"197.760497ms","start":"2026-09-19T01:54:20.621171Z","end":"2026-09-19T01:54:20.818932Z","steps":["trace[1717289884] 'agreement among raft nodes before linearized reading' (duration: 197.26731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:21.350504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9737240578744852910,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T01:54:21.674744Z","caller":"traceutil/trace.go:171","msg":"trace[1741639864] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2035; }","duration":"824.850335ms","start":"2026-09-19T01:54:20.849871Z","end":"2026-09-19T01:54:21.674721Z","steps":["trace[1741639864] 'read index received' (duration: 784.604002ms)","trace[1741639864] 'applied index is now lower than readState.Index' (duration: 40.245143ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:21.674816Z","caller":"traceutil/trace.go:171","msg":"trace[365250339] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"850.404277ms","start":"2026-09-19T01:54:20.824385Z","end":"2026-09-19T01:54:21.67479Z","steps":["trace[365250339] 'process raft request' (duration: 810.158524ms)","trace[365250339] 'compare' (duration: 39.683316ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:21.67485Z","caller":"traceutil/trace.go:171","msg":"trace[314305523] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"846.097848ms","start":"2026-09-19T01:54:20.828742Z","end":"2026-09-19T01:54:21.67484Z","steps":["trace[314305523] 'process raft request' (duration: 845.854524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:21.674934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:20.824372Z","time spent":"850.487679ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:54:21.674985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.124788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:54:21.675029Z","caller":"traceutil/trace.go:171","msg":"trace[354297773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1961; }","duration":"825.21107ms","start":"2026-09-19T01:54:20.849808Z","end":"2026-09-19T01:54:21.67502Z","steps":["trace[354297773] 'agreement among raft nodes before linearized reading' (duration: 825.147909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:21.675055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:20.849781Z","time spent":"825.26771ms","remote":"127.0.0.1:55828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T01:54:21.674933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:20.828714Z","time spent":"846.156918ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:54:21.675499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.29197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T01:54:21.675537Z","caller":"traceutil/trace.go:171","msg":"trace[1219982946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1961; }","duration":"661.37255ms","start":"2026-09-19T01:54:21.014153Z","end":"2026-09-19T01:54:21.675526Z","steps":["trace[1219982946] 'agreement among raft nodes before linearized reading' (duration: 661.234398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:21.675568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:21.014127Z","time spent":"661.433762ms","remote":"127.0.0.1:56074","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-09-19T01:54:21.675684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.34648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d3ac2a9-84bc-41f7-b402-d17f6a2daba6\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-09-19T01:54:21.675806Z","caller":"traceutil/trace.go:171","msg":"trace[31601327] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d3ac2a9-84bc-41f7-b402-d17f6a2daba6; range_end:; response_count:1; response_revision:1961; }","duration":"410.487862ms","start":"2026-09-19T01:54:21.265287Z","end":"2026-09-19T01:54:21.675775Z","steps":["trace[31601327] 'agreement among raft nodes before linearized reading' (duration: 410.259098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:21.675903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:21.265257Z","time spent":"410.629323ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d3ac2a9-84bc-41f7-b402-d17f6a2daba6\" "} {"level":"warn","ts":"2026-09-19T01:54:21.675903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.6116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T01:54:21.675962Z","caller":"traceutil/trace.go:171","msg":"trace[586319810] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:1961; }","duration":"556.770682ms","start":"2026-09-19T01:54:21.119174Z","end":"2026-09-19T01:54:21.675944Z","steps":["trace[586319810] 'agreement among raft nodes before linearized reading' (duration: 556.505618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:21.675999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:21.119135Z","time spent":"556.853424ms","remote":"127.0.0.1:55848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.220\" "} {"level":"info","ts":"2026-09-19T01:54:21.823603Z","caller":"traceutil/trace.go:171","msg":"trace[1811073425] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"140.117866ms","start":"2026-09-19T01:54:21.683455Z","end":"2026-09-19T01:54:21.823573Z","steps":["trace[1811073425] 'process raft request' (duration: 133.456255ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:21.823374Z","caller":"traceutil/trace.go:171","msg":"trace[1601538555] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2037; }","duration":"123.160013ms","start":"2026-09-19T01:54:21.700185Z","end":"2026-09-19T01:54:21.823345Z","steps":["trace[1601538555] 'read index received' (duration: 34.315812ms)","trace[1601538555] 'applied index is now lower than readState.Index' (duration: 88.842941ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:21.824109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.903903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:54:21.824177Z","caller":"traceutil/trace.go:171","msg":"trace[1842046816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1963; }","duration":"124.046845ms","start":"2026-09-19T01:54:21.700114Z","end":"2026-09-19T01:54:21.82416Z","steps":["trace[1842046816] 'agreement among raft nodes before linearized reading' (duration: 123.749471ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:29.849653Z","caller":"traceutil/trace.go:171","msg":"trace[1631422885] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"105.927467ms","start":"2026-09-19T01:54:29.743442Z","end":"2026-09-19T01:54:29.849369Z","steps":["trace[1631422885] 'process raft request' (duration: 105.653613ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:30.046785Z","caller":"traceutil/trace.go:171","msg":"trace[502555884] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"114.954116ms","start":"2026-09-19T01:54:29.931787Z","end":"2026-09-19T01:54:30.046741Z","steps":["trace[502555884] 'process raft request' (duration: 114.689053ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:30.086841Z","caller":"traceutil/trace.go:171","msg":"trace[2138514430] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2111; }","duration":"150.573081ms","start":"2026-09-19T01:54:29.936234Z","end":"2026-09-19T01:54:30.086807Z","steps":["trace[2138514430] 'read index received' (duration: 110.227093ms)","trace[2138514430] 'applied index is now lower than readState.Index' (duration: 40.344428ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:30.08717Z","caller":"traceutil/trace.go:171","msg":"trace[1685099947] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"140.372404ms","start":"2026-09-19T01:54:29.946759Z","end":"2026-09-19T01:54:30.087131Z","steps":["trace[1685099947] 'process raft request' (duration: 140.00805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:30.087096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.829865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:54:30.08736Z","caller":"traceutil/trace.go:171","msg":"trace[298296787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2035; }","duration":"151.166389ms","start":"2026-09-19T01:54:29.936183Z","end":"2026-09-19T01:54:30.08735Z","steps":["trace[298296787] 'agreement among raft nodes before linearized reading' (duration: 150.739894ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:30.087325Z","caller":"traceutil/trace.go:171","msg":"trace[1099026556] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"155.081671ms","start":"2026-09-19T01:54:29.932116Z","end":"2026-09-19T01:54:30.087198Z","steps":["trace[1099026556] 'process raft request' (duration: 154.489663ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:34.992273Z","caller":"traceutil/trace.go:171","msg":"trace[666239320] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"472.266322ms","start":"2026-09-19T01:54:34.519988Z","end":"2026-09-19T01:54:34.992255Z","steps":["trace[666239320] 'read index received' (duration: 472.1325ms)","trace[666239320] 'applied index is now lower than readState.Index' (duration: 132.692µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:34.992362Z","caller":"traceutil/trace.go:171","msg":"trace[439637739] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"607.430302ms","start":"2026-09-19T01:54:34.38489Z","end":"2026-09-19T01:54:34.992321Z","steps":["trace[439637739] 'process raft request' (duration: 607.210819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:34.992504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.508405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:54:34.992544Z","caller":"traceutil/trace.go:171","msg":"trace[1945252640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2076; }","duration":"472.607666ms","start":"2026-09-19T01:54:34.519927Z","end":"2026-09-19T01:54:34.992534Z","steps":["trace[1945252640] 'agreement among raft nodes before linearized reading' (duration: 472.425324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:34.992577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:34.519901Z","time spent":"472.669477ms","remote":"127.0.0.1:55828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T01:54:34.992637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:34.384869Z","time spent":"607.654285ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:35.092568Z","caller":"traceutil/trace.go:171","msg":"trace[2023305238] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"100.218757ms","start":"2026-09-19T01:54:34.992329Z","end":"2026-09-19T01:54:35.092548Z","steps":["trace[2023305238] 'read index received' (duration: 95.576106ms)","trace[2023305238] 'applied index is now lower than readState.Index' (duration: 4.641881ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:35.092846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.403098ms","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-09-19T01:54:35.092885Z","caller":"traceutil/trace.go:171","msg":"trace[788360700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2077; }","duration":"185.50758ms","start":"2026-09-19T01:54:34.907368Z","end":"2026-09-19T01:54:35.092875Z","steps":["trace[788360700] 'agreement among raft nodes before linearized reading' (duration: 185.259607ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:35.092887Z","caller":"traceutil/trace.go:171","msg":"trace[454626396] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"226.695723ms","start":"2026-09-19T01:54:34.866155Z","end":"2026-09-19T01:54:35.092851Z","steps":["trace[454626396] 'process raft request' (duration: 221.794988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:35.093264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.609061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:54:35.093303Z","caller":"traceutil/trace.go:171","msg":"trace[1857536937] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2077; }","duration":"109.686492ms","start":"2026-09-19T01:54:34.983606Z","end":"2026-09-19T01:54:35.093292Z","steps":["trace[1857536937] 'agreement among raft nodes before linearized reading' (duration: 109.53752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:35.093286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.139944ms","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-09-19T01:54:35.093367Z","caller":"traceutil/trace.go:171","msg":"trace[546430788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2077; }","duration":"150.268336ms","start":"2026-09-19T01:54:34.943079Z","end":"2026-09-19T01:54:35.093347Z","steps":["trace[546430788] 'agreement among raft nodes before linearized reading' (duration: 150.047793ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:56.441444Z","caller":"traceutil/trace.go:171","msg":"trace[1458942719] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2284; }","duration":"167.246561ms","start":"2026-09-19T01:54:56.274172Z","end":"2026-09-19T01:54:56.441418Z","steps":["trace[1458942719] 'read index received' (duration: 127.884951ms)","trace[1458942719] 'applied index is now lower than readState.Index' (duration: 39.36022ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:56.442046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.851898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:54:56.44216Z","caller":"traceutil/trace.go:171","msg":"trace[1159242285] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2200; }","duration":"168.00073ms","start":"2026-09-19T01:54:56.274125Z","end":"2026-09-19T01:54:56.442125Z","steps":["trace[1159242285] 'agreement among raft nodes before linearized reading' (duration: 167.717187ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:56.441878Z","caller":"traceutil/trace.go:171","msg":"trace[1233544495] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"240.161528ms","start":"2026-09-19T01:54:56.201687Z","end":"2026-09-19T01:54:56.441848Z","steps":["trace[1233544495] 'process raft request' (duration: 200.424954ms)","trace[1233544495] 'compare' (duration: 38.926504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:56.442858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.142839ms","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-09-19T01:54:56.442906Z","caller":"traceutil/trace.go:171","msg":"trace[1095616063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2200; }","duration":"167.25351ms","start":"2026-09-19T01:54:56.275641Z","end":"2026-09-19T01:54:56.442895Z","steps":["trace[1095616063] 'agreement among raft nodes before linearized reading' (duration: 167.039657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:56.443107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.371542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T01:54:56.443268Z","caller":"traceutil/trace.go:171","msg":"trace[1990206460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2200; }","duration":"167.557484ms","start":"2026-09-19T01:54:56.275679Z","end":"2026-09-19T01:54:56.443236Z","steps":["trace[1990206460] 'agreement among raft nodes before linearized reading' (duration: 167.008027ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:56.750917Z","caller":"traceutil/trace.go:171","msg":"trace[966421008] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"304.111053ms","start":"2026-09-19T01:54:56.446784Z","end":"2026-09-19T01:54:56.750895Z","steps":["trace[966421008] 'process raft request' (duration: 300.308605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:56.75107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:56.446756Z","time spent":"304.247775ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:56.885642Z","caller":"traceutil/trace.go:171","msg":"trace[1871914072] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"438.420144ms","start":"2026-09-19T01:54:56.447193Z","end":"2026-09-19T01:54:56.885613Z","steps":["trace[1871914072] 'process raft request' (duration: 407.549799ms)","trace[1871914072] 'compare' (duration: 30.50694ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:56.885786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:56.447179Z","time spent":"438.526065ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:56.926812Z","caller":"traceutil/trace.go:171","msg":"trace[457797884] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2285; }","duration":"353.937392ms","start":"2026-09-19T01:54:56.572841Z","end":"2026-09-19T01:54:56.926778Z","steps":["trace[457797884] 'read index received' (duration: 174.275377ms)","trace[457797884] 'applied index is now lower than readState.Index' (duration: 179.647945ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:56.927061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.195685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:54:56.927434Z","caller":"traceutil/trace.go:171","msg":"trace[941682560] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2202; }","duration":"354.618561ms","start":"2026-09-19T01:54:56.572798Z","end":"2026-09-19T01:54:56.927416Z","steps":["trace[941682560] 'agreement among raft nodes before linearized reading' (duration: 354.167806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:56.927502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:56.572778Z","time spent":"354.704842ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:54:57.571646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.592042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:54:57.572243Z","caller":"traceutil/trace.go:171","msg":"trace[172699729] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2292; }","duration":"247.062077ms","start":"2026-09-19T01:54:57.325168Z","end":"2026-09-19T01:54:57.57223Z","steps":["trace[172699729] 'read index received' (duration: 40.42µs)","trace[172699729] 'applied index is now lower than readState.Index' (duration: 247.020366ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:57.572271Z","caller":"traceutil/trace.go:171","msg":"trace[359147444] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"310.303902ms","start":"2026-09-19T01:54:57.261941Z","end":"2026-09-19T01:54:57.572245Z","steps":["trace[359147444] 'process raft request' (duration: 58.044281ms)","trace[359147444] 'compare' (duration: 251.170727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:57.57238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:57.261931Z","time spent":"310.395573ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:54:57.572374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.197098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:54:57.572452Z","caller":"traceutil/trace.go:171","msg":"trace[839406241] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2207; }","duration":"247.33837ms","start":"2026-09-19T01:54:57.325102Z","end":"2026-09-19T01:54:57.572441Z","steps":["trace[839406241] 'agreement among raft nodes before linearized reading' (duration: 247.229869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:57.572497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.403832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:54:57.572537Z","caller":"traceutil/trace.go:171","msg":"trace[946703845] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2207; }","duration":"123.491052ms","start":"2026-09-19T01:54:57.449037Z","end":"2026-09-19T01:54:57.572528Z","steps":["trace[946703845] 'agreement among raft nodes before linearized reading' (duration: 123.32119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:57.572526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.43376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d6960dc840a45d\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-19T01:54:57.572603Z","caller":"traceutil/trace.go:171","msg":"trace[1579759200] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d6960dc840a45d; range_end:; response_count:1; response_revision:2207; }","duration":"118.519941ms","start":"2026-09-19T01:54:57.454066Z","end":"2026-09-19T01:54:57.572585Z","steps":["trace[1579759200] 'agreement among raft nodes before linearized reading' (duration: 118.333049ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:57.713365Z","caller":"traceutil/trace.go:171","msg":"trace[810671432] linearizableReadLoop","detail":"{readStateIndex:2294; appliedIndex:2293; }","duration":"101.803573ms","start":"2026-09-19T01:54:57.611532Z","end":"2026-09-19T01:54:57.713336Z","steps":["trace[810671432] 'read index received' (duration: 60.424359ms)","trace[810671432] 'applied index is now lower than readState.Index' (duration: 41.377724ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:57.713552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.998815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:54:57.713578Z","caller":"traceutil/trace.go:171","msg":"trace[1276716255] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2208; }","duration":"102.095847ms","start":"2026-09-19T01:54:57.611475Z","end":"2026-09-19T01:54:57.713572Z","steps":["trace[1276716255] 'agreement among raft nodes before linearized reading' (duration: 101.984515ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:57.713703Z","caller":"traceutil/trace.go:171","msg":"trace[1227907047] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"138.820173ms","start":"2026-09-19T01:54:57.574721Z","end":"2026-09-19T01:54:57.713541Z","steps":["trace[1227907047] 'process raft request' (duration: 97.359639ms)","trace[1227907047] 'compare' (duration: 39.962376ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:58.375773Z","caller":"traceutil/trace.go:171","msg":"trace[781288436] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"331.880711ms","start":"2026-09-19T01:54:58.043875Z","end":"2026-09-19T01:54:58.375755Z","steps":["trace[781288436] 'process raft request' (duration: 331.756119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:58.375902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.043821Z","time spent":"332.015463ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:58.894253Z","caller":"traceutil/trace.go:171","msg":"trace[1262848562] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"447.208928ms","start":"2026-09-19T01:54:58.447017Z","end":"2026-09-19T01:54:58.894226Z","steps":["trace[1262848562] 'process raft request' (duration: 447.122747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:58.894396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.447001Z","time spent":"447.32015ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:58.894521Z","caller":"traceutil/trace.go:171","msg":"trace[1864631169] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"662.951851ms","start":"2026-09-19T01:54:58.23154Z","end":"2026-09-19T01:54:58.894492Z","steps":["trace[1864631169] 'process raft request' (duration: 567.219266ms)","trace[1864631169] 'compare' (duration: 95.209709ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:58.894652Z","caller":"traceutil/trace.go:171","msg":"trace[1538582792] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2295; }","duration":"479.070883ms","start":"2026-09-19T01:54:58.415569Z","end":"2026-09-19T01:54:58.89464Z","steps":["trace[1538582792] 'read index received' (duration: 383.255748ms)","trace[1538582792] 'applied index is now lower than readState.Index' (duration: 95.813785ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:58.894704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.231521Z","time spent":"663.102112ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:54:58.89483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.255706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:54:58.89487Z","caller":"traceutil/trace.go:171","msg":"trace[413089655] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2211; }","duration":"479.325556ms","start":"2026-09-19T01:54:58.415535Z","end":"2026-09-19T01:54:58.894861Z","steps":["trace[413089655] 'agreement among raft nodes before linearized reading' (duration: 479.220875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:58.894899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.415521Z","time spent":"479.370778ms","remote":"127.0.0.1:55940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:54:58.894913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.143444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:54:58.894958Z","caller":"traceutil/trace.go:171","msg":"trace[157683761] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2211; }","duration":"391.224025ms","start":"2026-09-19T01:54:58.503722Z","end":"2026-09-19T01:54:58.894946Z","steps":["trace[157683761] 'agreement among raft nodes before linearized reading' (duration: 391.109914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:58.894999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.503704Z","time spent":"391.286456ms","remote":"127.0.0.1:55980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":15,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:54:58.895304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.080615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T01:54:58.895341Z","caller":"traceutil/trace.go:171","msg":"trace[2136357193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2211; }","duration":"140.152465ms","start":"2026-09-19T01:54:58.755179Z","end":"2026-09-19T01:54:58.895332Z","steps":["trace[2136357193] 'agreement among raft nodes before linearized reading' (duration: 139.992123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:58.895459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.421922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:54:58.895504Z","caller":"traceutil/trace.go:171","msg":"trace[145821968] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2211; }","duration":"317.494162ms","start":"2026-09-19T01:54:58.577996Z","end":"2026-09-19T01:54:58.89549Z","steps":["trace[145821968] 'agreement among raft nodes before linearized reading' (duration: 317.32705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:58.895535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.577982Z","time spent":"317.545743ms","remote":"127.0.0.1:40456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-19T01:54:59.398201Z","caller":"traceutil/trace.go:171","msg":"trace[1808085715] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"498.316161ms","start":"2026-09-19T01:54:58.899847Z","end":"2026-09-19T01:54:59.398163Z","steps":["trace[1808085715] 'process raft request' (duration: 407.491839ms)","trace[1808085715] 'compare' (duration: 90.626401ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:59.398374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.899798Z","time spent":"498.486034ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:54:59.443584Z","caller":"traceutil/trace.go:171","msg":"trace[763883405] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"542.872601ms","start":"2026-09-19T01:54:58.900684Z","end":"2026-09-19T01:54:59.443557Z","steps":["trace[763883405] 'process raft request' (duration: 542.605108ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:59.44377Z","caller":"traceutil/trace.go:171","msg":"trace[323227439] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"281.008013ms","start":"2026-09-19T01:54:59.162723Z","end":"2026-09-19T01:54:59.443731Z","steps":["trace[323227439] 'process raft request' (duration: 280.707829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:59.444193Z","caller":"traceutil/trace.go:171","msg":"trace[1117612435] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2297; }","duration":"169.617656ms","start":"2026-09-19T01:54:59.274557Z","end":"2026-09-19T01:54:59.444175Z","steps":["trace[1117612435] 'read index received' (duration: 32.786535ms)","trace[1117612435] 'applied index is now lower than readState.Index' (duration: 136.829921ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:54:59.444207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:54:58.900658Z","time spent":"543.312498ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:54:59.444616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.054702ms","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-09-19T01:54:59.444614Z","caller":"traceutil/trace.go:171","msg":"trace[1317742732] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"226.899794ms","start":"2026-09-19T01:54:59.217679Z","end":"2026-09-19T01:54:59.444579Z","steps":["trace[1317742732] 'process raft request' (duration: 225.835331ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:59.444674Z","caller":"traceutil/trace.go:171","msg":"trace[2047193014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2215; }","duration":"170.142092ms","start":"2026-09-19T01:54:59.274516Z","end":"2026-09-19T01:54:59.444658Z","steps":["trace[2047193014] 'agreement among raft nodes before linearized reading' (duration: 169.732507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:59.72545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.362146ms","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-09-19T01:54:59.725541Z","caller":"traceutil/trace.go:171","msg":"trace[535981770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2216; }","duration":"149.500108ms","start":"2026-09-19T01:54:59.576019Z","end":"2026-09-19T01:54:59.725519Z","steps":["trace[535981770] 'range keys from in-memory index tree' (duration: 149.132713ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:35.030437Z","caller":"traceutil/trace.go:171","msg":"trace[577955076] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"115.062257ms","start":"2026-09-19T01:56:34.91535Z","end":"2026-09-19T01:56:35.030412Z","steps":["trace[577955076] 'process raft request' (duration: 113.139815ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:45.641838Z","caller":"traceutil/trace.go:171","msg":"trace[1249385921] linearizableReadLoop","detail":"{readStateIndex:2983; appliedIndex:2982; }","duration":"164.33682ms","start":"2026-09-19T01:56:45.47748Z","end":"2026-09-19T01:56:45.641817Z","steps":["trace[1249385921] 'read index received' (duration: 162.830357ms)","trace[1249385921] 'applied index is now lower than readState.Index' (duration: 1.505413ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:45.641968Z","caller":"traceutil/trace.go:171","msg":"trace[1370303447] transaction","detail":"{read_only:false; response_revision:2874; number_of_response:1; }","duration":"281.410869ms","start":"2026-09-19T01:56:45.360547Z","end":"2026-09-19T01:56:45.641957Z","steps":["trace[1370303447] 'process raft request' (duration: 279.863694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:45.642088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.974109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:56:45.642167Z","caller":"traceutil/trace.go:171","msg":"trace[327185325] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2874; }","duration":"142.091603ms","start":"2026-09-19T01:56:45.500038Z","end":"2026-09-19T01:56:45.64213Z","steps":["trace[327185325] 'agreement among raft nodes before linearized reading' (duration: 141.953578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:45.642377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.900449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-19T01:56:45.642416Z","caller":"traceutil/trace.go:171","msg":"trace[1177810489] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2874; }","duration":"164.975253ms","start":"2026-09-19T01:56:45.477429Z","end":"2026-09-19T01:56:45.642405Z","steps":["trace[1177810489] 'agreement among raft nodes before linearized reading' (duration: 164.841408ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:16.403201Z","caller":"traceutil/trace.go:171","msg":"trace[1283369780] linearizableReadLoop","detail":"{readStateIndex:3160; appliedIndex:3159; }","duration":"210.546173ms","start":"2026-09-19T01:57:16.192633Z","end":"2026-09-19T01:57:16.403179Z","steps":["trace[1283369780] 'read index received' (duration: 210.367238ms)","trace[1283369780] 'applied index is now lower than readState.Index' (duration: 178.015µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:57:16.403629Z","caller":"traceutil/trace.go:171","msg":"trace[1683357519] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"239.365603ms","start":"2026-09-19T01:57:16.164236Z","end":"2026-09-19T01:57:16.403602Z","steps":["trace[1683357519] 'process raft request' (duration: 238.820786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:16.403684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.859662ms","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-09-19T01:57:16.403732Z","caller":"traceutil/trace.go:171","msg":"trace[1719938623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3045; }","duration":"197.944544ms","start":"2026-09-19T01:57:16.205775Z","end":"2026-09-19T01:57:16.403719Z","steps":["trace[1719938623] 'agreement among raft nodes before linearized reading' (duration: 197.787589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:16.403941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.296376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:57:16.404001Z","caller":"traceutil/trace.go:171","msg":"trace[1941142125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3045; }","duration":"211.385099ms","start":"2026-09-19T01:57:16.1926Z","end":"2026-09-19T01:57:16.403985Z","steps":["trace[1941142125] 'agreement among raft nodes before linearized reading' (duration: 211.190043ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:21.686108Z","caller":"traceutil/trace.go:171","msg":"trace[668095915] transaction","detail":"{read_only:false; response_revision:3074; number_of_response:1; }","duration":"144.210025ms","start":"2026-09-19T01:57:21.541858Z","end":"2026-09-19T01:57:21.686068Z","steps":["trace[668095915] 'process raft request' (duration: 144.04774ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:21.945871Z","caller":"traceutil/trace.go:171","msg":"trace[1261818723] transaction","detail":"{read_only:false; response_revision:3075; number_of_response:1; }","duration":"150.471695ms","start":"2026-09-19T01:57:21.795369Z","end":"2026-09-19T01:57:21.945841Z","steps":["trace[1261818723] 'process raft request' (duration: 150.055782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:22.331004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.755822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:57:22.331207Z","caller":"traceutil/trace.go:171","msg":"trace[964462990] transaction","detail":"{read_only:false; response_revision:3076; number_of_response:1; }","duration":"310.354115ms","start":"2026-09-19T01:57:22.020836Z","end":"2026-09-19T01:57:22.33119Z","steps":["trace[964462990] 'process raft request' (duration: 174.333075ms)","trace[964462990] 'compare' (duration: 135.555586ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:57:22.331269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:22.020805Z","time spent":"310.436968ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:57:22.331584Z","caller":"traceutil/trace.go:171","msg":"trace[1125422638] transaction","detail":"{read_only:false; response_revision:3077; number_of_response:1; }","duration":"306.399627ms","start":"2026-09-19T01:57:22.025153Z","end":"2026-09-19T01:57:22.331552Z","steps":["trace[1125422638] 'process raft request' (duration: 305.964304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:22.33234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:22.025045Z","time spent":"307.171671ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T02:00:03.271865Z","caller":"traceutil/trace.go:171","msg":"trace[1437116399] transaction","detail":"{read_only:false; response_revision:3955; number_of_response:1; }","duration":"187.796938ms","start":"2026-09-19T02:00:03.083966Z","end":"2026-09-19T02:00:03.271763Z","steps":["trace[1437116399] 'process raft request' (duration: 98.100319ms)","trace[1437116399] 'compare' (duration: 89.505285ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:00:03.279694Z","caller":"traceutil/trace.go:171","msg":"trace[95029424] transaction","detail":"{read_only:false; response_revision:3956; number_of_response:1; }","duration":"192.793493ms","start":"2026-09-19T02:00:03.086882Z","end":"2026-09-19T02:00:03.279675Z","steps":["trace[95029424] 'process raft request' (duration: 192.66445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:00:03.54598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.624817ms","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-09-19T02:00:03.546067Z","caller":"traceutil/trace.go:171","msg":"trace[89478201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3965; }","duration":"101.75988ms","start":"2026-09-19T02:00:03.444288Z","end":"2026-09-19T02:00:03.546048Z","steps":["trace[89478201] 'agreement among raft nodes before linearized reading' (duration: 62.287447ms)","trace[89478201] 'range keys from in-memory index tree' (duration: 39.254088ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:00:03.705641Z","caller":"traceutil/trace.go:171","msg":"trace[1763793590] transaction","detail":"{read_only:false; response_revision:3974; number_of_response:1; }","duration":"107.171182ms","start":"2026-09-19T02:00:03.598444Z","end":"2026-09-19T02:00:03.705616Z","steps":["trace[1763793590] 'process raft request' (duration: 107.084141ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:00:03.705685Z","caller":"traceutil/trace.go:171","msg":"trace[149389065] linearizableReadLoop","detail":"{readStateIndex:4128; appliedIndex:4127; }","duration":"107.194192ms","start":"2026-09-19T02:00:03.598451Z","end":"2026-09-19T02:00:03.705645Z","steps":["trace[149389065] 'read index received' (duration: 48.064636ms)","trace[149389065] 'applied index is now lower than readState.Index' (duration: 59.126416ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:00:03.705873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.400397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:00:03.705917Z","caller":"traceutil/trace.go:171","msg":"trace[792368434] range","detail":"{range_begin:/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa; range_end:; response_count:0; response_revision:3974; }","duration":"107.487838ms","start":"2026-09-19T02:00:03.598416Z","end":"2026-09-19T02:00:03.705904Z","steps":["trace[792368434] 'agreement among raft nodes before linearized reading' (duration: 107.339895ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:00:03.706121Z","caller":"traceutil/trace.go:171","msg":"trace[2138182755] transaction","detail":"{read_only:false; response_revision:3973; number_of_response:1; }","duration":"117.06165ms","start":"2026-09-19T02:00:03.589046Z","end":"2026-09-19T02:00:03.706107Z","steps":["trace[2138182755] 'process raft request' (duration: 57.311431ms)","trace[2138182755] 'compare' (duration: 59.058116ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:01:35.292092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2764} {"level":"info","ts":"2026-09-19T02:01:35.373604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2764,"took":"80.547814ms","hash":3266248774,"current-db-size-bytes":41451520,"current-db-size":"42 MB","current-db-size-in-use-bytes":16478208,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-09-19T02:01:35.373661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3266248774,"revision":2764,"compact-revision":-1} {"level":"info","ts":"2026-09-19T02:02:05.178644Z","caller":"traceutil/trace.go:171","msg":"trace[1819863757] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"207.736539ms","start":"2026-09-19T02:02:04.970853Z","end":"2026-09-19T02:02:05.178589Z","steps":["trace[1819863757] 'process raft request' (duration: 207.557405ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:09.3848Z","caller":"traceutil/trace.go:171","msg":"trace[720631158] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"121.061914ms","start":"2026-09-19T02:02:09.263711Z","end":"2026-09-19T02:02:09.384772Z","steps":["trace[720631158] 'read index received' (duration: 109.421384ms)","trace[720631158] 'applied index is now lower than readState.Index' (duration: 11.63936ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:09.385153Z","caller":"traceutil/trace.go:171","msg":"trace[1777564647] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"161.440672ms","start":"2026-09-19T02:02:09.223676Z","end":"2026-09-19T02:02:09.385117Z","steps":["trace[1777564647] 'process raft request' (duration: 149.539767ms)","trace[1777564647] 'compare' (duration: 11.156512ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:02:09.38519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.408284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-19T02:02:09.385227Z","caller":"traceutil/trace.go:171","msg":"trace[990195747] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5255; }","duration":"117.474605ms","start":"2026-09-19T02:02:09.26774Z","end":"2026-09-19T02:02:09.385215Z","steps":["trace[990195747] 'agreement among raft nodes before linearized reading' (duration: 117.326163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:09.385148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.40283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-19T02:02:09.385776Z","caller":"traceutil/trace.go:171","msg":"trace[255894456] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5255; }","duration":"122.061411ms","start":"2026-09-19T02:02:09.263678Z","end":"2026-09-19T02:02:09.385739Z","steps":["trace[255894456] 'agreement among raft nodes before linearized reading' (duration: 121.243887ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.3246Z","caller":"traceutil/trace.go:171","msg":"trace[1764251084] linearizableReadLoop","detail":"{readStateIndex:5524; appliedIndex:5523; }","duration":"284.762518ms","start":"2026-09-19T02:02:16.039789Z","end":"2026-09-19T02:02:16.324552Z","steps":["trace[1764251084] 'read index received' (duration: 284.581825ms)","trace[1764251084] 'applied index is now lower than readState.Index' (duration: 179.883µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:16.324651Z","caller":"traceutil/trace.go:171","msg":"trace[1581345351] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"361.094696ms","start":"2026-09-19T02:02:15.963532Z","end":"2026-09-19T02:02:16.324626Z","steps":["trace[1581345351] 'process raft request' (duration: 360.888453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:16.324804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:02:15.963505Z","time spent":"361.208789ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T02:02:16.324932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.90402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7c76867f54-plbkt.18d69675e57e01bc\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-19T02:02:16.32502Z","caller":"traceutil/trace.go:171","msg":"trace[1319213950] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7c76867f54-plbkt.18d69675e57e01bc; range_end:; response_count:1; response_revision:5315; }","duration":"285.009642ms","start":"2026-09-19T02:02:16.039995Z","end":"2026-09-19T02:02:16.325005Z","steps":["trace[1319213950] 'agreement among raft nodes before linearized reading' (duration: 284.794088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:16.325167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.01052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-19T02:02:16.32524Z","caller":"traceutil/trace.go:171","msg":"trace[1084683700] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5315; }","duration":"225.105741ms","start":"2026-09-19T02:02:16.100112Z","end":"2026-09-19T02:02:16.325218Z","steps":["trace[1084683700] 'agreement among raft nodes before linearized reading' (duration: 224.964689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:16.327015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.209798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-09-19T02:02:16.327076Z","caller":"traceutil/trace.go:171","msg":"trace[1627287306] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5315; }","duration":"287.273559ms","start":"2026-09-19T02:02:16.039784Z","end":"2026-09-19T02:02:16.327058Z","steps":["trace[1627287306] 'agreement among raft nodes before linearized reading' (duration: 284.911231ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.526449Z","caller":"traceutil/trace.go:171","msg":"trace[1958792067] linearizableReadLoop","detail":"{readStateIndex:5525; appliedIndex:5524; }","duration":"197.425101ms","start":"2026-09-19T02:02:16.328999Z","end":"2026-09-19T02:02:16.526424Z","steps":["trace[1958792067] 'read index received' (duration: 136.373011ms)","trace[1958792067] 'applied index is now lower than readState.Index' (duration: 61.05114ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:16.526557Z","caller":"traceutil/trace.go:171","msg":"trace[2085413805] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"198.415978ms","start":"2026-09-19T02:02:16.328128Z","end":"2026-09-19T02:02:16.526544Z","steps":["trace[2085413805] 'process raft request' (duration: 137.160125ms)","trace[2085413805] 'compare' (duration: 61.016569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:02:16.526856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.823598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-359769d1-9260-4ea0-8c58-d5a82c6b5a9f\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-19T02:02:16.526932Z","caller":"traceutil/trace.go:171","msg":"trace[1317572534] range","detail":"{range_begin:/registry/persistentvolumes/pvc-359769d1-9260-4ea0-8c58-d5a82c6b5a9f; range_end:; response_count:1; response_revision:5316; }","duration":"197.95107ms","start":"2026-09-19T02:02:16.328963Z","end":"2026-09-19T02:02:16.526914Z","steps":["trace[1317572534] 'agreement among raft nodes before linearized reading' (duration: 197.668906ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.557393Z","caller":"traceutil/trace.go:171","msg":"trace[1189517743] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"227.630602ms","start":"2026-09-19T02:02:16.329745Z","end":"2026-09-19T02:02:16.557376Z","steps":["trace[1189517743] 'process raft request' (duration: 227.402358ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.557389Z","caller":"traceutil/trace.go:171","msg":"trace[397434796] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"214.57054ms","start":"2026-09-19T02:02:16.342796Z","end":"2026-09-19T02:02:16.557366Z","steps":["trace[397434796] 'process raft request' (duration: 214.486678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:16.607664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.3215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-19T02:02:16.607761Z","caller":"traceutil/trace.go:171","msg":"trace[913885255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5318; }","duration":"218.459243ms","start":"2026-09-19T02:02:16.389281Z","end":"2026-09-19T02:02:16.60774Z","steps":["trace[913885255] 'agreement among raft nodes before linearized reading' (duration: 218.199459ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.706493Z","caller":"traceutil/trace.go:171","msg":"trace[1495110848] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"141.847631ms","start":"2026-09-19T02:02:16.564622Z","end":"2026-09-19T02:02:16.70647Z","steps":["trace[1495110848] 'process raft request' (duration: 141.624038ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.706692Z","caller":"traceutil/trace.go:171","msg":"trace[2002212318] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"141.972024ms","start":"2026-09-19T02:02:16.564709Z","end":"2026-09-19T02:02:16.706681Z","steps":["trace[2002212318] 'process raft request' (duration: 141.676779ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.706907Z","caller":"traceutil/trace.go:171","msg":"trace[1880068693] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"141.911832ms","start":"2026-09-19T02:02:16.564975Z","end":"2026-09-19T02:02:16.706887Z","steps":["trace[1880068693] 'process raft request' (duration: 141.450505ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:16.707333Z","caller":"traceutil/trace.go:171","msg":"trace[406299487] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"141.597896ms","start":"2026-09-19T02:02:16.565724Z","end":"2026-09-19T02:02:16.707322Z","steps":["trace[406299487] 'process raft request' (duration: 141.090879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:16.707447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.012524ms","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-09-19T02:02:16.707504Z","caller":"traceutil/trace.go:171","msg":"trace[680241104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5322; }","duration":"176.107876ms","start":"2026-09-19T02:02:16.531382Z","end":"2026-09-19T02:02:16.70749Z","steps":["trace[680241104] 'agreement among raft nodes before linearized reading' (duration: 175.941134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.058393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.803595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T02:02:17.058628Z","caller":"traceutil/trace.go:171","msg":"trace[1909151632] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"346.907945ms","start":"2026-09-19T02:02:16.711697Z","end":"2026-09-19T02:02:17.058605Z","steps":["trace[1909151632] 'process raft request' (duration: 346.805893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.058753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:02:16.711681Z","time spent":"347.002006ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T02:02:17.05909Z","caller":"traceutil/trace.go:171","msg":"trace[130328028] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"447.71518ms","start":"2026-09-19T02:02:16.61134Z","end":"2026-09-19T02:02:17.059055Z","steps":["trace[130328028] 'process raft request' (duration: 116.075611ms)","trace[130328028] 'compare' (duration: 330.579181ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:02:17.059326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.489753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T02:02:17.059394Z","caller":"traceutil/trace.go:171","msg":"trace[1791617916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5324; }","duration":"435.591924ms","start":"2026-09-19T02:02:16.623786Z","end":"2026-09-19T02:02:17.059378Z","steps":["trace[1791617916] 'agreement among raft nodes before linearized reading' (duration: 435.391781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.059361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:02:16.611316Z","time spent":"447.880343ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T02:02:17.059441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:02:16.623771Z","time spent":"435.658925ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T02:02:17.059137Z","caller":"traceutil/trace.go:171","msg":"trace[1835413754] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"351.931238ms","start":"2026-09-19T02:02:16.707181Z","end":"2026-09-19T02:02:17.059112Z","steps":["trace[1835413754] 'read index received' (duration: 20.249329ms)","trace[1835413754] 'applied index is now lower than readState.Index' (duration: 331.679339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:02:17.084829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.190025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T02:02:17.084838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.082816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:02:17.0849Z","caller":"traceutil/trace.go:171","msg":"trace[1406296090] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:5325; }","duration":"158.291756ms","start":"2026-09-19T02:02:16.926589Z","end":"2026-09-19T02:02:17.084881Z","steps":["trace[1406296090] 'agreement among raft nodes before linearized reading' (duration: 158.134383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.084905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.734891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:02:17.084928Z","caller":"traceutil/trace.go:171","msg":"trace[28691042] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5325; }","duration":"247.240018ms","start":"2026-09-19T02:02:16.837667Z","end":"2026-09-19T02:02:17.084907Z","steps":["trace[28691042] 'agreement among raft nodes before linearized reading' (duration: 247.038955ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:17.084955Z","caller":"traceutil/trace.go:171","msg":"trace[264997583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5325; }","duration":"235.811993ms","start":"2026-09-19T02:02:16.849127Z","end":"2026-09-19T02:02:17.084939Z","steps":["trace[264997583] 'agreement among raft nodes before linearized reading' (duration: 235.741922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.084855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.002096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T02:02:17.085093Z","caller":"traceutil/trace.go:171","msg":"trace[756835676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5325; }","duration":"215.276349ms","start":"2026-09-19T02:02:16.869796Z","end":"2026-09-19T02:02:17.085072Z","steps":["trace[756835676] 'agreement among raft nodes before linearized reading' (duration: 214.965355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.08527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.393474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-19T02:02:17.085296Z","caller":"traceutil/trace.go:171","msg":"trace[510151559] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5325; }","duration":"111.492857ms","start":"2026-09-19T02:02:16.973797Z","end":"2026-09-19T02:02:17.08529Z","steps":["trace[510151559] 'agreement among raft nodes before linearized reading' (duration: 111.307084ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:17.319423Z","caller":"traceutil/trace.go:171","msg":"trace[361955626] linearizableReadLoop","detail":"{readStateIndex:5536; appliedIndex:5535; }","duration":"233.300872ms","start":"2026-09-19T02:02:17.086103Z","end":"2026-09-19T02:02:17.319404Z","steps":["trace[361955626] 'read index received' (duration: 176.823626ms)","trace[361955626] 'applied index is now lower than readState.Index' (duration: 56.476536ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:17.319609Z","caller":"traceutil/trace.go:171","msg":"trace[1153458162] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"255.574533ms","start":"2026-09-19T02:02:17.064026Z","end":"2026-09-19T02:02:17.3196Z","steps":["trace[1153458162] 'process raft request' (duration: 198.819283ms)","trace[1153458162] 'compare' (duration: 56.253252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:02:17.319745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.629897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:02:17.319766Z","caller":"traceutil/trace.go:171","msg":"trace[645569741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5326; }","duration":"233.696668ms","start":"2026-09-19T02:02:17.086064Z","end":"2026-09-19T02:02:17.319761Z","steps":["trace[645569741] 'agreement among raft nodes before linearized reading' (duration: 233.647937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.319936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.159016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-19T02:02:17.319957Z","caller":"traceutil/trace.go:171","msg":"trace[1518638908] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5326; }","duration":"231.213428ms","start":"2026-09-19T02:02:17.088736Z","end":"2026-09-19T02:02:17.319949Z","steps":["trace[1518638908] 'agreement among raft nodes before linearized reading' (duration: 231.132426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:17.32022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.190418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-19T02:02:17.320245Z","caller":"traceutil/trace.go:171","msg":"trace[1785060062] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5326; }","duration":"183.253479ms","start":"2026-09-19T02:02:17.136982Z","end":"2026-09-19T02:02:17.320235Z","steps":["trace[1785060062] 'agreement among raft nodes before linearized reading' (duration: 183.178629ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:17.539849Z","caller":"traceutil/trace.go:171","msg":"trace[1998042052] linearizableReadLoop","detail":"{readStateIndex:5537; appliedIndex:5536; }","duration":"147.280736ms","start":"2026-09-19T02:02:17.39253Z","end":"2026-09-19T02:02:17.539811Z","steps":["trace[1998042052] 'read index received' (duration: 139.051233ms)","trace[1998042052] 'applied index is now lower than readState.Index' (duration: 8.228643ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:02:17.53995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.406928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T02:02:17.539968Z","caller":"traceutil/trace.go:171","msg":"trace[911205177] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5327; }","duration":"147.476ms","start":"2026-09-19T02:02:17.392487Z","end":"2026-09-19T02:02:17.539963Z","steps":["trace[911205177] 'agreement among raft nodes before linearized reading' (duration: 147.401549ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:17.540078Z","caller":"traceutil/trace.go:171","msg":"trace[2138127005] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"214.330733ms","start":"2026-09-19T02:02:17.325716Z","end":"2026-09-19T02:02:17.540047Z","steps":["trace[2138127005] 'process raft request' (duration: 205.916747ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:20.622786Z","caller":"traceutil/trace.go:171","msg":"trace[1507510574] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"292.50191ms","start":"2026-09-19T02:02:20.33025Z","end":"2026-09-19T02:02:20.622752Z","steps":["trace[1507510574] 'process raft request' (duration: 291.147488ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:20.623236Z","caller":"traceutil/trace.go:171","msg":"trace[1509227724] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5592; }","duration":"284.840157ms","start":"2026-09-19T02:02:20.338381Z","end":"2026-09-19T02:02:20.623221Z","steps":["trace[1509227724] 'read index received' (duration: 283.096478ms)","trace[1509227724] 'applied index is now lower than readState.Index' (duration: 1.742879ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:20.623397Z","caller":"traceutil/trace.go:171","msg":"trace[2045416741] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"289.888318ms","start":"2026-09-19T02:02:20.333492Z","end":"2026-09-19T02:02:20.623381Z","steps":["trace[2045416741] 'process raft request' (duration: 289.612404ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:20.623391Z","caller":"traceutil/trace.go:171","msg":"trace[1873539934] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"283.568416ms","start":"2026-09-19T02:02:20.339768Z","end":"2026-09-19T02:02:20.623336Z","steps":["trace[1873539934] 'process raft request' (duration: 283.402963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:20.623754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.345174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-19T02:02:20.623912Z","caller":"traceutil/trace.go:171","msg":"trace[540534910] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5385; }","duration":"285.457495ms","start":"2026-09-19T02:02:20.338354Z","end":"2026-09-19T02:02:20.623811Z","steps":["trace[540534910] 'agreement among raft nodes before linearized reading' (duration: 285.228913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:20.625785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.074633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-19T02:02:20.625921Z","caller":"traceutil/trace.go:171","msg":"trace[719298496] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5385; }","duration":"172.222156ms","start":"2026-09-19T02:02:20.453653Z","end":"2026-09-19T02:02:20.625875Z","steps":["trace[719298496] 'agreement among raft nodes before linearized reading' (duration: 169.831997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:22.325258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.848786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a11b5d1-5b86-485e-a098-67f093b677b3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-19T02:02:22.325343Z","caller":"traceutil/trace.go:171","msg":"trace[1232637322] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7a11b5d1-5b86-485e-a098-67f093b677b3; range_end:; response_count:1; response_revision:5435; }","duration":"316.986558ms","start":"2026-09-19T02:02:22.008335Z","end":"2026-09-19T02:02:22.325321Z","steps":["trace[1232637322] 'range keys from in-memory index tree' (duration: 316.642233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:22.325385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:02:22.00831Z","time spent":"317.06418ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a11b5d1-5b86-485e-a098-67f093b677b3\" "} {"level":"warn","ts":"2026-09-19T02:02:22.325439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.641429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a11b5d1-5b86-485e-a098-67f093b677b3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-19T02:02:22.32554Z","caller":"traceutil/trace.go:171","msg":"trace[1777821953] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7a11b5d1-5b86-485e-a098-67f093b677b3; range_end:; response_count:1; response_revision:5435; }","duration":"221.798012ms","start":"2026-09-19T02:02:22.103703Z","end":"2026-09-19T02:02:22.325501Z","steps":["trace[1777821953] 'range keys from in-memory index tree' (duration: 221.383895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:22.325466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.070989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T02:02:22.325622Z","caller":"traceutil/trace.go:171","msg":"trace[175382932] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5435; }","duration":"359.257752ms","start":"2026-09-19T02:02:21.966343Z","end":"2026-09-19T02:02:22.325601Z","steps":["trace[175382932] 'count revisions from in-memory index tree' (duration: 358.973437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:02:22.32568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:02:21.96633Z","time spent":"359.333923ms","remote":"127.0.0.1:55970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":4,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-09-19T02:02:34.400601Z","caller":"traceutil/trace.go:171","msg":"trace[299387494] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"103.928994ms","start":"2026-09-19T02:02:34.29664Z","end":"2026-09-19T02:02:34.400569Z","steps":["trace[299387494] 'process raft request' (duration: 103.69143ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:34.400726Z","caller":"traceutil/trace.go:171","msg":"trace[2074933155] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"103.158702ms","start":"2026-09-19T02:02:34.297531Z","end":"2026-09-19T02:02:34.400689Z","steps":["trace[2074933155] 'process raft request' (duration: 102.899818ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:02:34.40078Z","caller":"traceutil/trace.go:171","msg":"trace[191684607] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"104.33696ms","start":"2026-09-19T02:02:34.296308Z","end":"2026-09-19T02:02:34.400645Z","steps":["trace[191684607] 'process raft request' (duration: 75.704582ms)","trace[191684607] 'compare' (duration: 28.193901ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:03:04.343286Z","caller":"traceutil/trace.go:171","msg":"trace[1055999046] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"190.832223ms","start":"2026-09-19T02:03:04.152426Z","end":"2026-09-19T02:03:04.343259Z","steps":["trace[1055999046] 'process raft request' (duration: 190.67116ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:03:06.45757Z","caller":"traceutil/trace.go:171","msg":"trace[1003740908] transaction","detail":"{read_only:false; response_revision:5881; number_of_response:1; }","duration":"108.198531ms","start":"2026-09-19T02:03:06.349322Z","end":"2026-09-19T02:03:06.457521Z","steps":["trace[1003740908] 'process raft request' (duration: 108.005738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:03:07.270421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.797376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-19T02:03:07.270551Z","caller":"traceutil/trace.go:171","msg":"trace[1758572386] linearizableReadLoop","detail":"{readStateIndex:6106; appliedIndex:6105; }","duration":"303.539998ms","start":"2026-09-19T02:03:06.966986Z","end":"2026-09-19T02:03:07.270526Z","steps":["trace[1758572386] 'read index received' (duration: 67.29µs)","trace[1758572386] 'applied index is now lower than readState.Index' (duration: 303.469048ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:03:07.270855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.804952ms","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-09-19T02:03:07.271229Z","caller":"traceutil/trace.go:171","msg":"trace[1822077232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5881; }","duration":"304.060477ms","start":"2026-09-19T02:03:06.966928Z","end":"2026-09-19T02:03:07.270989Z","steps":["trace[1822077232] 'agreement among raft nodes before linearized reading' (duration: 303.66819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:03:07.271303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T02:03:06.966907Z","time spent":"304.376851ms","remote":"127.0.0.1:56074","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-09-19T02:03:11.527213Z","caller":"traceutil/trace.go:171","msg":"trace[597149658] transaction","detail":"{read_only:false; response_revision:5908; number_of_response:1; }","duration":"104.257505ms","start":"2026-09-19T02:03:11.422915Z","end":"2026-09-19T02:03:11.527173Z","steps":["trace[597149658] 'process raft request' (duration: 51.886713ms)","trace[597149658] 'compare' (duration: 52.104747ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:03:11.686627Z","caller":"traceutil/trace.go:171","msg":"trace[892930319] transaction","detail":"{read_only:false; response_revision:5910; number_of_response:1; }","duration":"146.420826ms","start":"2026-09-19T02:03:11.540194Z","end":"2026-09-19T02:03:11.686615Z","steps":["trace[892930319] 'process raft request' (duration: 125.545019ms)","trace[892930319] 'compare' (duration: 20.648704ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:03:11.686614Z","caller":"traceutil/trace.go:171","msg":"trace[1787008989] transaction","detail":"{read_only:false; response_revision:5911; number_of_response:1; }","duration":"132.63212ms","start":"2026-09-19T02:03:11.553954Z","end":"2026-09-19T02:03:11.686587Z","steps":["trace[1787008989] 'process raft request' (duration: 132.555619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:06:15.284712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.475455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-09-19T02:06:15.284955Z","caller":"traceutil/trace.go:171","msg":"trace[522145997] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:7181; }","duration":"184.724865ms","start":"2026-09-19T02:06:15.100167Z","end":"2026-09-19T02:06:15.284891Z","steps":["trace[522145997] 'range keys from in-memory index tree' (duration: 184.346342ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T02:06:22.614345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.789304ms","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-09-19T02:06:22.614458Z","caller":"traceutil/trace.go:171","msg":"trace[702208534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7222; }","duration":"100.944099ms","start":"2026-09-19T02:06:22.513488Z","end":"2026-09-19T02:06:22.614432Z","steps":["trace[702208534] 'range keys from in-memory index tree' (duration: 100.499165ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:06:35.303165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4564} {"level":"info","ts":"2026-09-19T02:06:35.396792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4564,"took":"92.192825ms","hash":4147330923,"current-db-size-bytes":41451520,"current-db-size":"42 MB","current-db-size-in-use-bytes":33382400,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-19T02:06:35.396857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4147330923,"revision":4564,"compact-revision":2764} {"level":"info","ts":"2026-09-19T02:06:40.635782Z","caller":"traceutil/trace.go:171","msg":"trace[167774604] transaction","detail":"{read_only:false; response_revision:7366; number_of_response:1; }","duration":"108.406384ms","start":"2026-09-19T02:06:40.52735Z","end":"2026-09-19T02:06:40.635757Z","steps":["trace[167774604] 'process raft request' (duration: 108.225208ms)"],"step_count":1}