{"level":"warn","ts":"2026-04-02T02:12:46.4323Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T02:12:46.432431Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T02:12:46.432524Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T02:12:46.432535Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-02T02:12:46.432564Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T02:12:46.43315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-02T02:12:46.433236Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T02:12:46.438102Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.671336ms"} {"level":"info","ts":"2026-04-02T02:12:46.448533Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-02T02:12:46.448599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T02:12:46.448617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-02T02:12:46.448627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T02:12:46.448635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-02T02:12:46.448665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-02T02:12:46.462022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T02:12:46.464096Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T02:12:46.465721Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T02:12:46.467564Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T02:12:46.468118Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T02:12:46.46833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T02:12:46.468407Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T02:12:46.468427Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T02:12:46.472699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-02T02:12:46.472838Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-02T02:12:46.473334Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T02:12:46.473507Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T02:12:46.473535Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T02:12:46.473638Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-02T02:12:46.473657Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-02T02:12:46.649677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T02:12:46.649733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T02:12:46.649788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-02T02:12:46.649814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-02T02:12:46.649831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-02T02:12:46.649853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-02T02:12:46.649875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-02T02:12:46.650902Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T02:12:46.65174Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T02:12:46.651759Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T02:12:46.651824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T02:12:46.653458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T02:12:46.655997Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T02:12:46.656464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T02:12:46.657879Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T02:12:46.657903Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T02:12:46.658355Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-02T02:12:46.657868Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T02:12:49.577409Z","caller":"traceutil/trace.go:171","msg":"trace[1256675377] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"440.194774ms","start":"2026-04-02T02:12:49.137186Z","end":"2026-04-02T02:12:49.57738Z","steps":["trace[1256675377] 'read index received' (duration: 439.99496ms)","trace[1256675377] 'applied index is now lower than readState.Index' (duration: 198.694µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:12:49.577631Z","caller":"traceutil/trace.go:171","msg":"trace[701478942] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"440.445429ms","start":"2026-04-02T02:12:49.137148Z","end":"2026-04-02T02:12:49.577593Z","steps":["trace[701478942] 'process raft request' (duration: 440.060602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:12:49.577646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.432818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T02:12:49.577727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.154336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T02:12:49.577887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.692821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T02:12:49.577932Z","caller":"traceutil/trace.go:171","msg":"trace[2147164098] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:76; }","duration":"440.738664ms","start":"2026-04-02T02:12:49.137143Z","end":"2026-04-02T02:12:49.577881Z","steps":["trace[2147164098] 'agreement among raft nodes before linearized reading' (duration: 440.391808ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:12:49.577999Z","caller":"traceutil/trace.go:171","msg":"trace[788613608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"339.359619ms","start":"2026-04-02T02:12:49.238528Z","end":"2026-04-02T02:12:49.577888Z","steps":["trace[788613608] 'agreement among raft nodes before linearized reading' (duration: 339.154655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:12:49.578067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:49.137123Z","time spent":"440.924097ms","remote":"127.0.0.1:39546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-04-02T02:12:49.578052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:49.238516Z","time spent":"339.523693ms","remote":"127.0.0.1:39208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T02:12:49.578026Z","caller":"traceutil/trace.go:171","msg":"trace[2012078287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"372.003687ms","start":"2026-04-02T02:12:49.206004Z","end":"2026-04-02T02:12:49.578008Z","steps":["trace[2012078287] 'agreement among raft nodes before linearized reading' (duration: 371.682222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:12:49.578205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:49.205986Z","time spent":"372.205211ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T02:12:49.578557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:49.137125Z","time spent":"440.653752ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T02:12:50.366188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.823199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T02:12:50.366296Z","caller":"traceutil/trace.go:171","msg":"trace[884343101] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"780.860139ms","start":"2026-04-02T02:12:49.585417Z","end":"2026-04-02T02:12:50.366277Z","steps":["trace[884343101] 'read index received' (duration: 354.719044ms)","trace[884343101] 'applied index is now lower than readState.Index' (duration: 426.139705ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:12:50.36638Z","caller":"traceutil/trace.go:171","msg":"trace[1333283855] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"782.348498ms","start":"2026-04-02T02:12:49.584021Z","end":"2026-04-02T02:12:50.36637Z","steps":["trace[1333283855] 'process raft request' (duration: 356.05119ms)","trace[1333283855] 'compare' (duration: 425.664535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:12:50.366437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:49.58401Z","time spent":"782.404328ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T02:12:50.366916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.235785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T02:12:50.366984Z","caller":"traceutil/trace.go:171","msg":"trace[2099610248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"160.356947ms","start":"2026-04-02T02:12:50.206609Z","end":"2026-04-02T02:12:50.366966Z","steps":["trace[2099610248] 'agreement among raft nodes before linearized reading' (duration: 160.115533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:12:50.367097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.661734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T02:12:50.367158Z","caller":"traceutil/trace.go:171","msg":"trace[1590057245] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:77; }","duration":"781.740036ms","start":"2026-04-02T02:12:49.585402Z","end":"2026-04-02T02:12:50.367142Z","steps":["trace[1590057245] 'agreement among raft nodes before linearized reading' (duration: 781.657104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:12:50.36719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:49.585396Z","time spent":"781.787386ms","remote":"127.0.0.1:39546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-04-02T02:12:50.843021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.165463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-02T02:12:50.843306Z","caller":"traceutil/trace.go:171","msg":"trace[1530906260] linearizableReadLoop","detail":"{readStateIndex:83; appliedIndex:81; }","duration":"471.008426ms","start":"2026-04-02T02:12:50.372279Z","end":"2026-04-02T02:12:50.843287Z","steps":["trace[1530906260] 'read index received' (duration: 344.236682ms)","trace[1530906260] 'applied index is now lower than readState.Index' (duration: 126.770844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:12:50.843491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.211719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T02:12:50.84353Z","caller":"traceutil/trace.go:171","msg":"trace[643535015] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:79; }","duration":"471.28761ms","start":"2026-04-02T02:12:50.372232Z","end":"2026-04-02T02:12:50.843519Z","steps":["trace[643535015] 'agreement among raft nodes before linearized reading' (duration: 471.113997ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:12:50.843486Z","caller":"traceutil/trace.go:171","msg":"trace[1657662566] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"472.033045ms","start":"2026-04-02T02:12:50.37143Z","end":"2026-04-02T02:12:50.843463Z","steps":["trace[1657662566] 'process raft request' (duration: 471.693889ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:12:50.843594Z","caller":"traceutil/trace.go:171","msg":"trace[30405956] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"472.236009ms","start":"2026-04-02T02:12:50.371317Z","end":"2026-04-02T02:12:50.843553Z","steps":["trace[30405956] 'process raft request' (duration: 345.21125ms)","trace[30405956] 'compare' (duration: 126.0122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:12:50.843668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:50.372218Z","time spent":"471.337532ms","remote":"127.0.0.1:39546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-04-02T02:12:50.843766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:50.371295Z","time spent":"472.381402ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:12:50.843762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:12:50.371419Z","time spent":"472.215508ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:13:17.627148Z","caller":"traceutil/trace.go:171","msg":"trace[1895168525] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"329.810676ms","start":"2026-04-02T02:13:17.29731Z","end":"2026-04-02T02:13:17.627121Z","steps":["trace[1895168525] 'process raft request' (duration: 327.408162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:17.627333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:17.297286Z","time spent":"329.965608ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:13:17.63438Z","caller":"traceutil/trace.go:171","msg":"trace[1706527845] transaction","detail":"{read_only:false; number_of_response:1; response_revision:523; }","duration":"330.112619ms","start":"2026-04-02T02:13:17.304225Z","end":"2026-04-02T02:13:17.634337Z","steps":["trace[1706527845] 'process raft request' (duration: 329.748233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:17.634562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:17.304207Z","time spent":"330.260704ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":150531,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:13:17.634787Z","caller":"traceutil/trace.go:171","msg":"trace[727104136] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"303.507777ms","start":"2026-04-02T02:13:17.33083Z","end":"2026-04-02T02:13:17.634338Z","steps":["trace[727104136] 'process raft request' (duration: 303.410056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:17.634895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:17.33079Z","time spent":"304.052218ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T02:13:17.964407Z","caller":"traceutil/trace.go:171","msg":"trace[1823316697] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"278.956472ms","start":"2026-04-02T02:13:17.685426Z","end":"2026-04-02T02:13:17.964383Z","steps":["trace[1823316697] 'process raft request' (duration: 272.004546ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:17.964896Z","caller":"traceutil/trace.go:171","msg":"trace[832214738] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"270.472988ms","start":"2026-04-02T02:13:17.694406Z","end":"2026-04-02T02:13:17.964879Z","steps":["trace[832214738] 'process raft request' (duration: 270.369776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:21.887335Z","caller":"traceutil/trace.go:171","msg":"trace[1195928609] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"240.979824ms","start":"2026-04-02T02:13:21.646321Z","end":"2026-04-02T02:13:21.887301Z","steps":["trace[1195928609] 'process raft request' (duration: 240.25299ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:21.894678Z","caller":"traceutil/trace.go:171","msg":"trace[2010134871] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"179.097133ms","start":"2026-04-02T02:13:21.715562Z","end":"2026-04-02T02:13:21.89466Z","steps":["trace[2010134871] 'process raft request' (duration: 178.998742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:24.571604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113402263958737,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T02:13:25.072578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113402263958737,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T02:13:25.092477Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.197454667s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T02:13:25.102805Z","caller":"traceutil/trace.go:171","msg":"trace[1513467855] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"1.031580655s","start":"2026-04-02T02:13:24.071192Z","end":"2026-04-02T02:13:25.102773Z","steps":["trace[1513467855] 'read index received' (duration: 1.021471143s)","trace[1513467855] 'applied index is now lower than readState.Index' (duration: 10.108242ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:13:25.102899Z","caller":"traceutil/trace.go:171","msg":"trace[1287381304] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.209698826s","start":"2026-04-02T02:13:23.893175Z","end":"2026-04-02T02:13:25.102874Z","steps":["trace[1287381304] 'process raft request' (duration: 1.205820546s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:25.103252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.141046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hgwrv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-02T02:13:25.103279Z","caller":"traceutil/trace.go:171","msg":"trace[568203920] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hgwrv; range_end:; response_count:1; response_revision:581; }","duration":"729.413761ms","start":"2026-04-02T02:13:24.373856Z","end":"2026-04-02T02:13:25.10327Z","steps":["trace[568203920] 'agreement among raft nodes before linearized reading' (duration: 729.084045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:25.103306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:24.373832Z","time spent":"729.468222ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hgwrv\" "} {"level":"warn","ts":"2026-04-02T02:13:25.103366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.363023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:13:25.103336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032120755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T02:13:25.103414Z","caller":"traceutil/trace.go:171","msg":"trace[1732848202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"711.472056ms","start":"2026-04-02T02:13:24.391926Z","end":"2026-04-02T02:13:25.103398Z","steps":["trace[1732848202] 'agreement among raft nodes before linearized reading' (duration: 711.365124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:25.103435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:24.391895Z","time spent":"711.534507ms","remote":"127.0.0.1:39208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T02:13:25.103448Z","caller":"traceutil/trace.go:171","msg":"trace[836311657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"1.032302819s","start":"2026-04-02T02:13:24.071121Z","end":"2026-04-02T02:13:25.103424Z","steps":["trace[836311657] 'agreement among raft nodes before linearized reading' (duration: 1.0317949s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:25.103499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:24.071094Z","time spent":"1.03239193s","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T02:13:25.103672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.528487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T02:13:25.103741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:23.893131Z","time spent":"1.210103454s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:13:25.103782Z","caller":"traceutil/trace.go:171","msg":"trace[703870163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"246.713231ms","start":"2026-04-02T02:13:24.857047Z","end":"2026-04-02T02:13:25.10376Z","steps":["trace[703870163] 'agreement among raft nodes before linearized reading' (duration: 246.230492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:25.250146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.898211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T02:13:25.250552Z","caller":"traceutil/trace.go:171","msg":"trace[834792458] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"136.63512ms","start":"2026-04-02T02:13:25.113874Z","end":"2026-04-02T02:13:25.250509Z","steps":["trace[834792458] 'process raft request' (duration: 136.399786ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:25.250642Z","caller":"traceutil/trace.go:171","msg":"trace[1300420379] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"135.642762ms","start":"2026-04-02T02:13:25.114954Z","end":"2026-04-02T02:13:25.250596Z","steps":["trace[1300420379] 'process raft request' (duration: 135.515069ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:25.250517Z","caller":"traceutil/trace.go:171","msg":"trace[1538364278] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"137.020767ms","start":"2026-04-02T02:13:25.113412Z","end":"2026-04-02T02:13:25.250433Z","steps":["trace[1538364278] 'process raft request' (duration: 13.725238ms)","trace[1538364278] 'compare' (duration: 122.721549ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:13:25.250827Z","caller":"traceutil/trace.go:171","msg":"trace[271549875] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"132.149678ms","start":"2026-04-02T02:13:25.11866Z","end":"2026-04-02T02:13:25.25081Z","steps":["trace[271549875] 'process raft request' (duration: 131.854353ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:25.250937Z","caller":"traceutil/trace.go:171","msg":"trace[2077608639] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"136.70251ms","start":"2026-04-02T02:13:25.114219Z","end":"2026-04-02T02:13:25.250922Z","steps":["trace[2077608639] 'process raft request' (duration: 136.168001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.693695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300524544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.693781Z","caller":"traceutil/trace.go:171","msg":"trace[1652541620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"1.300691107s","start":"2026-04-02T02:13:25.393067Z","end":"2026-04-02T02:13:26.693758Z","steps":["trace[1652541620] 'range keys from in-memory index tree' (duration: 1.30035762s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.693819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:25.393015Z","time spent":"1.300796989s","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T02:13:26.696559Z","caller":"traceutil/trace.go:171","msg":"trace[380968497] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"383.064951ms","start":"2026-04-02T02:13:26.313486Z","end":"2026-04-02T02:13:26.696551Z","steps":["trace[380968497] 'read index received' (duration: 382.444591ms)","trace[380968497] 'applied index is now lower than readState.Index' (duration: 619.75µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:13:26.698699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.087928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.698762Z","caller":"traceutil/trace.go:171","msg":"trace[1465704813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:588; }","duration":"385.20278ms","start":"2026-04-02T02:13:26.313541Z","end":"2026-04-02T02:13:26.698744Z","steps":["trace[1465704813] 'agreement among raft nodes before linearized reading' (duration: 385.113509ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:26.696889Z","caller":"traceutil/trace.go:171","msg":"trace[490412633] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.039142299s","start":"2026-04-02T02:13:25.657706Z","end":"2026-04-02T02:13:26.696848Z","steps":["trace[490412633] 'process raft request' (duration: 1.038454137s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.698886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:25.657641Z","time spent":"1.041175676s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:13:26.697154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.554011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.698972Z","caller":"traceutil/trace.go:171","msg":"trace[1695390480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:588; }","duration":"385.515285ms","start":"2026-04-02T02:13:26.313444Z","end":"2026-04-02T02:13:26.698959Z","steps":["trace[1695390480] 'agreement among raft nodes before linearized reading' (duration: 383.55472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.700105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:26.31342Z","time spent":"386.642647ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:13:26.699059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.220617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700254Z","caller":"traceutil/trace.go:171","msg":"trace[706841818] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"274.43896ms","start":"2026-04-02T02:13:26.425789Z","end":"2026-04-02T02:13:26.700227Z","steps":["trace[706841818] 'agreement among raft nodes before linearized reading' (duration: 273.225217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.69881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:26.313505Z","time spent":"385.292591ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T02:13:26.699135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.719964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700368Z","caller":"traceutil/trace.go:171","msg":"trace[1915116063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"228.951407ms","start":"2026-04-02T02:13:26.471408Z","end":"2026-04-02T02:13:26.700359Z","steps":["trace[1915116063] 'agreement among raft nodes before linearized reading' (duration: 227.682513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.699181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.902278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700472Z","caller":"traceutil/trace.go:171","msg":"trace[701688944] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"229.180162ms","start":"2026-04-02T02:13:26.471274Z","end":"2026-04-02T02:13:26.700454Z","steps":["trace[701688944] 'agreement among raft nodes before linearized reading' (duration: 227.872027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.699201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.943668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700577Z","caller":"traceutil/trace.go:171","msg":"trace[1954971871] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"229.318714ms","start":"2026-04-02T02:13:26.471254Z","end":"2026-04-02T02:13:26.700572Z","steps":["trace[1954971871] 'agreement among raft nodes before linearized reading' (duration: 227.937698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.69922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.040324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.70069Z","caller":"traceutil/trace.go:171","msg":"trace[2033942632] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"274.521261ms","start":"2026-04-02T02:13:26.426164Z","end":"2026-04-02T02:13:26.700686Z","steps":["trace[2033942632] 'agreement among raft nodes before linearized reading' (duration: 273.045784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.69925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.48709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700772Z","caller":"traceutil/trace.go:171","msg":"trace[1222960873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:588; }","duration":"338.025108ms","start":"2026-04-02T02:13:26.362743Z","end":"2026-04-02T02:13:26.700768Z","steps":["trace[1222960873] 'agreement among raft nodes before linearized reading' (duration: 336.49324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.700792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:26.362737Z","time spent":"338.046349ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-04-02T02:13:26.699291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.673495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700885Z","caller":"traceutil/trace.go:171","msg":"trace[1169351216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"275.287444ms","start":"2026-04-02T02:13:26.425593Z","end":"2026-04-02T02:13:26.70088Z","steps":["trace[1169351216] 'agreement among raft nodes before linearized reading' (duration: 273.666354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.69932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.640252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.700985Z","caller":"traceutil/trace.go:171","msg":"trace[524932306] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:588; }","duration":"338.339624ms","start":"2026-04-02T02:13:26.362641Z","end":"2026-04-02T02:13:26.70098Z","steps":["trace[524932306] 'agreement among raft nodes before linearized reading' (duration: 336.664873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.701002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:26.36262Z","time spent":"338.375934ms","remote":"127.0.0.1:40156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:13:26.699348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.921608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:13:26.701143Z","caller":"traceutil/trace.go:171","msg":"trace[1609612971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:588; }","duration":"338.750471ms","start":"2026-04-02T02:13:26.362387Z","end":"2026-04-02T02:13:26.701137Z","steps":["trace[1609612971] 'agreement among raft nodes before linearized reading' (duration: 336.948588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:26.701162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:26.362363Z","time spent":"338.792652ms","remote":"127.0.0.1:40156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T02:13:44.128017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.494203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jvjxl\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-02T02:13:44.128124Z","caller":"traceutil/trace.go:171","msg":"trace[899829258] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jvjxl; range_end:; response_count:1; response_revision:751; }","duration":"648.710248ms","start":"2026-04-02T02:13:43.479393Z","end":"2026-04-02T02:13:44.128103Z","steps":["trace[899829258] 'range keys from in-memory index tree' (duration: 648.246876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:44.128169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:13:43.479365Z","time spent":"648.794ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jvjxl\" "} {"level":"info","ts":"2026-04-02T02:13:44.234809Z","caller":"traceutil/trace.go:171","msg":"trace[1202785065] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"299.608872ms","start":"2026-04-02T02:13:43.935181Z","end":"2026-04-02T02:13:44.23479Z","steps":["trace[1202785065] 'process raft request' (duration: 299.450368ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:13:44.408232Z","caller":"traceutil/trace.go:171","msg":"trace[1119324439] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"167.224286ms","start":"2026-04-02T02:13:44.240978Z","end":"2026-04-02T02:13:44.408202Z","steps":["trace[1119324439] 'read index received' (duration: 166.927528ms)","trace[1119324439] 'applied index is now lower than readState.Index' (duration: 295.548µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:13:44.408372Z","caller":"traceutil/trace.go:171","msg":"trace[988693804] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"269.611012ms","start":"2026-04-02T02:13:44.138721Z","end":"2026-04-02T02:13:44.408332Z","steps":["trace[988693804] 'process raft request' (duration: 269.280994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:13:44.408604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.574255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T02:13:44.408701Z","caller":"traceutil/trace.go:171","msg":"trace[1364498425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"167.741999ms","start":"2026-04-02T02:13:44.240936Z","end":"2026-04-02T02:13:44.408678Z","steps":["trace[1364498425] 'agreement among raft nodes before linearized reading' (duration: 167.429821ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:14:35.305626Z","caller":"traceutil/trace.go:171","msg":"trace[1807588275] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"116.00927ms","start":"2026-04-02T02:14:35.189398Z","end":"2026-04-02T02:14:35.305407Z","steps":["trace[1807588275] 'process raft request' (duration: 115.890477ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:14:37.178622Z","caller":"traceutil/trace.go:171","msg":"trace[1130718925] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"329.718443ms","start":"2026-04-02T02:14:36.848864Z","end":"2026-04-02T02:14:37.178583Z","steps":["trace[1130718925] 'process raft request' (duration: 329.468817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:14:37.178863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:14:36.848833Z","time spent":"329.900827ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:14:48.844796Z","caller":"traceutil/trace.go:171","msg":"trace[1264135683] linearizableReadLoop","detail":"{readStateIndex:1717; appliedIndex:1716; }","duration":"100.414ms","start":"2026-04-02T02:14:48.744355Z","end":"2026-04-02T02:14:48.844769Z","steps":["trace[1264135683] 'read index received' (duration: 96.451684ms)","trace[1264135683] 'applied index is now lower than readState.Index' (duration: 3.961226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:14:48.845346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.951561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:14:48.845461Z","caller":"traceutil/trace.go:171","msg":"trace[693417020] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1651; }","duration":"101.120225ms","start":"2026-04-02T02:14:48.744318Z","end":"2026-04-02T02:14:48.845438Z","steps":["trace[693417020] 'agreement among raft nodes before linearized reading' (duration: 100.856629ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:14:48.845448Z","caller":"traceutil/trace.go:171","msg":"trace[163835584] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"116.619168ms","start":"2026-04-02T02:14:48.728802Z","end":"2026-04-02T02:14:48.845421Z","steps":["trace[163835584] 'process raft request' (duration: 112.059839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:14:55.485063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.660837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:14:55.48517Z","caller":"traceutil/trace.go:171","msg":"trace[1420962857] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1731; }","duration":"151.834911ms","start":"2026-04-02T02:14:55.33332Z","end":"2026-04-02T02:14:55.485155Z","steps":["trace[1420962857] 'range keys from in-memory index tree' (duration: 151.552625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:14:55.485554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.957874ms","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:5"} {"level":"info","ts":"2026-04-02T02:14:55.485586Z","caller":"traceutil/trace.go:171","msg":"trace[1169622343] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1731; }","duration":"151.992065ms","start":"2026-04-02T02:14:55.333587Z","end":"2026-04-02T02:14:55.485579Z","steps":["trace[1169622343] 'count revisions from in-memory index tree' (duration: 151.872372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:14:55.485688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.91381ms","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:5"} {"level":"info","ts":"2026-04-02T02:14:55.48571Z","caller":"traceutil/trace.go:171","msg":"trace[1443413555] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1731; }","duration":"122.95486ms","start":"2026-04-02T02:14:55.36275Z","end":"2026-04-02T02:14:55.485705Z","steps":["trace[1443413555] 'count revisions from in-memory index tree' (duration: 122.848269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:14:55.485788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.117064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:14:55.48586Z","caller":"traceutil/trace.go:171","msg":"trace[1284820952] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1731; }","duration":"123.242747ms","start":"2026-04-02T02:14:55.362593Z","end":"2026-04-02T02:14:55.485836Z","steps":["trace[1284820952] 'range keys from in-memory index tree' (duration: 122.912129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:14:55.485647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.819131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:14:55.486368Z","caller":"traceutil/trace.go:171","msg":"trace[1744264317] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1731; }","duration":"117.589637ms","start":"2026-04-02T02:14:55.368759Z","end":"2026-04-02T02:14:55.486348Z","steps":["trace[1744264317] 'count revisions from in-memory index tree' (duration: 116.736628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:04.216865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.831166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-02T02:15:04.216939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.41591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T02:15:04.216975Z","caller":"traceutil/trace.go:171","msg":"trace[1609014471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"198.00724ms","start":"2026-04-02T02:15:04.018945Z","end":"2026-04-02T02:15:04.216952Z","steps":["trace[1609014471] 'range keys from in-memory index tree' (duration: 197.5565ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:04.217006Z","caller":"traceutil/trace.go:171","msg":"trace[2026610897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1845; }","duration":"348.532702ms","start":"2026-04-02T02:15:03.868455Z","end":"2026-04-02T02:15:04.216987Z","steps":["trace[2026610897] 'range keys from in-memory index tree' (duration: 348.081763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:04.217036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:03.868432Z","time spent":"348.597893ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T02:15:04.217047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.07309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1fdcb03f-4da0-4d54-896f-d90cc8bf68ac\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-02T02:15:04.217138Z","caller":"traceutil/trace.go:171","msg":"trace[368400000] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1fdcb03f-4da0-4d54-896f-d90cc8bf68ac; range_end:; response_count:1; response_revision:1845; }","duration":"322.196742ms","start":"2026-04-02T02:15:03.89492Z","end":"2026-04-02T02:15:04.217116Z","steps":["trace[368400000] 'range keys from in-memory index tree' (duration: 321.925337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:04.217188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:03.894907Z","time spent":"322.267034ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1fdcb03f-4da0-4d54-896f-d90cc8bf68ac\" "} {"level":"warn","ts":"2026-04-02T02:15:04.217307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.839379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:15:04.217345Z","caller":"traceutil/trace.go:171","msg":"trace[1332978754] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1845; }","duration":"472.91713ms","start":"2026-04-02T02:15:03.744415Z","end":"2026-04-02T02:15:04.217332Z","steps":["trace[1332978754] 'count revisions from in-memory index tree' (duration: 472.650535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:04.217372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:03.744391Z","time spent":"472.973292ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:15:04.217382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.511105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-02T02:15:04.217429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.547036ms","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-04-02T02:15:04.217492Z","caller":"traceutil/trace.go:171","msg":"trace[2146898376] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"341.660479ms","start":"2026-04-02T02:15:03.875816Z","end":"2026-04-02T02:15:04.217476Z","steps":["trace[2146898376] 'range keys from in-memory index tree' (duration: 341.387233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:04.217604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.451118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T02:15:04.217631Z","caller":"traceutil/trace.go:171","msg":"trace[784909823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1845; }","duration":"357.535599ms","start":"2026-04-02T02:15:03.860089Z","end":"2026-04-02T02:15:04.217624Z","steps":["trace[784909823] 'range keys from in-memory index tree' (duration: 357.262544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:04.217649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:03.860055Z","time spent":"357.588741ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T02:15:04.218119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:03.87577Z","time spent":"342.332892ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T02:15:04.217499Z","caller":"traceutil/trace.go:171","msg":"trace[948263586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1845; }","duration":"192.699389ms","start":"2026-04-02T02:15:04.024763Z","end":"2026-04-02T02:15:04.217463Z","steps":["trace[948263586] 'range keys from in-memory index tree' (duration: 192.25907ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:04.409939Z","caller":"traceutil/trace.go:171","msg":"trace[1430208618] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"186.99403ms","start":"2026-04-02T02:15:04.222911Z","end":"2026-04-02T02:15:04.409905Z","steps":["trace[1430208618] 'process raft request' (duration: 186.636333ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:05.081042Z","caller":"traceutil/trace.go:171","msg":"trace[250626402] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"857.032529ms","start":"2026-04-02T02:15:04.223979Z","end":"2026-04-02T02:15:05.081012Z","steps":["trace[250626402] 'process raft request' (duration: 856.845866ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:05.081032Z","caller":"traceutil/trace.go:171","msg":"trace[1001015162] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"857.707434ms","start":"2026-04-02T02:15:04.223255Z","end":"2026-04-02T02:15:05.080962Z","steps":["trace[1001015162] 'process raft request' (duration: 857.414719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:05.081336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.223232Z","time spent":"857.933131ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:05.081446Z","caller":"traceutil/trace.go:171","msg":"trace[855819713] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"857.328947ms","start":"2026-04-02T02:15:04.224082Z","end":"2026-04-02T02:15:05.081411Z","steps":["trace[855819713] 'process raft request' (duration: 856.795265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:05.081407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.223952Z","time spent":"857.170653ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:05.081613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.224073Z","time spent":"857.453659ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:05.081804Z","caller":"traceutil/trace.go:171","msg":"trace[1502978279] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1919; }","duration":"682.230686ms","start":"2026-04-02T02:15:04.39948Z","end":"2026-04-02T02:15:05.081711Z","steps":["trace[1502978279] 'read index received' (duration: 10.113681ms)","trace[1502978279] 'applied index is now lower than readState.Index' (duration: 672.115335ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:05.081978Z","caller":"traceutil/trace.go:171","msg":"trace[744799719] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"857.00543ms","start":"2026-04-02T02:15:04.224933Z","end":"2026-04-02T02:15:05.081938Z","steps":["trace[744799719] 'process raft request' (duration: 855.985229ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:05.082039Z","caller":"traceutil/trace.go:171","msg":"trace[1260439005] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"724.183701ms","start":"2026-04-02T02:15:04.357842Z","end":"2026-04-02T02:15:05.082026Z","steps":["trace[1260439005] 'process raft request' (duration: 723.105978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:05.082114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.357827Z","time spent":"724.250332ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:05.082236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.224919Z","time spent":"857.141483ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:05.082243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.803697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:15:05.082413Z","caller":"traceutil/trace.go:171","msg":"trace[491994451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"683.029302ms","start":"2026-04-02T02:15:04.399363Z","end":"2026-04-02T02:15:05.082392Z","steps":["trace[491994451] 'agreement among raft nodes before linearized reading' (duration: 682.832508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:05.082458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.399325Z","time spent":"683.120554ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T02:15:05.201382Z","caller":"traceutil/trace.go:171","msg":"trace[1561585129] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"119.474858ms","start":"2026-04-02T02:15:05.081891Z","end":"2026-04-02T02:15:05.201366Z","steps":["trace[1561585129] 'read index received' (duration: 119.348775ms)","trace[1561585129] 'applied index is now lower than readState.Index' (duration: 125.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:05.201471Z","caller":"traceutil/trace.go:171","msg":"trace[1649319275] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"536.36714ms","start":"2026-04-02T02:15:04.665096Z","end":"2026-04-02T02:15:05.201463Z","steps":["trace[1649319275] 'process raft request' (duration: 536.238387ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:05.20149Z","caller":"traceutil/trace.go:171","msg":"trace[867651200] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"718.868705ms","start":"2026-04-02T02:15:04.482576Z","end":"2026-04-02T02:15:05.201444Z","steps":["trace[867651200] 'process raft request' (duration: 718.665931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:05.201584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.665078Z","time spent":"536.40987ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:05.201683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.482545Z","time spent":"719.037428ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:05.201747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.718341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:15:05.201771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.567592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:15:05.201894Z","caller":"traceutil/trace.go:171","msg":"trace[236621794] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1853; }","duration":"521.791616ms","start":"2026-04-02T02:15:04.680083Z","end":"2026-04-02T02:15:05.201875Z","steps":["trace[236621794] 'agreement among raft nodes before linearized reading' (duration: 521.453328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:05.202004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:04.680019Z","time spent":"521.968371ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:15:05.202058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.983579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T02:15:05.202109Z","caller":"traceutil/trace.go:171","msg":"trace[1264730917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1853; }","duration":"275.072732ms","start":"2026-04-02T02:15:04.927024Z","end":"2026-04-02T02:15:05.202097Z","steps":["trace[1264730917] 'agreement among raft nodes before linearized reading' (duration: 274.904518ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:05.201812Z","caller":"traceutil/trace.go:171","msg":"trace[1241109574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"116.810713ms","start":"2026-04-02T02:15:05.084983Z","end":"2026-04-02T02:15:05.201793Z","steps":["trace[1241109574] 'agreement among raft nodes before linearized reading' (duration: 116.70298ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:19.309392Z","caller":"traceutil/trace.go:171","msg":"trace[1903974435] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"139.024593ms","start":"2026-04-02T02:15:19.170336Z","end":"2026-04-02T02:15:19.309361Z","steps":["trace[1903974435] 'read index received' (duration: 138.943502ms)","trace[1903974435] 'applied index is now lower than readState.Index' (duration: 80.071µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:19.309538Z","caller":"traceutil/trace.go:171","msg":"trace[593552251] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"180.41997ms","start":"2026-04-02T02:15:19.129078Z","end":"2026-04-02T02:15:19.309498Z","steps":["trace[593552251] 'process raft request' (duration: 180.125113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:19.309635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.282078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T02:15:19.309675Z","caller":"traceutil/trace.go:171","msg":"trace[1084526983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1967; }","duration":"139.338509ms","start":"2026-04-02T02:15:19.170324Z","end":"2026-04-02T02:15:19.309662Z","steps":["trace[1084526983] 'agreement among raft nodes before linearized reading' (duration: 139.193196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:19.313782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.002062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T02:15:19.313864Z","caller":"traceutil/trace.go:171","msg":"trace[977808932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1969; }","duration":"139.092444ms","start":"2026-04-02T02:15:19.174751Z","end":"2026-04-02T02:15:19.313843Z","steps":["trace[977808932] 'agreement among raft nodes before linearized reading' (duration: 138.87164ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:19.786336Z","caller":"traceutil/trace.go:171","msg":"trace[231225700] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"367.876583ms","start":"2026-04-02T02:15:19.418434Z","end":"2026-04-02T02:15:19.78631Z","steps":["trace[231225700] 'process raft request' (duration: 367.71651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:19.786513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:19.418403Z","time spent":"368.008815ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:35.572184Z","caller":"traceutil/trace.go:171","msg":"trace[1250979595] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"211.035803ms","start":"2026-04-02T02:15:35.361124Z","end":"2026-04-02T02:15:35.57216Z","steps":["trace[1250979595] 'process raft request' (duration: 210.866319ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:35.631391Z","caller":"traceutil/trace.go:171","msg":"trace[1777755362] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2143; }","duration":"231.717709ms","start":"2026-04-02T02:15:35.399639Z","end":"2026-04-02T02:15:35.631357Z","steps":["trace[1777755362] 'read index received' (duration: 172.433327ms)","trace[1777755362] 'applied index is now lower than readState.Index' (duration: 59.283672ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:35.631457Z","caller":"traceutil/trace.go:171","msg":"trace[1233993328] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"262.469147ms","start":"2026-04-02T02:15:35.368953Z","end":"2026-04-02T02:15:35.631423Z","steps":["trace[1233993328] 'process raft request' (duration: 262.359075ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:35.631453Z","caller":"traceutil/trace.go:171","msg":"trace[162987307] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"266.512819ms","start":"2026-04-02T02:15:35.364882Z","end":"2026-04-02T02:15:35.631395Z","steps":["trace[162987307] 'process raft request' (duration: 232.412793ms)","trace[162987307] 'compare' (duration: 33.876561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:15:35.63177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.106817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:15:35.631821Z","caller":"traceutil/trace.go:171","msg":"trace[769918737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2066; }","duration":"232.209999ms","start":"2026-04-02T02:15:35.399599Z","end":"2026-04-02T02:15:35.631809Z","steps":["trace[769918737] 'agreement among raft nodes before linearized reading' (duration: 231.909533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:35.631972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.752117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T02:15:35.632048Z","caller":"traceutil/trace.go:171","msg":"trace[2120367697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2066; }","duration":"203.870099ms","start":"2026-04-02T02:15:35.42816Z","end":"2026-04-02T02:15:35.63203Z","steps":["trace[2120367697] 'agreement among raft nodes before linearized reading' (duration: 203.667185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:35.632036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.197346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T02:15:35.632112Z","caller":"traceutil/trace.go:171","msg":"trace[570292799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2066; }","duration":"211.314419ms","start":"2026-04-02T02:15:35.420781Z","end":"2026-04-02T02:15:35.632095Z","steps":["trace[570292799] 'agreement among raft nodes before linearized reading' (duration: 211.030363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:35.632232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.082743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T02:15:35.632266Z","caller":"traceutil/trace.go:171","msg":"trace[1420779586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2066; }","duration":"204.138985ms","start":"2026-04-02T02:15:35.428117Z","end":"2026-04-02T02:15:35.632256Z","steps":["trace[1420779586] 'agreement among raft nodes before linearized reading' (duration: 204.016542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:35.632279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.939341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T02:15:35.632328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.56626ms","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-04-02T02:15:35.632357Z","caller":"traceutil/trace.go:171","msg":"trace[1549695351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2066; }","duration":"211.075983ms","start":"2026-04-02T02:15:35.421266Z","end":"2026-04-02T02:15:35.632342Z","steps":["trace[1549695351] 'agreement among raft nodes before linearized reading' (duration: 210.87685ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:35.632364Z","caller":"traceutil/trace.go:171","msg":"trace[1385832535] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2066; }","duration":"183.622402ms","start":"2026-04-02T02:15:35.448727Z","end":"2026-04-02T02:15:35.632349Z","steps":["trace[1385832535] 'agreement among raft nodes before linearized reading' (duration: 183.53356ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:41.793767Z","caller":"traceutil/trace.go:171","msg":"trace[1117900012] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2177; }","duration":"142.204884ms","start":"2026-04-02T02:15:41.651539Z","end":"2026-04-02T02:15:41.793744Z","steps":["trace[1117900012] 'read index received' (duration: 142.126942ms)","trace[1117900012] 'applied index is now lower than readState.Index' (duration: 76.762µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:15:41.794081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.531241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T02:15:41.794161Z","caller":"traceutil/trace.go:171","msg":"trace[1940095255] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:2097; }","duration":"142.618773ms","start":"2026-04-02T02:15:41.651522Z","end":"2026-04-02T02:15:41.794141Z","steps":["trace[1940095255] 'agreement among raft nodes before linearized reading' (duration: 142.336347ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:41.794543Z","caller":"traceutil/trace.go:171","msg":"trace[2034064514] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"202.724264ms","start":"2026-04-02T02:15:41.591745Z","end":"2026-04-02T02:15:41.79447Z","steps":["trace[2034064514] 'process raft request' (duration: 201.828477ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:41.927507Z","caller":"traceutil/trace.go:171","msg":"trace[178129926] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"274.474348ms","start":"2026-04-02T02:15:41.653006Z","end":"2026-04-02T02:15:41.92748Z","steps":["trace[178129926] 'process raft request' (duration: 250.902547ms)","trace[178129926] 'compare' (duration: 23.409787ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:41.927834Z","caller":"traceutil/trace.go:171","msg":"trace[1990177552] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2178; }","duration":"133.949849ms","start":"2026-04-02T02:15:41.793838Z","end":"2026-04-02T02:15:41.927788Z","steps":["trace[1990177552] 'read index received' (duration: 109.988709ms)","trace[1990177552] 'applied index is now lower than readState.Index' (duration: 23.9566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:15:41.927964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.690493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T02:15:41.928033Z","caller":"traceutil/trace.go:171","msg":"trace[685687608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2099; }","duration":"165.791476ms","start":"2026-04-02T02:15:41.762215Z","end":"2026-04-02T02:15:41.928006Z","steps":["trace[685687608] 'agreement among raft nodes before linearized reading' (duration: 165.650953ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:41.928075Z","caller":"traceutil/trace.go:171","msg":"trace[2035690033] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"273.51719ms","start":"2026-04-02T02:15:41.654545Z","end":"2026-04-02T02:15:41.928062Z","steps":["trace[2035690033] 'process raft request' (duration: 272.893617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:41.927954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.526471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T02:15:41.928303Z","caller":"traceutil/trace.go:171","msg":"trace[1202454533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"160.894218ms","start":"2026-04-02T02:15:41.767367Z","end":"2026-04-02T02:15:41.928261Z","steps":["trace[1202454533] 'agreement among raft nodes before linearized reading' (duration: 160.453099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:41.928226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.573452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T02:15:41.928424Z","caller":"traceutil/trace.go:171","msg":"trace[40973385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"160.806037ms","start":"2026-04-02T02:15:41.767597Z","end":"2026-04-02T02:15:41.928403Z","steps":["trace[40973385] 'agreement among raft nodes before linearized reading' (duration: 160.567372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:41.928476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.654522ms","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":"warn","ts":"2026-04-02T02:15:41.928493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.711304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T02:15:41.928759Z","caller":"traceutil/trace.go:171","msg":"trace[1446011926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"256.894588ms","start":"2026-04-02T02:15:41.671766Z","end":"2026-04-02T02:15:41.92866Z","steps":["trace[1446011926] 'agreement among raft nodes before linearized reading' (duration: 256.450839ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:41.928739Z","caller":"traceutil/trace.go:171","msg":"trace[995642306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"173.094742ms","start":"2026-04-02T02:15:41.755612Z","end":"2026-04-02T02:15:41.928707Z","steps":["trace[995642306] 'agreement among raft nodes before linearized reading' (duration: 172.378748ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:42.288982Z","caller":"traceutil/trace.go:171","msg":"trace[2043133661] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"173.744892ms","start":"2026-04-02T02:15:42.11521Z","end":"2026-04-02T02:15:42.288955Z","steps":["trace[2043133661] 'process raft request' (duration: 129.378265ms)","trace[2043133661] 'compare' (duration: 43.964128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:15:42.289004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.18124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-04-02T02:15:42.28911Z","caller":"traceutil/trace.go:171","msg":"trace[1775549106] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2105; }","duration":"206.297182ms","start":"2026-04-02T02:15:42.082771Z","end":"2026-04-02T02:15:42.289068Z","steps":["trace[1775549106] 'range keys from in-memory index tree' (duration: 206.009436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.477574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113402263961832,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T02:15:44.60227Z","caller":"traceutil/trace.go:171","msg":"trace[544178504] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"802.205436ms","start":"2026-04-02T02:15:43.800034Z","end":"2026-04-02T02:15:44.60224Z","steps":["trace[544178504] 'process raft request' (duration: 802.018043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.602438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.800017Z","time spent":"802.324899ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:44.953155Z","caller":"traceutil/trace.go:171","msg":"trace[1371087952] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2189; }","duration":"975.93282ms","start":"2026-04-02T02:15:43.977197Z","end":"2026-04-02T02:15:44.95313Z","steps":["trace[1371087952] 'read index received' (duration: 624.890016ms)","trace[1371087952] 'applied index is now lower than readState.Index' (duration: 351.041634ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:44.953218Z","caller":"traceutil/trace.go:171","msg":"trace[448992816] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"1.01952859s","start":"2026-04-02T02:15:43.933664Z","end":"2026-04-02T02:15:44.953193Z","steps":["trace[448992816] 'process raft request' (duration: 992.852457ms)","trace[448992816] 'compare' (duration: 26.348375ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:44.953306Z","caller":"traceutil/trace.go:171","msg":"trace[1992532527] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"1.019638172s","start":"2026-04-02T02:15:43.933655Z","end":"2026-04-02T02:15:44.953293Z","steps":["trace[1992532527] 'process raft request' (duration: 1.019346066s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.953432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.933612Z","time spent":"1.019731874s","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:44.953465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.933635Z","time spent":"1.019642801s","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:44.953704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.502921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T02:15:44.953795Z","caller":"traceutil/trace.go:171","msg":"trace[358170462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2111; }","duration":"976.651804ms","start":"2026-04-02T02:15:43.977124Z","end":"2026-04-02T02:15:44.953776Z","steps":["trace[358170462] 'agreement among raft nodes before linearized reading' (duration: 976.43757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.953841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.977099Z","time spent":"976.734225ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T02:15:44.953854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.495032ms","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-04-02T02:15:44.953711Z","caller":"traceutil/trace.go:171","msg":"trace[401464310] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"1.006189603s","start":"2026-04-02T02:15:43.947487Z","end":"2026-04-02T02:15:44.953676Z","steps":["trace[401464310] 'process raft request' (duration: 1.005559591s)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:44.953939Z","caller":"traceutil/trace.go:171","msg":"trace[399987335] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2111; }","duration":"927.629265ms","start":"2026-04-02T02:15:44.026286Z","end":"2026-04-02T02:15:44.953915Z","steps":["trace[399987335] 'agreement among raft nodes before linearized reading' (duration: 927.428591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.954006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.099824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:15:44.954024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:44.026267Z","time spent":"927.746408ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T02:15:44.954054Z","caller":"traceutil/trace.go:171","msg":"trace[711493155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2111; }","duration":"562.188675ms","start":"2026-04-02T02:15:44.391854Z","end":"2026-04-02T02:15:44.954042Z","steps":["trace[711493155] 'agreement among raft nodes before linearized reading' (duration: 562.083893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.95407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.242565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T02:15:44.95414Z","caller":"traceutil/trace.go:171","msg":"trace[1082559914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"203.386438ms","start":"2026-04-02T02:15:44.750735Z","end":"2026-04-02T02:15:44.954122Z","steps":["trace[1082559914] 'agreement among raft nodes before linearized reading' (duration: 203.183704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.954149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.947471Z","time spent":"1.00655369s","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:44.954182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.98509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-02T02:15:44.954231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.577432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T02:15:44.954238Z","caller":"traceutil/trace.go:171","msg":"trace[1621158127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2111; }","duration":"976.070242ms","start":"2026-04-02T02:15:43.978157Z","end":"2026-04-02T02:15:44.954227Z","steps":["trace[1621158127] 'agreement among raft nodes before linearized reading' (duration: 975.878858ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:15:44.954271Z","caller":"traceutil/trace.go:171","msg":"trace[1135469585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2111; }","duration":"976.631324ms","start":"2026-04-02T02:15:43.977628Z","end":"2026-04-02T02:15:44.954259Z","steps":["trace[1135469585] 'agreement among raft nodes before linearized reading' (duration: 976.492591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.954282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.978149Z","time spent":"976.126303ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T02:15:44.95409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:44.391837Z","time spent":"562.246916ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T02:15:44.954304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.977623Z","time spent":"976.674724ms","remote":"127.0.0.1:39460","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-04-02T02:15:44.954943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.333177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T02:15:44.954994Z","caller":"traceutil/trace.go:171","msg":"trace[1713670779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2111; }","duration":"977.378858ms","start":"2026-04-02T02:15:43.977603Z","end":"2026-04-02T02:15:44.954982Z","steps":["trace[1713670779] 'agreement among raft nodes before linearized reading' (duration: 976.055712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:44.955034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:43.97755Z","time spent":"977.47437ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T02:15:45.560757Z","caller":"traceutil/trace.go:171","msg":"trace[82857074] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"600.701854ms","start":"2026-04-02T02:15:44.960013Z","end":"2026-04-02T02:15:45.560715Z","steps":["trace[82857074] 'process raft request' (duration: 580.251116ms)","trace[82857074] 'compare' (duration: 20.116041ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:15:45.560832Z","caller":"traceutil/trace.go:171","msg":"trace[1403316285] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"600.788005ms","start":"2026-04-02T02:15:44.960012Z","end":"2026-04-02T02:15:45.5608Z","steps":["trace[1403316285] 'process raft request' (duration: 600.640472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:45.56098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:44.959988Z","time spent":"600.906929ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:45.561028Z","caller":"traceutil/trace.go:171","msg":"trace[1067077643] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"600.165234ms","start":"2026-04-02T02:15:44.960834Z","end":"2026-04-02T02:15:45.560999Z","steps":["trace[1067077643] 'process raft request' (duration: 599.891638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:45.561195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:44.960823Z","time spent":"600.289147ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:15:45.561286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:44.959988Z","time spent":"600.853047ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:45.561327Z","caller":"traceutil/trace.go:171","msg":"trace[348845563] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"600.035032ms","start":"2026-04-02T02:15:44.961279Z","end":"2026-04-02T02:15:45.561314Z","steps":["trace[348845563] 'process raft request' (duration: 599.509621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:15:45.561393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:15:44.961268Z","time spent":"600.089313ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:15:45.560853Z","caller":"traceutil/trace.go:171","msg":"trace[737023327] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2194; }","duration":"168.279944ms","start":"2026-04-02T02:15:45.392551Z","end":"2026-04-02T02:15:45.560831Z","steps":["trace[737023327] 'read index received' (duration: 147.690094ms)","trace[737023327] 'applied index is now lower than readState.Index' (duration: 20.58898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:15:45.561713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.149063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:15:45.561783Z","caller":"traceutil/trace.go:171","msg":"trace[985771136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2116; }","duration":"169.286515ms","start":"2026-04-02T02:15:45.392483Z","end":"2026-04-02T02:15:45.561769Z","steps":["trace[985771136] 'agreement among raft nodes before linearized reading' (duration: 169.0235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:16:37.457719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.430044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:16:37.457831Z","caller":"traceutil/trace.go:171","msg":"trace[1438762241] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2441; }","duration":"117.606267ms","start":"2026-04-02T02:16:37.340198Z","end":"2026-04-02T02:16:37.457804Z","steps":["trace[1438762241] 'count revisions from in-memory index tree' (duration: 117.314512ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:16:37.607243Z","caller":"traceutil/trace.go:171","msg":"trace[1319977573] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"139.813626ms","start":"2026-04-02T02:16:37.467403Z","end":"2026-04-02T02:16:37.607216Z","steps":["trace[1319977573] 'process raft request' (duration: 139.647523ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:16:46.988603Z","caller":"traceutil/trace.go:171","msg":"trace[1167563311] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"163.309253ms","start":"2026-04-02T02:16:46.825248Z","end":"2026-04-02T02:16:46.988557Z","steps":["trace[1167563311] 'process raft request' (duration: 163.1573ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:17:13.470965Z","caller":"traceutil/trace.go:171","msg":"trace[2127396715] linearizableReadLoop","detail":"{readStateIndex:2796; appliedIndex:2795; }","duration":"135.826885ms","start":"2026-04-02T02:17:13.335105Z","end":"2026-04-02T02:17:13.470932Z","steps":["trace[2127396715] 'read index received' (duration: 135.624451ms)","trace[2127396715] 'applied index is now lower than readState.Index' (duration: 200.394µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:17:13.47103Z","caller":"traceutil/trace.go:171","msg":"trace[1668460366] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"138.145009ms","start":"2026-04-02T02:17:13.332851Z","end":"2026-04-02T02:17:13.470996Z","steps":["trace[1668460366] 'process raft request' (duration: 137.879414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:13.471561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.427086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-02T02:17:13.471804Z","caller":"traceutil/trace.go:171","msg":"trace[1828786681] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2693; }","duration":"136.545668ms","start":"2026-04-02T02:17:13.33506Z","end":"2026-04-02T02:17:13.471605Z","steps":["trace[1828786681] 'agreement among raft nodes before linearized reading' (duration: 136.017998ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:17:13.524584Z","caller":"traceutil/trace.go:171","msg":"trace[1045555578] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"171.415066ms","start":"2026-04-02T02:17:13.353148Z","end":"2026-04-02T02:17:13.524564Z","steps":["trace[1045555578] 'process raft request' (duration: 171.09767ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:17:13.524805Z","caller":"traceutil/trace.go:171","msg":"trace[1619642961] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"167.678335ms","start":"2026-04-02T02:17:13.357117Z","end":"2026-04-02T02:17:13.524796Z","steps":["trace[1619642961] 'process raft request' (duration: 167.250777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:13.525119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.702965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:17:13.525192Z","caller":"traceutil/trace.go:171","msg":"trace[1516348215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2695; }","duration":"131.839278ms","start":"2026-04-02T02:17:13.393333Z","end":"2026-04-02T02:17:13.525172Z","steps":["trace[1516348215] 'agreement among raft nodes before linearized reading' (duration: 131.686115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:13.524986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.55649ms","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-04-02T02:17:13.527131Z","caller":"traceutil/trace.go:171","msg":"trace[349460016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2695; }","duration":"112.772083ms","start":"2026-04-02T02:17:13.414346Z","end":"2026-04-02T02:17:13.527118Z","steps":["trace[349460016] 'agreement among raft nodes before linearized reading' (duration: 110.54337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:20.127862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.345488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T02:17:20.128025Z","caller":"traceutil/trace.go:171","msg":"trace[1479765218] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"586.408198ms","start":"2026-04-02T02:17:19.541549Z","end":"2026-04-02T02:17:20.127957Z","steps":["trace[1479765218] 'process raft request' (duration: 352.708304ms)","trace[1479765218] 'compare' (duration: 232.995511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:17:20.128686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:19.541527Z","time spent":"586.53426ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:17:20.355677Z","caller":"traceutil/trace.go:171","msg":"trace[1651965354] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2828; }","duration":"790.868322ms","start":"2026-04-02T02:17:19.564783Z","end":"2026-04-02T02:17:20.355651Z","steps":["trace[1651965354] 'read index received' (duration: 329.54293ms)","trace[1651965354] 'applied index is now lower than readState.Index' (duration: 461.324402ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:17:20.355947Z","caller":"traceutil/trace.go:171","msg":"trace[1890625208] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"811.895554ms","start":"2026-04-02T02:17:19.544032Z","end":"2026-04-02T02:17:20.355928Z","steps":["trace[1890625208] 'process raft request' (duration: 725.689154ms)","trace[1890625208] 'compare' (duration: 85.795832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:17:20.35619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.353831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a2689b61955caa\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-02T02:17:20.356231Z","caller":"traceutil/trace.go:171","msg":"trace[1546251968] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a2689b61955caa; range_end:; response_count:1; response_revision:2726; }","duration":"650.454494ms","start":"2026-04-02T02:17:19.705766Z","end":"2026-04-02T02:17:20.356221Z","steps":["trace[1546251968] 'agreement among raft nodes before linearized reading' (duration: 650.330221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:20.356235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.479673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-02T02:17:20.356392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.952631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T02:17:20.356427Z","caller":"traceutil/trace.go:171","msg":"trace[1660779239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2726; }","duration":"175.019561ms","start":"2026-04-02T02:17:20.1814Z","end":"2026-04-02T02:17:20.356419Z","steps":["trace[1660779239] 'agreement among raft nodes before linearized reading' (duration: 174.920909ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:17:20.356347Z","caller":"traceutil/trace.go:171","msg":"trace[2028616530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2726; }","duration":"791.627567ms","start":"2026-04-02T02:17:19.564698Z","end":"2026-04-02T02:17:20.356326Z","steps":["trace[2028616530] 'agreement among raft nodes before linearized reading' (duration: 791.27329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:20.356592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:19.564678Z","time spent":"791.905292ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T02:17:20.356289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:19.705726Z","time spent":"650.554767ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a2689b61955caa\" "} {"level":"warn","ts":"2026-04-02T02:17:20.356824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.854783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T02:17:20.356901Z","caller":"traceutil/trace.go:171","msg":"trace[621540414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2726; }","duration":"203.982336ms","start":"2026-04-02T02:17:20.152903Z","end":"2026-04-02T02:17:20.356885Z","steps":["trace[621540414] 'agreement among raft nodes before linearized reading' (duration: 203.65326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:20.35709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.30728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T02:17:20.357138Z","caller":"traceutil/trace.go:171","msg":"trace[714504484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2726; }","duration":"183.373512ms","start":"2026-04-02T02:17:20.173755Z","end":"2026-04-02T02:17:20.357128Z","steps":["trace[714504484] 'agreement among raft nodes before linearized reading' (duration: 183.247399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:20.357109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.327583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:17:20.357227Z","caller":"traceutil/trace.go:171","msg":"trace[1360945530] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2726; }","duration":"202.450175ms","start":"2026-04-02T02:17:20.154768Z","end":"2026-04-02T02:17:20.357219Z","steps":["trace[1360945530] 'agreement among raft nodes before linearized reading' (duration: 202.314133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:20.357373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:19.54401Z","time spent":"812.157048ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:17:20.356672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.267688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T02:17:20.361714Z","caller":"traceutil/trace.go:171","msg":"trace[1263572291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2726; }","duration":"189.347625ms","start":"2026-04-02T02:17:20.172324Z","end":"2026-04-02T02:17:20.361671Z","steps":["trace[1263572291] 'agreement among raft nodes before linearized reading' (duration: 184.093535ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:17:25.685833Z","caller":"traceutil/trace.go:171","msg":"trace[1991988460] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2860; }","duration":"368.828846ms","start":"2026-04-02T02:17:25.316977Z","end":"2026-04-02T02:17:25.685806Z","steps":["trace[1991988460] 'read index received' (duration: 368.706073ms)","trace[1991988460] 'applied index is now lower than readState.Index' (duration: 121.272µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:17:25.685891Z","caller":"traceutil/trace.go:171","msg":"trace[22455302] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"450.823384ms","start":"2026-04-02T02:17:25.235036Z","end":"2026-04-02T02:17:25.68586Z","steps":["trace[22455302] 'process raft request' (duration: 450.555028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:25.686011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.013499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:17:25.686113Z","caller":"traceutil/trace.go:171","msg":"trace[1689243179] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2756; }","duration":"369.177732ms","start":"2026-04-02T02:17:25.316918Z","end":"2026-04-02T02:17:25.686096Z","steps":["trace[1689243179] 'agreement among raft nodes before linearized reading' (duration: 368.989948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:25.686195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:25.316893Z","time spent":"369.287114ms","remote":"127.0.0.1:40204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:17:25.686439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:25.235019Z","time spent":"450.965856ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:17:25.69829Z","caller":"traceutil/trace.go:171","msg":"trace[1810437801] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"152.534867ms","start":"2026-04-02T02:17:25.545726Z","end":"2026-04-02T02:17:25.69826Z","steps":["trace[1810437801] 'process raft request' (duration: 152.444796ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:17:25.698383Z","caller":"traceutil/trace.go:171","msg":"trace[61372352] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"333.54765ms","start":"2026-04-02T02:17:25.3648Z","end":"2026-04-02T02:17:25.698347Z","steps":["trace[61372352] 'process raft request' (duration: 333.258084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:17:25.698562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:17:25.364771Z","time spent":"333.685964ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:19:50.549812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.946295ms","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-04-02T02:19:50.549926Z","caller":"traceutil/trace.go:171","msg":"trace[1581288207] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3606; }","duration":"101.131279ms","start":"2026-04-02T02:19:50.448774Z","end":"2026-04-02T02:19:50.549905Z","steps":["trace[1581288207] 'range keys from in-memory index tree' (duration: 100.637588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:19:50.550382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.986009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:19:50.550471Z","caller":"traceutil/trace.go:171","msg":"trace[455201123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3606; }","duration":"159.120132ms","start":"2026-04-02T02:19:50.391331Z","end":"2026-04-02T02:19:50.550451Z","steps":["trace[455201123] 'range keys from in-memory index tree' (duration: 158.915807ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:08.580349Z","caller":"traceutil/trace.go:171","msg":"trace[1319173989] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"157.543193ms","start":"2026-04-02T02:21:08.422773Z","end":"2026-04-02T02:21:08.580316Z","steps":["trace[1319173989] 'process raft request' (duration: 157.332689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:13.074525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.373448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T02:21:13.075041Z","caller":"traceutil/trace.go:171","msg":"trace[619923831] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4584; }","duration":"106.572324ms","start":"2026-04-02T02:21:12.968051Z","end":"2026-04-02T02:21:13.074623Z","steps":["trace[619923831] 'count revisions from in-memory index tree' (duration: 106.253505ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:13.27792Z","caller":"traceutil/trace.go:171","msg":"trace[914226928] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"156.371331ms","start":"2026-04-02T02:21:13.121521Z","end":"2026-04-02T02:21:13.277893Z","steps":["trace[914226928] 'process raft request' (duration: 156.175627ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:13.280576Z","caller":"traceutil/trace.go:171","msg":"trace[421981896] transaction","detail":"{read_only:false; response_revision:4586; number_of_response:1; }","duration":"120.282318ms","start":"2026-04-02T02:21:13.160262Z","end":"2026-04-02T02:21:13.280544Z","steps":["trace[421981896] 'process raft request' (duration: 119.948131ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:16.208474Z","caller":"traceutil/trace.go:171","msg":"trace[160074426] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"163.858082ms","start":"2026-04-02T02:21:16.044583Z","end":"2026-04-02T02:21:16.208442Z","steps":["trace[160074426] 'process raft request' (duration: 163.696819ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:17.572234Z","caller":"traceutil/trace.go:171","msg":"trace[31977906] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"282.365379ms","start":"2026-04-02T02:21:17.289835Z","end":"2026-04-02T02:21:17.5722Z","steps":["trace[31977906] 'process raft request' (duration: 282.100524ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:17.576348Z","caller":"traceutil/trace.go:171","msg":"trace[2110531839] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4847; }","duration":"223.049763ms","start":"2026-04-02T02:21:17.353268Z","end":"2026-04-02T02:21:17.576318Z","steps":["trace[2110531839] 'read index received' (duration: 218.758384ms)","trace[2110531839] 'applied index is now lower than readState.Index' (duration: 4.289999ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:21:17.576627Z","caller":"traceutil/trace.go:171","msg":"trace[1018345643] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"262.531766ms","start":"2026-04-02T02:21:17.314083Z","end":"2026-04-02T02:21:17.576614Z","steps":["trace[1018345643] 'process raft request' (duration: 262.091576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:17.576968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.674147ms","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-04-02T02:21:17.577013Z","caller":"traceutil/trace.go:171","msg":"trace[793633601] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4669; }","duration":"223.786209ms","start":"2026-04-02T02:21:17.353216Z","end":"2026-04-02T02:21:17.577002Z","steps":["trace[793633601] 'agreement among raft nodes before linearized reading' (duration: 223.602215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:17.577231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.440711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:21:17.577268Z","caller":"traceutil/trace.go:171","msg":"trace[64218113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4669; }","duration":"185.500641ms","start":"2026-04-02T02:21:17.391758Z","end":"2026-04-02T02:21:17.577259Z","steps":["trace[64218113] 'agreement among raft nodes before linearized reading' (duration: 185.44598ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:22.965378Z","caller":"traceutil/trace.go:171","msg":"trace[1762363031] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"366.970294ms","start":"2026-04-02T02:21:22.598386Z","end":"2026-04-02T02:21:22.965356Z","steps":["trace[1762363031] 'process raft request' (duration: 366.836462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:22.96554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.598368Z","time spent":"367.089616ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:21:22.965885Z","caller":"traceutil/trace.go:171","msg":"trace[2013192369] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4945; }","duration":"347.827326ms","start":"2026-04-02T02:21:22.618027Z","end":"2026-04-02T02:21:22.965854Z","steps":["trace[2013192369] 'read index received' (duration: 347.815136ms)","trace[2013192369] 'applied index is now lower than readState.Index' (duration: 9.68µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:21:22.966419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.45659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-02T02:21:22.966505Z","caller":"traceutil/trace.go:171","msg":"trace[1489867488] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7; range_end:; response_count:1; response_revision:4763; }","duration":"348.603213ms","start":"2026-04-02T02:21:22.617883Z","end":"2026-04-02T02:21:22.966486Z","steps":["trace[1489867488] 'agreement among raft nodes before linearized reading' (duration: 348.214504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:22.966617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.617863Z","time spent":"348.739956ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7\" "} {"level":"info","ts":"2026-04-02T02:21:23.460469Z","caller":"traceutil/trace.go:171","msg":"trace[303978125] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"711.546738ms","start":"2026-04-02T02:21:22.74889Z","end":"2026-04-02T02:21:23.460436Z","steps":["trace[303978125] 'process raft request' (duration: 709.22979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.460656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.748871Z","time spent":"711.697392ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:21:23.460687Z","caller":"traceutil/trace.go:171","msg":"trace[1624543379] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4945; }","duration":"494.617883ms","start":"2026-04-02T02:21:22.966043Z","end":"2026-04-02T02:21:23.46066Z","steps":["trace[1624543379] 'read index received' (duration: 492.020508ms)","trace[1624543379] 'applied index is now lower than readState.Index' (duration: 2.595595ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:21:23.461022Z","caller":"traceutil/trace.go:171","msg":"trace[626278457] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"707.829852ms","start":"2026-04-02T02:21:22.75318Z","end":"2026-04-02T02:21:23.46101Z","steps":["trace[626278457] 'process raft request' (duration: 707.001805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.461036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.920964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-02T02:21:23.461077Z","caller":"traceutil/trace.go:171","msg":"trace[291382999] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7; range_end:; response_count:1; response_revision:4766; }","duration":"553.997146ms","start":"2026-04-02T02:21:22.907067Z","end":"2026-04-02T02:21:23.461064Z","steps":["trace[291382999] 'agreement among raft nodes before linearized reading' (duration: 553.845043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.46112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.907049Z","time spent":"554.060927ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7\" "} {"level":"warn","ts":"2026-04-02T02:21:23.461139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.753168Z","time spent":"707.892543ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:21:23.461262Z","caller":"traceutil/trace.go:171","msg":"trace[1140680789] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"552.846751ms","start":"2026-04-02T02:21:22.908406Z","end":"2026-04-02T02:21:23.461253Z","steps":["trace[1140680789] 'process raft request' (duration: 552.198897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.461324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.908391Z","time spent":"552.903223ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T02:21:23.46136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.404407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:21:23.461468Z","caller":"traceutil/trace.go:171","msg":"trace[249379648] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4766; }","duration":"790.574941ms","start":"2026-04-02T02:21:22.670873Z","end":"2026-04-02T02:21:23.461448Z","steps":["trace[249379648] 'agreement among raft nodes before linearized reading' (duration: 790.364016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.461521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.670853Z","time spent":"790.656962ms","remote":"127.0.0.1:43904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:21:23.461661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.016843ms","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-04-02T02:21:23.462039Z","caller":"traceutil/trace.go:171","msg":"trace[347715449] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4766; }","duration":"795.672357ms","start":"2026-04-02T02:21:22.666352Z","end":"2026-04-02T02:21:23.462024Z","steps":["trace[347715449] 'agreement among raft nodes before linearized reading' (duration: 794.580174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.462073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:22.666339Z","time spent":"795.723798ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-02T02:21:23.511975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.947663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:21:23.512055Z","caller":"traceutil/trace.go:171","msg":"trace[229904808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4767; }","duration":"111.099466ms","start":"2026-04-02T02:21:23.400941Z","end":"2026-04-02T02:21:23.512041Z","steps":["trace[229904808] 'agreement among raft nodes before linearized reading' (duration: 110.976704ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:23.58146Z","caller":"traceutil/trace.go:171","msg":"trace[214420567] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"110.594046ms","start":"2026-04-02T02:21:23.470824Z","end":"2026-04-02T02:21:23.581418Z","steps":["trace[214420567] 'process raft request' (duration: 110.27645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.581859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.317165ms","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-04-02T02:21:23.582031Z","caller":"traceutil/trace.go:171","msg":"trace[761470447] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4768; }","duration":"115.547129ms","start":"2026-04-02T02:21:23.466458Z","end":"2026-04-02T02:21:23.582005Z","steps":["trace[761470447] 'agreement among raft nodes before linearized reading' (duration: 114.924606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:23.754417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.934156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-04-02T02:21:23.754482Z","caller":"traceutil/trace.go:171","msg":"trace[772487773] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4772; }","duration":"114.031818ms","start":"2026-04-02T02:21:23.640435Z","end":"2026-04-02T02:21:23.754467Z","steps":["trace[772487773] 'agreement among raft nodes before linearized reading' (duration: 113.843504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.328392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113402263968283,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T02:21:24.45927Z","caller":"traceutil/trace.go:171","msg":"trace[1694445077] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"639.844683ms","start":"2026-04-02T02:21:23.819402Z","end":"2026-04-02T02:21:24.459246Z","steps":["trace[1694445077] 'process raft request' (duration: 639.011176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.459436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.819377Z","time spent":"639.970866ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:21:24.516375Z","caller":"traceutil/trace.go:171","msg":"trace[1554599753] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4957; }","duration":"688.858211ms","start":"2026-04-02T02:21:23.827489Z","end":"2026-04-02T02:21:24.516347Z","steps":["trace[1554599753] 'read index received' (duration: 631.00181ms)","trace[1554599753] 'applied index is now lower than readState.Index' (duration: 57.854611ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:21:24.516436Z","caller":"traceutil/trace.go:171","msg":"trace[1101823921] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"534.041596ms","start":"2026-04-02T02:21:23.982345Z","end":"2026-04-02T02:21:24.516387Z","steps":["trace[1101823921] 'process raft request' (duration: 533.813941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.516613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.099496ms","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-04-02T02:21:24.516693Z","caller":"traceutil/trace.go:171","msg":"trace[108767616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4777; }","duration":"689.197438ms","start":"2026-04-02T02:21:23.82748Z","end":"2026-04-02T02:21:24.516677Z","steps":["trace[108767616] 'agreement among raft nodes before linearized reading' (duration: 688.965163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.516675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.982323Z","time spent":"534.22087ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T02:21:24.516728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.909497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T02:21:24.51677Z","caller":"traceutil/trace.go:171","msg":"trace[967127167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4777; }","duration":"655.967178ms","start":"2026-04-02T02:21:23.860792Z","end":"2026-04-02T02:21:24.516759Z","steps":["trace[967127167] 'agreement among raft nodes before linearized reading' (duration: 655.882006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.51674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.827428Z","time spent":"689.30612ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T02:21:24.516805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.860783Z","time spent":"656.014378ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T02:21:24.516645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.307791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T02:21:24.516882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.264975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-02T02:21:24.516926Z","caller":"traceutil/trace.go:171","msg":"trace[833923380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4777; }","duration":"663.619777ms","start":"2026-04-02T02:21:23.853286Z","end":"2026-04-02T02:21:24.516905Z","steps":["trace[833923380] 'agreement among raft nodes before linearized reading' (duration: 663.224218ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:24.516986Z","caller":"traceutil/trace.go:171","msg":"trace[1397600167] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7; range_end:; response_count:1; response_revision:4777; }","duration":"540.400868ms","start":"2026-04-02T02:21:23.976558Z","end":"2026-04-02T02:21:24.516959Z","steps":["trace[1397600167] 'agreement among raft nodes before linearized reading' (duration: 540.02671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.51701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.853274Z","time spent":"663.697078ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T02:21:24.516905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.640861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T02:21:24.517035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.976479Z","time spent":"540.548191ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05667542-4ab5-4e96-a6b4-3909fba132a7\" "} {"level":"info","ts":"2026-04-02T02:21:24.517065Z","caller":"traceutil/trace.go:171","msg":"trace[2105083542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4777; }","duration":"656.852365ms","start":"2026-04-02T02:21:23.860197Z","end":"2026-04-02T02:21:24.517049Z","steps":["trace[2105083542] 'agreement among raft nodes before linearized reading' (duration: 656.516149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:24.517107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:23.860177Z","time spent":"656.917697ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T02:21:26.332514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.904357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-02T02:21:26.332575Z","caller":"traceutil/trace.go:171","msg":"trace[89695602] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4805; }","duration":"174.02395ms","start":"2026-04-02T02:21:26.158536Z","end":"2026-04-02T02:21:26.33256Z","steps":["trace[89695602] 'range keys from in-memory index tree' (duration: 173.745193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:26.332949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.291667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a268d85bce2d30\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-04-02T02:21:26.333016Z","caller":"traceutil/trace.go:171","msg":"trace[567417881] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a268d85bce2d30; range_end:; response_count:1; response_revision:4805; }","duration":"164.389889ms","start":"2026-04-02T02:21:26.168613Z","end":"2026-04-02T02:21:26.333003Z","steps":["trace[567417881] 'range keys from in-memory index tree' (duration: 164.170724ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:27.731833Z","caller":"traceutil/trace.go:171","msg":"trace[536086692] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"191.151904ms","start":"2026-04-02T02:21:27.540647Z","end":"2026-04-02T02:21:27.731799Z","steps":["trace[536086692] 'process raft request' (duration: 190.934559ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:27.732381Z","caller":"traceutil/trace.go:171","msg":"trace[1139720270] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"113.31946ms","start":"2026-04-02T02:21:27.619039Z","end":"2026-04-02T02:21:27.732359Z","steps":["trace[1139720270] 'process raft request' (duration: 113.195858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:37.778463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.411719ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T02:21:37.778724Z","caller":"traceutil/trace.go:171","msg":"trace[1703653855] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5141; }","duration":"603.949502ms","start":"2026-04-02T02:21:37.174745Z","end":"2026-04-02T02:21:37.778695Z","steps":["trace[1703653855] 'read index received' (duration: 158.264718ms)","trace[1703653855] 'applied index is now lower than readState.Index' (duration: 445.682174ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:21:37.778772Z","caller":"traceutil/trace.go:171","msg":"trace[1844136844] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"256.496947ms","start":"2026-04-02T02:21:37.52225Z","end":"2026-04-02T02:21:37.778747Z","steps":["trace[1844136844] 'process raft request' (duration: 256.334244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:37.77931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.553145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16402"} {"level":"info","ts":"2026-04-02T02:21:37.779361Z","caller":"traceutil/trace.go:171","msg":"trace[1510327776] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4956; }","duration":"604.615136ms","start":"2026-04-02T02:21:37.174731Z","end":"2026-04-02T02:21:37.779346Z","steps":["trace[1510327776] 'agreement among raft nodes before linearized reading' (duration: 604.420142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:37.779396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:37.174713Z","time spent":"604.675378ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16425,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-02T02:21:37.987349Z","caller":"traceutil/trace.go:171","msg":"trace[973632843] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"464.696927ms","start":"2026-04-02T02:21:37.522621Z","end":"2026-04-02T02:21:37.987318Z","steps":["trace[973632843] 'process raft request' (duration: 425.951057ms)","trace[973632843] 'compare' (duration: 38.612038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:21:37.987507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:37.522588Z","time spent":"464.841231ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:21:37.997474Z","caller":"traceutil/trace.go:171","msg":"trace[1599437395] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"212.325546ms","start":"2026-04-02T02:21:37.78512Z","end":"2026-04-02T02:21:37.997446Z","steps":["trace[1599437395] 'process raft request' (duration: 212.194833ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:37.997574Z","caller":"traceutil/trace.go:171","msg":"trace[668605188] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"220.051064ms","start":"2026-04-02T02:21:37.777389Z","end":"2026-04-02T02:21:37.99744Z","steps":["trace[668605188] 'process raft request' (duration: 219.744698ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:37.99773Z","caller":"traceutil/trace.go:171","msg":"trace[1504600331] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"201.519492ms","start":"2026-04-02T02:21:37.796199Z","end":"2026-04-02T02:21:37.997719Z","steps":["trace[1504600331] 'process raft request' (duration: 201.175845ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:37.997897Z","caller":"traceutil/trace.go:171","msg":"trace[435758013] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5143; }","duration":"218.991193ms","start":"2026-04-02T02:21:37.778806Z","end":"2026-04-02T02:21:37.997797Z","steps":["trace[435758013] 'read index received' (duration: 170.103143ms)","trace[435758013] 'applied index is now lower than readState.Index' (duration: 48.88652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:21:37.997975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.659331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:21:37.998181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.181478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:21:37.99823Z","caller":"traceutil/trace.go:171","msg":"trace[2075346269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4960; }","duration":"605.29208ms","start":"2026-04-02T02:21:37.392924Z","end":"2026-04-02T02:21:37.998216Z","steps":["trace[2075346269] 'agreement among raft nodes before linearized reading' (duration: 605.168778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:37.998256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:37.392894Z","time spent":"605.354462ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T02:21:37.998409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.202506ms","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-04-02T02:21:37.998471Z","caller":"traceutil/trace.go:171","msg":"trace[1535940736] 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:4960; }","duration":"783.360489ms","start":"2026-04-02T02:21:37.215089Z","end":"2026-04-02T02:21:37.998449Z","steps":["trace[1535940736] 'agreement among raft nodes before linearized reading' (duration: 783.089062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:37.998532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:37.215063Z","time spent":"783.43443ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-02T02:21:37.999177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.810348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T02:21:37.999228Z","caller":"traceutil/trace.go:171","msg":"trace[1548502156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4960; }","duration":"124.922481ms","start":"2026-04-02T02:21:37.874292Z","end":"2026-04-02T02:21:37.999215Z","steps":["trace[1548502156] 'agreement among raft nodes before linearized reading' (duration: 124.720146ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:38.000015Z","caller":"traceutil/trace.go:171","msg":"trace[2099520411] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:4960; }","duration":"321.875966ms","start":"2026-04-02T02:21:37.676188Z","end":"2026-04-02T02:21:37.998064Z","steps":["trace[2099520411] 'agreement among raft nodes before linearized reading' (duration: 321.646661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:21:38.000701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:21:37.676169Z","time spent":"323.902ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:21:38.226718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.698794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17151"} {"level":"info","ts":"2026-04-02T02:21:38.226896Z","caller":"traceutil/trace.go:171","msg":"trace[234180451] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4963; }","duration":"139.93758ms","start":"2026-04-02T02:21:38.086936Z","end":"2026-04-02T02:21:38.226873Z","steps":["trace[234180451] 'range keys from in-memory index tree' (duration: 138.243224ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:38.22673Z","caller":"traceutil/trace.go:171","msg":"trace[1845065573] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"139.693554ms","start":"2026-04-02T02:21:38.08701Z","end":"2026-04-02T02:21:38.226704Z","steps":["trace[1845065573] 'process raft request' (duration: 139.577082ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:38.22731Z","caller":"traceutil/trace.go:171","msg":"trace[247363118] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"138.705523ms","start":"2026-04-02T02:21:38.088539Z","end":"2026-04-02T02:21:38.227245Z","steps":["trace[247363118] 'process raft request' (duration: 138.381227ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:21:38.226729Z","caller":"traceutil/trace.go:171","msg":"trace[1729143064] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"139.744715ms","start":"2026-04-02T02:21:38.086949Z","end":"2026-04-02T02:21:38.226694Z","steps":["trace[1729143064] 'process raft request' (duration: 40.470096ms)","trace[1729143064] 'compare' (duration: 97.992483ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:21:38.227316Z","caller":"traceutil/trace.go:171","msg":"trace[42544004] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"139.509761ms","start":"2026-04-02T02:21:38.087756Z","end":"2026-04-02T02:21:38.227265Z","steps":["trace[42544004] 'process raft request' (duration: 138.905858ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:22:14.100479Z","caller":"traceutil/trace.go:171","msg":"trace[1517648318] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"137.289961ms","start":"2026-04-02T02:22:13.963154Z","end":"2026-04-02T02:22:14.100444Z","steps":["trace[1517648318] 'process raft request' (duration: 137.109868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:22:16.5844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.867197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:22:16.584493Z","caller":"traceutil/trace.go:171","msg":"trace[1468081344] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5292; }","duration":"149.98351ms","start":"2026-04-02T02:22:16.434486Z","end":"2026-04-02T02:22:16.58447Z","steps":["trace[1468081344] 'count revisions from in-memory index tree' (duration: 149.679802ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:22:46.944954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2870} {"level":"info","ts":"2026-04-02T02:22:47.049553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2870,"took":"102.155898ms","hash":2698927804,"current-db-size-bytes":53497856,"current-db-size":"54 MB","current-db-size-in-use-bytes":28672000,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-02T02:22:47.049632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2698927804,"revision":2870,"compact-revision":-1} {"level":"info","ts":"2026-04-02T02:24:38.527143Z","caller":"traceutil/trace.go:171","msg":"trace[1449305945] transaction","detail":"{read_only:false; response_revision:6169; number_of_response:1; }","duration":"108.557562ms","start":"2026-04-02T02:24:38.418256Z","end":"2026-04-02T02:24:38.526814Z","steps":["trace[1449305945] 'process raft request' (duration: 108.388617ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:17.01748Z","caller":"traceutil/trace.go:171","msg":"trace[753439617] transaction","detail":"{read_only:false; response_revision:6543; number_of_response:1; }","duration":"124.031801ms","start":"2026-04-02T02:25:16.893424Z","end":"2026-04-02T02:25:17.017456Z","steps":["trace[753439617] 'process raft request' (duration: 123.887128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:17.25282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.090962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T02:25:17.253581Z","caller":"traceutil/trace.go:171","msg":"trace[556923671] transaction","detail":"{read_only:false; response_revision:6544; number_of_response:1; }","duration":"339.637401ms","start":"2026-04-02T02:25:16.913904Z","end":"2026-04-02T02:25:17.253542Z","steps":["trace[556923671] 'process raft request' (duration: 142.736732ms)","trace[556923671] 'compare' (duration: 195.771506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:25:17.253754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:25:16.913885Z","time spent":"339.782413ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:25:17.253835Z","caller":"traceutil/trace.go:171","msg":"trace[1974373291] transaction","detail":"{read_only:false; response_revision:6546; number_of_response:1; }","duration":"333.906677ms","start":"2026-04-02T02:25:16.9199Z","end":"2026-04-02T02:25:17.253807Z","steps":["trace[1974373291] 'process raft request' (duration: 333.54814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:17.254019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:25:16.919893Z","time spent":"334.013799ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:25:17.253785Z","caller":"traceutil/trace.go:171","msg":"trace[366359551] transaction","detail":"{read_only:false; response_revision:6545; number_of_response:1; }","duration":"335.931357ms","start":"2026-04-02T02:25:16.917831Z","end":"2026-04-02T02:25:17.253763Z","steps":["trace[366359551] 'process raft request' (duration: 335.55065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:17.255009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:25:16.917823Z","time spent":"336.505918ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:25:17.272592Z","caller":"traceutil/trace.go:171","msg":"trace[704055250] linearizableReadLoop","detail":"{readStateIndex:6785; appliedIndex:6781; }","duration":"141.612621ms","start":"2026-04-02T02:25:17.130961Z","end":"2026-04-02T02:25:17.272573Z","steps":["trace[704055250] 'read index received' (duration: 101.428942ms)","trace[704055250] 'applied index is now lower than readState.Index' (duration: 40.182439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:25:17.272912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.913267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T02:25:17.273016Z","caller":"traceutil/trace.go:171","msg":"trace[1305272630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6546; }","duration":"142.06806ms","start":"2026-04-02T02:25:17.130925Z","end":"2026-04-02T02:25:17.272993Z","steps":["trace[1305272630] 'agreement among raft nodes before linearized reading' (duration: 141.750794ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:22.090305Z","caller":"traceutil/trace.go:171","msg":"trace[501397865] transaction","detail":"{read_only:false; response_revision:6571; number_of_response:1; }","duration":"102.940362ms","start":"2026-04-02T02:25:21.987325Z","end":"2026-04-02T02:25:22.090265Z","steps":["trace[501397865] 'process raft request' (duration: 102.82593ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:22.229606Z","caller":"traceutil/trace.go:171","msg":"trace[1917059840] transaction","detail":"{read_only:false; response_revision:6572; number_of_response:1; }","duration":"241.981661ms","start":"2026-04-02T02:25:21.987598Z","end":"2026-04-02T02:25:22.22958Z","steps":["trace[1917059840] 'process raft request' (duration: 203.530318ms)","trace[1917059840] 'compare' (duration: 38.28276ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:25:22.229725Z","caller":"traceutil/trace.go:171","msg":"trace[1458739376] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"242.071342ms","start":"2026-04-02T02:25:21.98762Z","end":"2026-04-02T02:25:22.229692Z","steps":["trace[1458739376] 'process raft request' (duration: 241.920829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:22.229903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.803489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-04-02T02:25:22.229702Z","caller":"traceutil/trace.go:171","msg":"trace[677415436] linearizableReadLoop","detail":"{readStateIndex:6813; appliedIndex:6811; }","duration":"105.580005ms","start":"2026-04-02T02:25:22.124088Z","end":"2026-04-02T02:25:22.229668Z","steps":["trace[677415436] 'read index received' (duration: 66.990309ms)","trace[677415436] 'applied index is now lower than readState.Index' (duration: 38.587786ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:25:22.229967Z","caller":"traceutil/trace.go:171","msg":"trace[1600047028] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6573; }","duration":"105.887491ms","start":"2026-04-02T02:25:22.124047Z","end":"2026-04-02T02:25:22.229934Z","steps":["trace[1600047028] 'agreement among raft nodes before linearized reading' (duration: 105.729628ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:23.386805Z","caller":"traceutil/trace.go:171","msg":"trace[1385458233] transaction","detail":"{read_only:false; response_revision:6575; number_of_response:1; }","duration":"111.89968ms","start":"2026-04-02T02:25:23.274877Z","end":"2026-04-02T02:25:23.386776Z","steps":["trace[1385458233] 'process raft request' (duration: 111.687696ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:23.463907Z","caller":"traceutil/trace.go:171","msg":"trace[1826191726] transaction","detail":"{read_only:false; response_revision:6577; number_of_response:1; }","duration":"184.96179ms","start":"2026-04-02T02:25:23.278922Z","end":"2026-04-02T02:25:23.463884Z","steps":["trace[1826191726] 'process raft request' (duration: 184.904939ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:23.463943Z","caller":"traceutil/trace.go:171","msg":"trace[1926510111] transaction","detail":"{read_only:false; response_revision:6576; number_of_response:1; }","duration":"185.653275ms","start":"2026-04-02T02:25:23.27826Z","end":"2026-04-02T02:25:23.463914Z","steps":["trace[1926510111] 'process raft request' (duration: 185.44511ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:23.464082Z","caller":"traceutil/trace.go:171","msg":"trace[336900777] linearizableReadLoop","detail":"{readStateIndex:6818; appliedIndex:6814; }","duration":"157.540366ms","start":"2026-04-02T02:25:23.306514Z","end":"2026-04-02T02:25:23.464055Z","steps":["trace[336900777] 'read index received' (duration: 80.050939ms)","trace[336900777] 'applied index is now lower than readState.Index' (duration: 77.487727ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:25:23.464121Z","caller":"traceutil/trace.go:171","msg":"trace[109346426] transaction","detail":"{read_only:false; response_revision:6578; number_of_response:1; }","duration":"181.820147ms","start":"2026-04-02T02:25:23.282289Z","end":"2026-04-02T02:25:23.464109Z","steps":["trace[109346426] 'process raft request' (duration: 181.569652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:23.464222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.691089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T02:25:23.464271Z","caller":"traceutil/trace.go:171","msg":"trace[125776537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6578; }","duration":"157.775051ms","start":"2026-04-02T02:25:23.306484Z","end":"2026-04-02T02:25:23.464259Z","steps":["trace[125776537] 'agreement among raft nodes before linearized reading' (duration: 157.651609ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:26.503343Z","caller":"traceutil/trace.go:171","msg":"trace[207525088] linearizableReadLoop","detail":"{readStateIndex:6839; appliedIndex:6838; }","duration":"190.450128ms","start":"2026-04-02T02:25:26.312851Z","end":"2026-04-02T02:25:26.503301Z","steps":["trace[207525088] 'read index received' (duration: 165.690287ms)","trace[207525088] 'applied index is now lower than readState.Index' (duration: 24.757911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:25:26.503696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.031673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-04-02T02:25:26.503742Z","caller":"traceutil/trace.go:171","msg":"trace[2078019801] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6598; }","duration":"108.139245ms","start":"2026-04-02T02:25:26.395588Z","end":"2026-04-02T02:25:26.503727Z","steps":["trace[2078019801] 'agreement among raft nodes before linearized reading' (duration: 107.911111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:26.503741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.848395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-04-02T02:25:26.503806Z","caller":"traceutil/trace.go:171","msg":"trace[906426537] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6598; }","duration":"191.002798ms","start":"2026-04-02T02:25:26.312789Z","end":"2026-04-02T02:25:26.503791Z","steps":["trace[906426537] 'agreement among raft nodes before linearized reading' (duration: 190.615761ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:26.503378Z","caller":"traceutil/trace.go:171","msg":"trace[1879394811] transaction","detail":"{read_only:false; response_revision:6598; number_of_response:1; }","duration":"197.967337ms","start":"2026-04-02T02:25:26.305374Z","end":"2026-04-02T02:25:26.503342Z","steps":["trace[1879394811] 'process raft request' (duration: 173.095073ms)","trace[1879394811] 'compare' (duration: 24.470176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:25:41.635454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.514081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:25:41.635536Z","caller":"traceutil/trace.go:171","msg":"trace[2044765321] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:6728; }","duration":"326.641152ms","start":"2026-04-02T02:25:41.308883Z","end":"2026-04-02T02:25:41.635525Z","steps":["trace[2044765321] 'agreement among raft nodes before linearized reading' (duration: 326.5202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:41.635567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:25:41.308868Z","time spent":"326.691394ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-04-02T02:25:41.635757Z","caller":"traceutil/trace.go:171","msg":"trace[1153609863] transaction","detail":"{read_only:false; response_revision:6728; number_of_response:1; }","duration":"473.924442ms","start":"2026-04-02T02:25:41.161376Z","end":"2026-04-02T02:25:41.6353Z","steps":["trace[1153609863] 'process raft request' (duration: 464.619288ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:41.635374Z","caller":"traceutil/trace.go:171","msg":"trace[1368644179] linearizableReadLoop","detail":"{readStateIndex:6974; appliedIndex:6973; }","duration":"326.143954ms","start":"2026-04-02T02:25:41.30892Z","end":"2026-04-02T02:25:41.635064Z","steps":["trace[1368644179] 'read index received' (duration: 317.016483ms)","trace[1368644179] 'applied index is now lower than readState.Index' (duration: 9.12601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:25:41.636098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:25:41.16136Z","time spent":"474.477213ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T02:25:41.675533Z","caller":"traceutil/trace.go:171","msg":"trace[220560079] transaction","detail":"{read_only:false; response_revision:6729; number_of_response:1; }","duration":"159.793036ms","start":"2026-04-02T02:25:41.515719Z","end":"2026-04-02T02:25:41.675512Z","steps":["trace[220560079] 'process raft request' (duration: 159.656834ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:41.675844Z","caller":"traceutil/trace.go:171","msg":"trace[311082704] transaction","detail":"{read_only:false; response_revision:6730; number_of_response:1; }","duration":"141.465094ms","start":"2026-04-02T02:25:41.534371Z","end":"2026-04-02T02:25:41.675836Z","steps":["trace[311082704] 'process raft request' (duration: 141.107607ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:41.675978Z","caller":"traceutil/trace.go:171","msg":"trace[1611111542] transaction","detail":"{read_only:false; response_revision:6731; number_of_response:1; }","duration":"140.503294ms","start":"2026-04-02T02:25:41.535385Z","end":"2026-04-02T02:25:41.675888Z","steps":["trace[1611111542] 'process raft request' (duration: 140.347051ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:25:41.698817Z","caller":"traceutil/trace.go:171","msg":"trace[1445137112] transaction","detail":"{read_only:false; response_revision:6732; number_of_response:1; }","duration":"150.371391ms","start":"2026-04-02T02:25:41.548416Z","end":"2026-04-02T02:25:41.698787Z","steps":["trace[1445137112] 'process raft request' (duration: 127.422276ms)","trace[1445137112] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:538; } (duration: 22.602898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:25:41.698888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.469073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:25:41.699052Z","caller":"traceutil/trace.go:171","msg":"trace[429589698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6732; }","duration":"306.578386ms","start":"2026-04-02T02:25:41.392364Z","end":"2026-04-02T02:25:41.698943Z","steps":["trace[429589698] 'agreement among raft nodes before linearized reading' (duration: 306.423503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:25:41.699142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:25:41.392352Z","time spent":"306.775069ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T02:25:54.081049Z","caller":"traceutil/trace.go:171","msg":"trace[253500013] transaction","detail":"{read_only:false; response_revision:6795; number_of_response:1; }","duration":"124.979235ms","start":"2026-04-02T02:25:53.956043Z","end":"2026-04-02T02:25:54.081023Z","steps":["trace[253500013] 'process raft request' (duration: 124.73587ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:08.599482Z","caller":"traceutil/trace.go:171","msg":"trace[1288660158] transaction","detail":"{read_only:false; response_revision:6871; number_of_response:1; }","duration":"172.071226ms","start":"2026-04-02T02:26:08.427388Z","end":"2026-04-02T02:26:08.599459Z","steps":["trace[1288660158] 'process raft request' (duration: 171.047215ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:44.22794Z","caller":"traceutil/trace.go:171","msg":"trace[77244724] transaction","detail":"{read_only:false; response_revision:7071; number_of_response:1; }","duration":"109.104384ms","start":"2026-04-02T02:26:44.118818Z","end":"2026-04-02T02:26:44.227923Z","steps":["trace[77244724] 'process raft request' (duration: 108.93122ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:46.128232Z","caller":"traceutil/trace.go:171","msg":"trace[1774828092] transaction","detail":"{read_only:false; response_revision:7078; number_of_response:1; }","duration":"172.652252ms","start":"2026-04-02T02:26:45.955562Z","end":"2026-04-02T02:26:46.128214Z","steps":["trace[1774828092] 'process raft request' (duration: 172.540969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:26:46.961182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.141177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T02:26:46.961289Z","caller":"traceutil/trace.go:171","msg":"trace[1252700193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7084; }","duration":"106.26958ms","start":"2026-04-02T02:26:46.855Z","end":"2026-04-02T02:26:46.961269Z","steps":["trace[1252700193] 'range keys from in-memory index tree' (duration: 105.988704ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:52.300031Z","caller":"traceutil/trace.go:171","msg":"trace[1032232942] transaction","detail":"{read_only:false; response_revision:7109; number_of_response:1; }","duration":"115.597399ms","start":"2026-04-02T02:26:52.184409Z","end":"2026-04-02T02:26:52.300006Z","steps":["trace[1032232942] 'process raft request' (duration: 115.366064ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:52.444672Z","caller":"traceutil/trace.go:171","msg":"trace[1543555070] linearizableReadLoop","detail":"{readStateIndex:7372; appliedIndex:7371; }","duration":"110.197181ms","start":"2026-04-02T02:26:52.334448Z","end":"2026-04-02T02:26:52.444645Z","steps":["trace[1543555070] 'read index received' (duration: 55.408166ms)","trace[1543555070] 'applied index is now lower than readState.Index' (duration: 54.787584ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:26:52.444672Z","caller":"traceutil/trace.go:171","msg":"trace[804438093] transaction","detail":"{read_only:false; response_revision:7111; number_of_response:1; }","duration":"139.054246ms","start":"2026-04-02T02:26:52.305593Z","end":"2026-04-02T02:26:52.444647Z","steps":["trace[804438093] 'process raft request' (duration: 84.174309ms)","trace[804438093] 'compare' (duration: 54.694753ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:26:52.444838Z","caller":"traceutil/trace.go:171","msg":"trace[948702241] transaction","detail":"{read_only:false; response_revision:7112; number_of_response:1; }","duration":"107.86705ms","start":"2026-04-02T02:26:52.336947Z","end":"2026-04-02T02:26:52.444814Z","steps":["trace[948702241] 'process raft request' (duration: 107.663036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:26:52.445115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.651621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-node-exporter-full\" ","response":"range_response_count:1 size:683762"} {"level":"info","ts":"2026-04-02T02:26:52.445396Z","caller":"traceutil/trace.go:171","msg":"trace[1859323133] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-node-exporter-full; range_end:; response_count:1; response_revision:7112; }","duration":"110.752333ms","start":"2026-04-02T02:26:52.334391Z","end":"2026-04-02T02:26:52.445143Z","steps":["trace[1859323133] 'agreement among raft nodes before linearized reading' (duration: 110.411486ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:52.789303Z","caller":"traceutil/trace.go:171","msg":"trace[599877811] transaction","detail":"{read_only:false; response_revision:7114; number_of_response:1; }","duration":"147.509252ms","start":"2026-04-02T02:26:52.641768Z","end":"2026-04-02T02:26:52.789277Z","steps":["trace[599877811] 'process raft request' (duration: 147.332388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:26:57.31811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.197473ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T02:26:57.31822Z","caller":"traceutil/trace.go:171","msg":"trace[1227927851] linearizableReadLoop","detail":"{readStateIndex:7398; appliedIndex:7397; }","duration":"134.902229ms","start":"2026-04-02T02:26:57.183295Z","end":"2026-04-02T02:26:57.318198Z","steps":["trace[1227927851] 'read index received' (duration: 5.564372ms)","trace[1227927851] 'applied index is now lower than readState.Index' (duration: 129.336217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:26:57.318448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.454675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T02:26:57.318481Z","caller":"traceutil/trace.go:171","msg":"trace[490595742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7136; }","duration":"101.510977ms","start":"2026-04-02T02:26:57.216958Z","end":"2026-04-02T02:26:57.318469Z","steps":["trace[490595742] 'agreement among raft nodes before linearized reading' (duration: 101.360873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:26:57.318631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.298247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T02:26:57.318758Z","caller":"traceutil/trace.go:171","msg":"trace[404671317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7136; }","duration":"135.455141ms","start":"2026-04-02T02:26:57.183249Z","end":"2026-04-02T02:26:57.318704Z","steps":["trace[404671317] 'agreement among raft nodes before linearized reading' (duration: 135.055032ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:26:58.636503Z","caller":"traceutil/trace.go:171","msg":"trace[1452849938] transaction","detail":"{read_only:false; response_revision:7142; number_of_response:1; }","duration":"120.88821ms","start":"2026-04-02T02:26:58.515595Z","end":"2026-04-02T02:26:58.636483Z","steps":["trace[1452849938] 'process raft request' (duration: 68.674156ms)","trace[1452849938] 'compare' (duration: 52.02166ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:27:02.9648Z","caller":"traceutil/trace.go:171","msg":"trace[1984626182] transaction","detail":"{read_only:false; response_revision:7164; number_of_response:1; }","duration":"107.640377ms","start":"2026-04-02T02:27:02.857136Z","end":"2026-04-02T02:27:02.964776Z","steps":["trace[1984626182] 'process raft request' (duration: 107.540484ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:27:06.869742Z","caller":"traceutil/trace.go:171","msg":"trace[1590727623] transaction","detail":"{read_only:false; response_revision:7184; number_of_response:1; }","duration":"147.212698ms","start":"2026-04-02T02:27:06.722205Z","end":"2026-04-02T02:27:06.869418Z","steps":["trace[1590727623] 'process raft request' (duration: 86.238965ms)","trace[1590727623] 'compare' (duration: 60.83915ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:27:10.968753Z","caller":"traceutil/trace.go:171","msg":"trace[1687687979] linearizableReadLoop","detail":"{readStateIndex:7467; appliedIndex:7466; }","duration":"182.441134ms","start":"2026-04-02T02:27:10.786292Z","end":"2026-04-02T02:27:10.968733Z","steps":["trace[1687687979] 'read index received' (duration: 174.542872ms)","trace[1687687979] 'applied index is now lower than readState.Index' (duration: 7.897412ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:27:10.968845Z","caller":"traceutil/trace.go:171","msg":"trace[2040848274] transaction","detail":"{read_only:false; response_revision:7203; number_of_response:1; }","duration":"259.758122ms","start":"2026-04-02T02:27:10.709048Z","end":"2026-04-02T02:27:10.968807Z","steps":["trace[2040848274] 'process raft request' (duration: 251.850159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:27:10.968874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.551377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:27:10.968972Z","caller":"traceutil/trace.go:171","msg":"trace[2064357019] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:7203; }","duration":"182.69091ms","start":"2026-04-02T02:27:10.78626Z","end":"2026-04-02T02:27:10.968951Z","steps":["trace[2064357019] 'agreement among raft nodes before linearized reading' (duration: 182.541917ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:27:46.952808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5511} {"level":"info","ts":"2026-04-02T02:27:47.050045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5511,"took":"95.830099ms","hash":2298397556,"current-db-size-bytes":53497856,"current-db-size":"54 MB","current-db-size-in-use-bytes":18259968,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-02T02:27:47.050102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2298397556,"revision":5511,"compact-revision":2870} {"level":"info","ts":"2026-04-02T02:27:49.535318Z","caller":"traceutil/trace.go:171","msg":"trace[643558391] transaction","detail":"{read_only:false; response_revision:7398; number_of_response:1; }","duration":"102.515282ms","start":"2026-04-02T02:27:49.432762Z","end":"2026-04-02T02:27:49.535277Z","steps":["trace[643558391] 'process raft request' (duration: 101.920438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:28:02.121771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.972711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T02:28:02.121877Z","caller":"traceutil/trace.go:171","msg":"trace[472310683] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7463; }","duration":"109.132615ms","start":"2026-04-02T02:28:02.012704Z","end":"2026-04-02T02:28:02.121837Z","steps":["trace[472310683] 'count revisions from in-memory index tree' (duration: 108.811257ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:28:56.650962Z","caller":"traceutil/trace.go:171","msg":"trace[65276204] transaction","detail":"{read_only:false; response_revision:7736; number_of_response:1; }","duration":"102.861541ms","start":"2026-04-02T02:28:56.54807Z","end":"2026-04-02T02:28:56.650931Z","steps":["trace[65276204] 'process raft request' (duration: 102.666956ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:02.03506Z","caller":"traceutil/trace.go:171","msg":"trace[1351146419] transaction","detail":"{read_only:false; response_revision:7762; number_of_response:1; }","duration":"111.048481ms","start":"2026-04-02T02:29:01.923985Z","end":"2026-04-02T02:29:02.035034Z","steps":["trace[1351146419] 'process raft request' (duration: 110.732044ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:05.886379Z","caller":"traceutil/trace.go:171","msg":"trace[287141133] transaction","detail":"{read_only:false; response_revision:7781; number_of_response:1; }","duration":"130.025461ms","start":"2026-04-02T02:29:05.756326Z","end":"2026-04-02T02:29:05.886351Z","steps":["trace[287141133] 'process raft request' (duration: 129.670374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.982344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.693811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/auth-system/\" range_end:\"/registry/rolebindings/auth-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:10.982394Z","caller":"traceutil/trace.go:171","msg":"trace[2098235174] range","detail":"{range_begin:/registry/rolebindings/auth-system/; range_end:/registry/rolebindings/auth-system0; response_count:0; response_revision:7806; }","duration":"376.753342ms","start":"2026-04-02T02:29:10.60563Z","end":"2026-04-02T02:29:10.982383Z","steps":["trace[2098235174] 'range keys from in-memory index tree' (duration: 376.642329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.982415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:10.605585Z","time spent":"376.825123ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/auth-system/\" range_end:\"/registry/rolebindings/auth-system0\" limit:500 "} {"level":"warn","ts":"2026-04-02T02:29:10.982444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.440059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T02:29:10.98252Z","caller":"traceutil/trace.go:171","msg":"trace[496208484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7806; }","duration":"255.545391ms","start":"2026-04-02T02:29:10.726956Z","end":"2026-04-02T02:29:10.982501Z","steps":["trace[496208484] 'range keys from in-memory index tree' (duration: 255.281805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.982551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.444403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:5014"} {"level":"info","ts":"2026-04-02T02:29:10.982634Z","caller":"traceutil/trace.go:171","msg":"trace[1508492699] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:7806; }","duration":"215.563546ms","start":"2026-04-02T02:29:10.767052Z","end":"2026-04-02T02:29:10.982615Z","steps":["trace[1508492699] 'range keys from in-memory index tree' (duration: 215.27106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.982708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.123022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/cert-manager/\" range_end:\"/registry/events/cert-manager0\" limit:500 ","response":"range_response_count:52 size:42244"} {"level":"info","ts":"2026-04-02T02:29:10.982738Z","caller":"traceutil/trace.go:171","msg":"trace[1737292705] range","detail":"{range_begin:/registry/events/cert-manager/; range_end:/registry/events/cert-manager0; response_count:52; response_revision:7806; }","duration":"379.167073ms","start":"2026-04-02T02:29:10.603565Z","end":"2026-04-02T02:29:10.982733Z","steps":["trace[1737292705] 'range keys from in-memory index tree' (duration: 378.902937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.982717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.561481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/cert-manager/\" range_end:\"/registry/rolebindings/cert-manager0\" limit:500 ","response":"range_response_count:3 size:3495"} {"level":"info","ts":"2026-04-02T02:29:10.982793Z","caller":"traceutil/trace.go:171","msg":"trace[1142813941] range","detail":"{range_begin:/registry/rolebindings/cert-manager/; range_end:/registry/rolebindings/cert-manager0; response_count:3; response_revision:7806; }","duration":"325.652883ms","start":"2026-04-02T02:29:10.657119Z","end":"2026-04-02T02:29:10.982772Z","steps":["trace[1142813941] 'range keys from in-memory index tree' (duration: 325.374277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.98279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.711878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:29:10.982849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:10.657109Z","time spent":"325.728904ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":3,"response size":3518,"request content":"key:\"/registry/rolebindings/cert-manager/\" range_end:\"/registry/rolebindings/cert-manager0\" limit:500 "} {"level":"warn","ts":"2026-04-02T02:29:10.982754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:10.603558Z","time spent":"379.190543ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":52,"response size":42267,"request content":"key:\"/registry/events/cert-manager/\" range_end:\"/registry/events/cert-manager0\" limit:500 "} {"level":"info","ts":"2026-04-02T02:29:10.982886Z","caller":"traceutil/trace.go:171","msg":"trace[1165810994] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:7806; }","duration":"311.844241ms","start":"2026-04-02T02:29:10.671013Z","end":"2026-04-02T02:29:10.982857Z","steps":["trace[1165810994] 'count revisions from in-memory index tree' (duration: 311.639297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:10.982942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:10.671001Z","time spent":"311.928082ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T02:29:11.475598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.31331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-04-02T02:29:11.475676Z","caller":"traceutil/trace.go:171","msg":"trace[87212155] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:7808; }","duration":"154.435422ms","start":"2026-04-02T02:29:11.321224Z","end":"2026-04-02T02:29:11.475659Z","steps":["trace[87212155] 'range keys from in-memory index tree' (duration: 154.172737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:11.475943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.700558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/\" range_end:\"/registry/serviceaccounts/cert-manager0\" limit:500 ","response":"range_response_count:4 size:3085"} {"level":"info","ts":"2026-04-02T02:29:11.475983Z","caller":"traceutil/trace.go:171","msg":"trace[139924451] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/; range_end:/registry/serviceaccounts/cert-manager0; response_count:4; response_revision:7808; }","duration":"189.753399ms","start":"2026-04-02T02:29:11.286219Z","end":"2026-04-02T02:29:11.475972Z","steps":["trace[139924451] 'range keys from in-memory index tree' (duration: 189.624796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:11.47613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.830019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/\" range_end:\"/registry/serviceaccounts/auth-system0\" limit:500 ","response":"range_response_count:2 size:1077"} {"level":"info","ts":"2026-04-02T02:29:11.476244Z","caller":"traceutil/trace.go:171","msg":"trace[449946318] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/; range_end:/registry/serviceaccounts/auth-system0; response_count:2; response_revision:7808; }","duration":"187.960811ms","start":"2026-04-02T02:29:11.288258Z","end":"2026-04-02T02:29:11.476219Z","steps":["trace[449946318] 'range keys from in-memory index tree' (duration: 187.746926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:11.476199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.263454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/auth-system/keycloak\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-04-02T02:29:11.47648Z","caller":"traceutil/trace.go:171","msg":"trace[945887985] range","detail":"{range_begin:/registry/networkpolicies/auth-system/keycloak; range_end:; response_count:1; response_revision:7808; }","duration":"212.609892ms","start":"2026-04-02T02:29:11.26376Z","end":"2026-04-02T02:29:11.47637Z","steps":["trace[945887985] 'range keys from in-memory index tree' (duration: 212.114151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.857191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.820196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/envoy-gateway-system/\" range_end:\"/registry/ingress/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:12.857306Z","caller":"traceutil/trace.go:171","msg":"trace[1063796412] range","detail":"{range_begin:/registry/ingress/envoy-gateway-system/; range_end:/registry/ingress/envoy-gateway-system0; response_count:0; response_revision:7816; }","duration":"604.935009ms","start":"2026-04-02T02:29:12.252324Z","end":"2026-04-02T02:29:12.857259Z","steps":["trace[1063796412] 'range keys from in-memory index tree' (duration: 604.774356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.85735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.252319Z","time spent":"605.020081ms","remote":"127.0.0.1:39492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/envoy-gateway-system/\" range_end:\"/registry/ingress/envoy-gateway-system0\" limit:500 "} {"level":"warn","ts":"2026-04-02T02:29:12.857249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.021537ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:12.85743Z","caller":"traceutil/trace.go:171","msg":"trace[670197133] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7816; }","duration":"451.215361ms","start":"2026-04-02T02:29:12.406196Z","end":"2026-04-02T02:29:12.857411Z","steps":["trace[670197133] 'range keys from in-memory index tree' (duration: 451.004267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.857612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.253709ms","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-04-02T02:29:12.857729Z","caller":"traceutil/trace.go:171","msg":"trace[1403715035] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7816; }","duration":"564.395101ms","start":"2026-04-02T02:29:12.293314Z","end":"2026-04-02T02:29:12.857709Z","steps":["trace[1403715035] 'range keys from in-memory index tree' (duration: 564.185187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.857824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.293304Z","time spent":"564.507924ms","remote":"127.0.0.1:39662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-02T02:29:12.857994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.088031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/cert-manager/cert-manager:leaderelection\" ","response":"range_response_count:1 size:1149"} {"level":"warn","ts":"2026-04-02T02:29:12.858006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.756565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-cainjector:leaderelection\" ","response":"range_response_count:1 size:1201"} {"level":"warn","ts":"2026-04-02T02:29:12.85805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.251317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:12.858068Z","caller":"traceutil/trace.go:171","msg":"trace[1390466973] range","detail":"{range_begin:/registry/jobs/default/; range_end:/registry/jobs/default0; response_count:0; response_revision:7816; }","duration":"527.289408ms","start":"2026-04-02T02:29:12.330773Z","end":"2026-04-02T02:29:12.858063Z","steps":["trace[1390466973] 'range keys from in-memory index tree' (duration: 527.161375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.858082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.33076Z","time spent":"527.317559ms","remote":"127.0.0.1:39416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 "} {"level":"info","ts":"2026-04-02T02:29:12.858151Z","caller":"traceutil/trace.go:171","msg":"trace[756831918] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-cainjector:leaderelection; range_end:; response_count:1; response_revision:7816; }","duration":"604.928738ms","start":"2026-04-02T02:29:12.253204Z","end":"2026-04-02T02:29:12.858133Z","steps":["trace[756831918] 'range keys from in-memory index tree' (duration: 604.576871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.858242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.277018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:12.858305Z","caller":"traceutil/trace.go:171","msg":"trace[1049237054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7816; }","duration":"465.38205ms","start":"2026-04-02T02:29:12.39291Z","end":"2026-04-02T02:29:12.858292Z","steps":["trace[1049237054] 'range keys from in-memory index tree' (duration: 465.177207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.858339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.392889Z","time spent":"465.441662ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T02:29:12.858253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.253195Z","time spent":"605.000509ms","remote":"127.0.0.1:39534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":1224,"request content":"key:\"/registry/roles/cert-manager/cert-manager-cainjector:leaderelection\" "} {"level":"info","ts":"2026-04-02T02:29:12.858022Z","caller":"traceutil/trace.go:171","msg":"trace[1043265332] range","detail":"{range_begin:/registry/rolebindings/cert-manager/cert-manager:leaderelection; range_end:; response_count:1; response_revision:7816; }","duration":"524.149682ms","start":"2026-04-02T02:29:12.333866Z","end":"2026-04-02T02:29:12.858015Z","steps":["trace[1043265332] 'range keys from in-memory index tree' (duration: 523.953408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.858553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.33385Z","time spent":"524.638133ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":1172,"request content":"key:\"/registry/rolebindings/cert-manager/cert-manager:leaderelection\" "} {"level":"info","ts":"2026-04-02T02:29:12.942708Z","caller":"traceutil/trace.go:171","msg":"trace[1256085799] linearizableReadLoop","detail":"{readStateIndex:8107; appliedIndex:8106; }","duration":"436.755256ms","start":"2026-04-02T02:29:12.505941Z","end":"2026-04-02T02:29:12.942696Z","steps":["trace[1256085799] 'read index received' (duration: 436.675584ms)","trace[1256085799] 'applied index is now lower than readState.Index' (duration: 78.602µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:29:12.942789Z","caller":"traceutil/trace.go:171","msg":"trace[715972146] transaction","detail":"{read_only:false; response_revision:7817; number_of_response:1; }","duration":"510.879241ms","start":"2026-04-02T02:29:12.431903Z","end":"2026-04-02T02:29:12.942783Z","steps":["trace[715972146] 'process raft request' (duration: 510.700077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.942856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.43188Z","time spent":"510.921662ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:29:12.942979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.046442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T02:29:12.943Z","caller":"traceutil/trace.go:171","msg":"trace[1960398080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7817; }","duration":"437.106783ms","start":"2026-04-02T02:29:12.505888Z","end":"2026-04-02T02:29:12.942995Z","steps":["trace[1960398080] 'agreement among raft nodes before linearized reading' (duration: 437.038602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.943014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:12.505869Z","time spent":"437.141604ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T02:29:12.943068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.162822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:12.943087Z","caller":"traceutil/trace.go:171","msg":"trace[7688821] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:7817; }","duration":"284.215493ms","start":"2026-04-02T02:29:12.658866Z","end":"2026-04-02T02:29:12.943082Z","steps":["trace[7688821] 'agreement among raft nodes before linearized reading' (duration: 284.187402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:12.9436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.07229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:12.944752Z","caller":"traceutil/trace.go:171","msg":"trace[1769326817] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:7817; }","duration":"195.229645ms","start":"2026-04-02T02:29:12.749469Z","end":"2026-04-02T02:29:12.944698Z","steps":["trace[1769326817] 'agreement among raft nodes before linearized reading' (duration: 194.05616ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:13.267431Z","caller":"traceutil/trace.go:171","msg":"trace[341635244] transaction","detail":"{read_only:false; response_revision:7820; number_of_response:1; }","duration":"108.45962ms","start":"2026-04-02T02:29:13.158938Z","end":"2026-04-02T02:29:13.267398Z","steps":["trace[341635244] 'process raft request' (duration: 108.289426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.689819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.907492ms","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-04-02T02:29:13.689901Z","caller":"traceutil/trace.go:171","msg":"trace[2134410768] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:7820; }","duration":"290.040785ms","start":"2026-04-02T02:29:13.399841Z","end":"2026-04-02T02:29:13.689882Z","steps":["trace[2134410768] 'count revisions from in-memory index tree' (duration: 289.81202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.689987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.647255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:29:13.690122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.198848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak\" ","response":"range_response_count:1 size:963"} {"level":"info","ts":"2026-04-02T02:29:13.690221Z","caller":"traceutil/trace.go:171","msg":"trace[1525349046] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak; range_end:; response_count:1; response_revision:7820; }","duration":"343.334341ms","start":"2026-04-02T02:29:13.346868Z","end":"2026-04-02T02:29:13.690202Z","steps":["trace[1525349046] 'range keys from in-memory index tree' (duration: 343.035295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.690233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.463714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T02:29:13.690262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:13.346853Z","time spent":"343.400472ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":986,"request content":"key:\"/registry/secrets/auth-system/keycloak\" "} {"level":"info","ts":"2026-04-02T02:29:13.690282Z","caller":"traceutil/trace.go:171","msg":"trace[1972400327] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:7820; }","duration":"308.545676ms","start":"2026-04-02T02:29:13.381722Z","end":"2026-04-02T02:29:13.690268Z","steps":["trace[1972400327] 'range keys from in-memory index tree' (duration: 308.403313ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:13.690303Z","caller":"traceutil/trace.go:171","msg":"trace[1231196283] transaction","detail":"{read_only:false; response_revision:7821; number_of_response:1; }","duration":"204.244234ms","start":"2026-04-02T02:29:13.486041Z","end":"2026-04-02T02:29:13.690285Z","steps":["trace[1231196283] 'process raft request' (duration: 203.383836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.690316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:13.381705Z","time spent":"308.603047ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 "} {"level":"warn","ts":"2026-04-02T02:29:13.690421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.17174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak-externaldb\" ","response":"range_response_count:1 size:903"} {"level":"info","ts":"2026-04-02T02:29:13.690589Z","caller":"traceutil/trace.go:171","msg":"trace[218247743] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak-externaldb; range_end:; response_count:1; response_revision:7820; }","duration":"327.364684ms","start":"2026-04-02T02:29:13.363212Z","end":"2026-04-02T02:29:13.690577Z","steps":["trace[218247743] 'range keys from in-memory index tree' (duration: 326.854764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.690639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:13.363199Z","time spent":"327.428645ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":926,"request content":"key:\"/registry/secrets/auth-system/keycloak-externaldb\" "} {"level":"info","ts":"2026-04-02T02:29:13.69115Z","caller":"traceutil/trace.go:171","msg":"trace[1135389845] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:7820; }","duration":"323.798048ms","start":"2026-04-02T02:29:13.366255Z","end":"2026-04-02T02:29:13.690053Z","steps":["trace[1135389845] 'range keys from in-memory index tree' (duration: 323.607404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.691208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:13.36625Z","time spent":"324.940993ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 "} {"level":"info","ts":"2026-04-02T02:29:13.696122Z","caller":"traceutil/trace.go:171","msg":"trace[48527902] transaction","detail":"{read_only:false; response_revision:7822; number_of_response:1; }","duration":"197.94617ms","start":"2026-04-02T02:29:13.49816Z","end":"2026-04-02T02:29:13.696106Z","steps":["trace[48527902] 'process raft request' (duration: 197.698544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:13.919974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.759143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak.199-204-45-242.nip.io-tls\" ","response":"range_response_count:1 size:4376"} {"level":"info","ts":"2026-04-02T02:29:13.920031Z","caller":"traceutil/trace.go:171","msg":"trace[1571468722] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak.199-204-45-242.nip.io-tls; range_end:; response_count:1; response_revision:7822; }","duration":"123.841295ms","start":"2026-04-02T02:29:13.796177Z","end":"2026-04-02T02:29:13.920018Z","steps":["trace[1571468722] 'range keys from in-memory index tree' (duration: 123.610911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:14.130701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.659836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:14.13076Z","caller":"traceutil/trace.go:171","msg":"trace[27859413] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:7823; }","duration":"110.752568ms","start":"2026-04-02T02:29:14.019995Z","end":"2026-04-02T02:29:14.130747Z","steps":["trace[27859413] 'range keys from in-memory index tree' (duration: 110.628685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:14.130754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.913832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak.199-204-45-242.nip.io-tls\" ","response":"range_response_count:1 size:4376"} {"level":"info","ts":"2026-04-02T02:29:14.130818Z","caller":"traceutil/trace.go:171","msg":"trace[842833703] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak.199-204-45-242.nip.io-tls; range_end:; response_count:1; response_revision:7823; }","duration":"111.012663ms","start":"2026-04-02T02:29:14.019788Z","end":"2026-04-02T02:29:14.130801Z","steps":["trace[842833703] 'range keys from in-memory index tree' (duration: 110.787979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:14.6797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.327002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/sh.helm.release.v1.cert-manager.v1\" ","response":"range_response_count:1 size:211678"} {"level":"info","ts":"2026-04-02T02:29:14.679764Z","caller":"traceutil/trace.go:171","msg":"trace[1393806409] range","detail":"{range_begin:/registry/secrets/cert-manager/sh.helm.release.v1.cert-manager.v1; range_end:; response_count:1; response_revision:7826; }","duration":"283.424414ms","start":"2026-04-02T02:29:14.396325Z","end":"2026-04-02T02:29:14.679749Z","steps":["trace[1393806409] 'range keys from in-memory index tree' (duration: 283.125827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:14.679786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.107843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/default/\" range_end:\"/registry/statefulsets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:14.679856Z","caller":"traceutil/trace.go:171","msg":"trace[1941118489] range","detail":"{range_begin:/registry/statefulsets/default/; range_end:/registry/statefulsets/default0; response_count:0; response_revision:7826; }","duration":"216.229385ms","start":"2026-04-02T02:29:14.463606Z","end":"2026-04-02T02:29:14.679836Z","steps":["trace[1941118489] 'range keys from in-memory index tree' (duration: 216.0107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:14.68004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.843527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/kube-prometheus-stack-ca\" ","response":"range_response_count:1 size:2550"} {"level":"info","ts":"2026-04-02T02:29:14.68016Z","caller":"traceutil/trace.go:171","msg":"trace[1895270739] range","detail":"{range_begin:/registry/secrets/cert-manager/kube-prometheus-stack-ca; range_end:; response_count:1; response_revision:7826; }","duration":"280.145704ms","start":"2026-04-02T02:29:14.399995Z","end":"2026-04-02T02:29:14.680141Z","steps":["trace[1895270739] 'range keys from in-memory index tree' (duration: 279.721025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:14.680613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.527517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/\" range_end:\"/registry/configmaps/ingress-nginx0\" limit:500 ","response":"range_response_count:4 size:5053"} {"level":"info","ts":"2026-04-02T02:29:14.680668Z","caller":"traceutil/trace.go:171","msg":"trace[1897463808] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/; range_end:/registry/configmaps/ingress-nginx0; response_count:4; response_revision:7826; }","duration":"220.610188ms","start":"2026-04-02T02:29:14.460045Z","end":"2026-04-02T02:29:14.680655Z","steps":["trace[1897463808] 'range keys from in-memory index tree' (duration: 220.411664ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:17.82459Z","caller":"traceutil/trace.go:171","msg":"trace[915682439] transaction","detail":"{read_only:false; response_revision:7839; number_of_response:1; }","duration":"122.528236ms","start":"2026-04-02T02:29:17.702044Z","end":"2026-04-02T02:29:17.824572Z","steps":["trace[915682439] 'process raft request' (duration: 122.410953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:18.062485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.743986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T02:29:18.062653Z","caller":"traceutil/trace.go:171","msg":"trace[2102322088] transaction","detail":"{read_only:false; response_revision:7841; number_of_response:1; }","duration":"124.853914ms","start":"2026-04-02T02:29:17.937785Z","end":"2026-04-02T02:29:18.062639Z","steps":["trace[2102322088] 'process raft request' (duration: 124.792613ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:18.062696Z","caller":"traceutil/trace.go:171","msg":"trace[1140969407] linearizableReadLoop","detail":"{readStateIndex:8131; appliedIndex:8129; }","duration":"340.147957ms","start":"2026-04-02T02:29:17.722518Z","end":"2026-04-02T02:29:18.062666Z","steps":["trace[1140969407] 'read index received' (duration: 101.982262ms)","trace[1140969407] 'applied index is now lower than readState.Index' (duration: 238.163355ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T02:29:18.062833Z","caller":"traceutil/trace.go:171","msg":"trace[397329131] transaction","detail":"{read_only:false; response_revision:7840; number_of_response:1; }","duration":"354.840717ms","start":"2026-04-02T02:29:17.707973Z","end":"2026-04-02T02:29:18.062814Z","steps":["trace[397329131] 'process raft request' (duration: 224.690502ms)","trace[397329131] 'compare' (duration: 129.481091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T02:29:18.062967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:17.70795Z","time spent":"354.927509ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T02:29:18.063229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.701718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:1 size:4452"} {"level":"info","ts":"2026-04-02T02:29:18.063292Z","caller":"traceutil/trace.go:171","msg":"trace[769917652] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:1; response_revision:7841; }","duration":"340.790699ms","start":"2026-04-02T02:29:17.722482Z","end":"2026-04-02T02:29:18.063273Z","steps":["trace[769917652] 'agreement among raft nodes before linearized reading' (duration: 340.622606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:18.063334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:17.722467Z","time spent":"340.855451ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4475,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" "} {"level":"warn","ts":"2026-04-02T02:29:18.066678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.73048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T02:29:18.066773Z","caller":"traceutil/trace.go:171","msg":"trace[962705048] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:7842; }","duration":"279.843953ms","start":"2026-04-02T02:29:17.786889Z","end":"2026-04-02T02:29:18.066733Z","steps":["trace[962705048] 'agreement among raft nodes before linearized reading' (duration: 279.111838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:18.067114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.931572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036305"} {"level":"info","ts":"2026-04-02T02:29:18.067214Z","caller":"traceutil/trace.go:171","msg":"trace[477469001] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:7842; }","duration":"313.050915ms","start":"2026-04-02T02:29:17.754125Z","end":"2026-04-02T02:29:18.067176Z","steps":["trace[477469001] 'agreement among raft nodes before linearized reading' (duration: 311.633485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:18.067304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.417643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-04-02T02:29:18.067276Z","caller":"traceutil/trace.go:171","msg":"trace[1477662818] transaction","detail":"{read_only:false; response_revision:7842; number_of_response:1; }","duration":"124.902564ms","start":"2026-04-02T02:29:17.941147Z","end":"2026-04-02T02:29:18.066049Z","steps":["trace[1477662818] 'process raft request' (duration: 124.475065ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:18.067361Z","caller":"traceutil/trace.go:171","msg":"trace[1480451157] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:7842; }","duration":"240.480204ms","start":"2026-04-02T02:29:17.82685Z","end":"2026-04-02T02:29:18.06733Z","steps":["trace[1480451157] 'agreement among raft nodes before linearized reading' (duration: 239.131595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T02:29:18.067434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T02:29:17.754111Z","time spent":"313.3027ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":1036328,"request content":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" "} {"level":"warn","ts":"2026-04-02T02:29:49.0808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.109806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:751"} {"level":"warn","ts":"2026-04-02T02:29:49.080904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.595991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-credential-rotate\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-04-02T02:29:49.080971Z","caller":"traceutil/trace.go:171","msg":"trace[1833797649] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-credential-rotate; range_end:; response_count:1; response_revision:7997; }","duration":"193.714924ms","start":"2026-04-02T02:29:48.887237Z","end":"2026-04-02T02:29:49.080952Z","steps":["trace[1833797649] 'range keys from in-memory index tree' (duration: 193.473198ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T02:29:49.080989Z","caller":"traceutil/trace.go:171","msg":"trace[1257266978] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:7997; }","duration":"190.29727ms","start":"2026-04-02T02:29:48.89064Z","end":"2026-04-02T02:29:49.080937Z","steps":["trace[1257266978] 'range keys from in-memory index tree' (duration: 189.980513ms)"],"step_count":1}