{"level":"warn","ts":"2026-09-18T05:13:20.533614Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:13:20.533726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.162:2380","--initial-cluster=instance=https://162.253.55.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-18T05:13:20.533808Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:13:20.533818Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-09-18T05:13:20.533842Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T05:13:20.534613Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"]} {"level":"info","ts":"2026-09-18T05:13:20.534705Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-18T05:13:20.539937Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.939029ms"} {"level":"info","ts":"2026-09-18T05:13:20.550261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f61dce011a423b00","cluster-id":"d363ade277557413"} {"level":"info","ts":"2026-09-18T05:13:20.550357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:13:20.550377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:13:20.550389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f61dce011a423b00 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:13:20.550396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:13:20.550446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"warn","ts":"2026-09-18T05:13:20.562239Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:13:20.564055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:13:20.566203Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-18T05:13:20.572486Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f61dce011a423b00","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:13:20.57346Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f61dce011a423b00","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:13:20.574871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"info","ts":"2026-09-18T05:13:20.574846Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T05:13:20.575092Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T05:13:20.575112Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T05:13:20.575474Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","added-peer-id":"f61dce011a423b00","added-peer-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-09-18T05:13:20.577649Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T05:13:20.57783Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f61dce011a423b00","initial-advertise-peer-urls":["https://162.253.55.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:13:20.577854Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:13:20.577946Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-09-18T05:13:20.577957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-09-18T05:13:21.051314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:13:21.051395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:13:21.051428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgPreVoteResp from f61dce011a423b00 at term 1"} {"level":"info","ts":"2026-09-18T05:13:21.051445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:13:21.051451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgVoteResp from f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-09-18T05:13:21.051459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:13:21.051471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f61dce011a423b00 elected leader f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-09-18T05:13:21.091767Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:13:21.092051Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f61dce011a423b00","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.162:2379]}","request-path":"/0/members/f61dce011a423b00/attributes","cluster-id":"d363ade277557413","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:13:21.09226Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:13:21.092745Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:13:21.094195Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:13:21.094231Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:13:21.094959Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.162:2379"} {"level":"info","ts":"2026-09-18T05:13:21.095458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:13:21.104029Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:13:21.104338Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:13:21.104477Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:13:23.037306Z","caller":"traceutil/trace.go:171","msg":"trace[249214487] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"149.437851ms","start":"2026-09-18T05:13:22.887838Z","end":"2026-09-18T05:13:23.037276Z","steps":["trace[249214487] 'process raft request' (duration: 51.664196ms)","trace[249214487] 'compare' (duration: 97.633224ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:23.916873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.244583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-18T05:13:23.917011Z","caller":"traceutil/trace.go:171","msg":"trace[172398113] linearizableReadLoop","detail":"{readStateIndex:127; appliedIndex:126; }","duration":"370.856194ms","start":"2026-09-18T05:13:23.546134Z","end":"2026-09-18T05:13:23.916991Z","steps":["trace[172398113] 'read index received' (duration: 30.752973ms)","trace[172398113] 'applied index is now lower than readState.Index' (duration: 340.101661ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:23.917078Z","caller":"traceutil/trace.go:171","msg":"trace[1818339421] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"422.748728ms","start":"2026-09-18T05:13:23.494293Z","end":"2026-09-18T05:13:23.917042Z","steps":["trace[1818339421] 'process raft request' (duration: 82.649137ms)","trace[1818339421] 'compare' (duration: 339.070142ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:23.917119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.983645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:13:23.917164Z","caller":"traceutil/trace.go:171","msg":"trace[464384249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:122; }","duration":"371.113976ms","start":"2026-09-18T05:13:23.546037Z","end":"2026-09-18T05:13:23.917151Z","steps":["trace[464384249] 'agreement among raft nodes before linearized reading' (duration: 371.006016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:23.917214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:23.546007Z","time spent":"371.196767ms","remote":"127.0.0.1:39138","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-09-18T05:13:23.917284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:23.494282Z","time spent":"422.873248ms","remote":"127.0.0.1:39462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:13:24.051436Z","caller":"traceutil/trace.go:171","msg":"trace[1691505799] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:127; }","duration":"132.764535ms","start":"2026-09-18T05:13:23.918639Z","end":"2026-09-18T05:13:24.051403Z","steps":["trace[1691505799] 'read index received' (duration: 126.431457ms)","trace[1691505799] 'applied index is now lower than readState.Index' (duration: 6.331778ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:24.051793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.123517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:13:24.051819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.613946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:13:24.05185Z","caller":"traceutil/trace.go:171","msg":"trace[917207308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:123; }","duration":"133.234908ms","start":"2026-09-18T05:13:23.918599Z","end":"2026-09-18T05:13:24.051834Z","steps":["trace[917207308] 'agreement among raft nodes before linearized reading' (duration: 133.069297ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:24.051849Z","caller":"traceutil/trace.go:171","msg":"trace[1623845826] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"353.115386ms","start":"2026-09-18T05:13:23.698696Z","end":"2026-09-18T05:13:24.051812Z","steps":["trace[1623845826] 'process raft request' (duration: 346.448575ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:24.05196Z","caller":"traceutil/trace.go:171","msg":"trace[354513702] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:123; }","duration":"131.714646ms","start":"2026-09-18T05:13:23.920148Z","end":"2026-09-18T05:13:24.051862Z","steps":["trace[354513702] 'agreement among raft nodes before linearized reading' (duration: 131.612575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:24.052013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:23.698655Z","time spent":"353.282707ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:13:24.829829Z","caller":"traceutil/trace.go:171","msg":"trace[558751188] linearizableReadLoop","detail":"{readStateIndex:199; appliedIndex:198; }","duration":"226.772035ms","start":"2026-09-18T05:13:24.60301Z","end":"2026-09-18T05:13:24.829782Z","steps":["trace[558751188] 'read index received' (duration: 191.677712ms)","trace[558751188] 'applied index is now lower than readState.Index' (duration: 35.092833ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:24.829994Z","caller":"traceutil/trace.go:171","msg":"trace[1193683809] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"309.130713ms","start":"2026-09-18T05:13:24.520829Z","end":"2026-09-18T05:13:24.82996Z","steps":["trace[1193683809] 'process raft request' (duration: 273.933889ms)","trace[1193683809] 'compare' (duration: 34.822582ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:24.830141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.122907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T05:13:24.830186Z","caller":"traceutil/trace.go:171","msg":"trace[517756549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:194; }","duration":"227.3034ms","start":"2026-09-18T05:13:24.602871Z","end":"2026-09-18T05:13:24.830175Z","steps":["trace[517756549] 'agreement among raft nodes before linearized reading' (duration: 227.117127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:24.830185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:24.520821Z","time spent":"309.239284ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:13:24.830239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.790772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:13:24.830286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.088147ms","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-09-18T05:13:24.830411Z","caller":"traceutil/trace.go:171","msg":"trace[369214382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:194; }","duration":"183.964193ms","start":"2026-09-18T05:13:24.64637Z","end":"2026-09-18T05:13:24.830334Z","steps":["trace[369214382] 'agreement among raft nodes before linearized reading' (duration: 183.800262ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:24.830658Z","caller":"traceutil/trace.go:171","msg":"trace[456516421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:194; }","duration":"227.302579ms","start":"2026-09-18T05:13:24.603074Z","end":"2026-09-18T05:13:24.830377Z","steps":["trace[456516421] 'agreement among raft nodes before linearized reading' (duration: 226.964356ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:25.163041Z","caller":"traceutil/trace.go:171","msg":"trace[967242343] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"121.811137ms","start":"2026-09-18T05:13:25.041199Z","end":"2026-09-18T05:13:25.16301Z","steps":["trace[967242343] 'process raft request' (duration: 56.502321ms)","trace[967242343] 'compare' (duration: 65.178515ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:25.904498Z","caller":"traceutil/trace.go:171","msg":"trace[1998481764] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"168.531135ms","start":"2026-09-18T05:13:25.735938Z","end":"2026-09-18T05:13:25.904469Z","steps":["trace[1998481764] 'process raft request' (duration: 168.360553ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:27.57093Z","caller":"traceutil/trace.go:171","msg":"trace[1641828651] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"177.075316ms","start":"2026-09-18T05:13:27.393821Z","end":"2026-09-18T05:13:27.570896Z","steps":["trace[1641828651] 'read index received' (duration: 88.424126ms)","trace[1641828651] 'applied index is now lower than readState.Index' (duration: 88.6496ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:27.571199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.338938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-18T05:13:27.571233Z","caller":"traceutil/trace.go:171","msg":"trace[1312021938] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"177.427418ms","start":"2026-09-18T05:13:27.393797Z","end":"2026-09-18T05:13:27.571225Z","steps":["trace[1312021938] 'agreement among raft nodes before linearized reading' (duration: 177.271257ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:27.571006Z","caller":"traceutil/trace.go:171","msg":"trace[1475666091] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"202.529492ms","start":"2026-09-18T05:13:27.368454Z","end":"2026-09-18T05:13:27.570983Z","steps":["trace[1475666091] 'process raft request' (duration: 113.717312ms)","trace[1475666091] 'compare' (duration: 88.561839ms)"],"step_count":2} 2026/09/18 05:13:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T05:13:28.144974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.347611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:13:28.145326Z","caller":"traceutil/trace.go:171","msg":"trace[1661665417] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"100.076597ms","start":"2026-09-18T05:13:28.045231Z","end":"2026-09-18T05:13:28.145308Z","steps":["trace[1661665417] 'process raft request' (duration: 99.819806ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:28.145352Z","caller":"traceutil/trace.go:171","msg":"trace[1683232767] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"146.254661ms","start":"2026-09-18T05:13:27.999075Z","end":"2026-09-18T05:13:28.145329Z","steps":["trace[1683232767] 'process raft request' (duration: 31.496028ms)","trace[1683232767] 'compare' (duration: 114.22772ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:29.207648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.131785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-09-18T05:13:29.207716Z","caller":"traceutil/trace.go:171","msg":"trace[1561867861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:324; }","duration":"181.246516ms","start":"2026-09-18T05:13:29.026453Z","end":"2026-09-18T05:13:29.2077Z","steps":["trace[1561867861] 'range keys from in-memory index tree' (duration: 181.039654ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:29.284897Z","caller":"traceutil/trace.go:171","msg":"trace[2061337627] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"238.502327ms","start":"2026-09-18T05:13:29.046362Z","end":"2026-09-18T05:13:29.284865Z","steps":["trace[2061337627] 'process raft request' (duration: 238.331986ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:29.284904Z","caller":"traceutil/trace.go:171","msg":"trace[1067707083] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"160.885319ms","start":"2026-09-18T05:13:29.123972Z","end":"2026-09-18T05:13:29.284857Z","steps":["trace[1067707083] 'read index received' (duration: 160.864639ms)","trace[1067707083] 'applied index is now lower than readState.Index' (duration: 17.38µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:29.285205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.191271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-09-18T05:13:29.285289Z","caller":"traceutil/trace.go:171","msg":"trace[2059143220] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:325; }","duration":"161.315562ms","start":"2026-09-18T05:13:29.123935Z","end":"2026-09-18T05:13:29.28525Z","steps":["trace[2059143220] 'agreement among raft nodes before linearized reading' (duration: 161.094261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:29.790093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4251574738611579108,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T05:13:30.210626Z","caller":"traceutil/trace.go:171","msg":"trace[759355654] transaction","detail":"{read_only:false; number_of_response:0; response_revision:325; }","duration":"1.084199375s","start":"2026-09-18T05:13:29.126402Z","end":"2026-09-18T05:13:30.210602Z","steps":["trace[759355654] 'process raft request' (duration: 1.083997493s)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:30.210717Z","caller":"traceutil/trace.go:171","msg":"trace[758657607] transaction","detail":"{read_only:false; number_of_response:0; response_revision:325; }","duration":"1.081372774s","start":"2026-09-18T05:13:29.129338Z","end":"2026-09-18T05:13:30.210711Z","steps":["trace[758657607] 'process raft request' (duration: 1.081229673s)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:30.210691Z","caller":"traceutil/trace.go:171","msg":"trace[1496221517] transaction","detail":"{read_only:false; number_of_response:0; response_revision:325; }","duration":"1.082680413s","start":"2026-09-18T05:13:29.127977Z","end":"2026-09-18T05:13:30.210658Z","steps":["trace[1496221517] 'process raft request' (duration: 1.082566532s)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:30.210729Z","caller":"traceutil/trace.go:171","msg":"trace[431974873] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"997.999923ms","start":"2026-09-18T05:13:29.212698Z","end":"2026-09-18T05:13:30.210698Z","steps":["trace[431974873] 'process raft request' (duration: 997.898373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:30.210811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.129324Z","time spent":"1.081424865s","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:13:30.21085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.12796Z","time spent":"1.082818894s","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:13:30.210654Z","caller":"traceutil/trace.go:171","msg":"trace[1359421470] transaction","detail":"{read_only:false; number_of_response:0; response_revision:325; }","duration":"1.082670263s","start":"2026-09-18T05:13:29.127953Z","end":"2026-09-18T05:13:30.210623Z","steps":["trace[1359421470] 'process raft request' (duration: 1.082559932s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:30.211078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.126384Z","time spent":"1.084637707s","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:13:30.211136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.212669Z","time spent":"998.151674ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:13:30.211201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.127918Z","time spent":"1.083199186s","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:13:30.310979Z","caller":"traceutil/trace.go:171","msg":"trace[729301837] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:335; }","duration":"1.021185716s","start":"2026-09-18T05:13:29.289774Z","end":"2026-09-18T05:13:30.31096Z","steps":["trace[729301837] 'read index received' (duration: 920.668407ms)","trace[729301837] 'applied index is now lower than readState.Index' (duration: 100.516629ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:30.311032Z","caller":"traceutil/trace.go:171","msg":"trace[1777730803] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"994.373462ms","start":"2026-09-18T05:13:29.316631Z","end":"2026-09-18T05:13:30.311005Z","steps":["trace[1777730803] 'process raft request' (duration: 959.903996ms)","trace[1777730803] 'compare' (duration: 34.317975ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:30.311141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021347937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-09-18T05:13:30.311168Z","caller":"traceutil/trace.go:171","msg":"trace[1582090413] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:327; }","duration":"1.021448587s","start":"2026-09-18T05:13:29.289712Z","end":"2026-09-18T05:13:30.31116Z","steps":["trace[1582090413] 'agreement among raft nodes before linearized reading' (duration: 1.021356786s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:30.311173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.316607Z","time spent":"994.494222ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:13:30.311188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.289687Z","time spent":"1.021496787s","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-09-18T05:13:30.312481Z","caller":"traceutil/trace.go:171","msg":"trace[2033053822] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:327; }","duration":"100.019635ms","start":"2026-09-18T05:13:30.212432Z","end":"2026-09-18T05:13:30.312452Z","steps":["trace[2033053822] 'agreement among raft nodes before linearized reading' (duration: 99.921753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:30.312615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01221511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T05:13:30.312643Z","caller":"traceutil/trace.go:171","msg":"trace[1021918096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:327; }","duration":"1.012543293s","start":"2026-09-18T05:13:29.300091Z","end":"2026-09-18T05:13:30.312635Z","steps":["trace[1021918096] 'agreement among raft nodes before linearized reading' (duration: 1.01221469s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:30.31266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:29.300069Z","time spent":"1.012586293s","remote":"127.0.0.1:39386","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-09-18T05:13:30.312625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.035425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-09-18T05:13:30.313333Z","caller":"traceutil/trace.go:171","msg":"trace[21899233] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:327; }","duration":"100.906811ms","start":"2026-09-18T05:13:30.212414Z","end":"2026-09-18T05:13:30.313321Z","steps":["trace[21899233] 'agreement among raft nodes before linearized reading' (duration: 100.015715ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:30.540968Z","caller":"traceutil/trace.go:171","msg":"trace[322435473] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"133.467463ms","start":"2026-09-18T05:13:30.407447Z","end":"2026-09-18T05:13:30.540914Z","steps":["trace[322435473] 'process raft request' (duration: 47.974763ms)","trace[322435473] 'compare' (duration: 85.262109ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:30.541136Z","caller":"traceutil/trace.go:171","msg":"trace[118157808] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"131.876541ms","start":"2026-09-18T05:13:30.409232Z","end":"2026-09-18T05:13:30.541109Z","steps":["trace[118157808] 'process raft request' (duration: 131.60652ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:30.749336Z","caller":"traceutil/trace.go:171","msg":"trace[1526836065] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"147.992736ms","start":"2026-09-18T05:13:30.601309Z","end":"2026-09-18T05:13:30.749302Z","steps":["trace[1526836065] 'read index received' (duration: 94.800146ms)","trace[1526836065] 'applied index is now lower than readState.Index' (duration: 53.19129ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:13:30.749488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.144138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:13:30.749607Z","caller":"traceutil/trace.go:171","msg":"trace[953832708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:335; }","duration":"148.217669ms","start":"2026-09-18T05:13:30.601303Z","end":"2026-09-18T05:13:30.749521Z","steps":["trace[953832708] 'agreement among raft nodes before linearized reading' (duration: 148.115088ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:30.749803Z","caller":"traceutil/trace.go:171","msg":"trace[835036084] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"196.236102ms","start":"2026-09-18T05:13:30.553208Z","end":"2026-09-18T05:13:30.749444Z","steps":["trace[835036084] 'process raft request' (duration: 142.85623ms)","trace[835036084] 'compare' (duration: 53.077079ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:14:04.997618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.386094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:14:04.997704Z","caller":"traceutil/trace.go:171","msg":"trace[497019670] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"325.908251ms","start":"2026-09-18T05:14:04.67178Z","end":"2026-09-18T05:14:04.997688Z","steps":["trace[497019670] 'process raft request' (duration: 160.398551ms)","trace[497019670] 'compare' (duration: 165.218355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:14:04.997759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:04.671763Z","time spent":"325.981175ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:14:05.011931Z","caller":"traceutil/trace.go:171","msg":"trace[1836559050] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"331.730913ms","start":"2026-09-18T05:14:04.680174Z","end":"2026-09-18T05:14:05.011905Z","steps":["trace[1836559050] 'process raft request' (duration: 331.529043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:05.012103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:04.680154Z","time spent":"331.86733ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:05.012079Z","caller":"traceutil/trace.go:171","msg":"trace[979756253] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"321.988652ms","start":"2026-09-18T05:14:04.690055Z","end":"2026-09-18T05:14:05.012044Z","steps":["trace[979756253] 'process raft request' (duration: 321.803443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:05.012337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:04.690033Z","time spent":"322.14072ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:05.666259Z","caller":"traceutil/trace.go:171","msg":"trace[1993573433] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"188.982649ms","start":"2026-09-18T05:14:05.477252Z","end":"2026-09-18T05:14:05.666234Z","steps":["trace[1993573433] 'process raft request' (duration: 188.702525ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:06.175232Z","caller":"traceutil/trace.go:171","msg":"trace[335820349] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"478.062872ms","start":"2026-09-18T05:14:05.69714Z","end":"2026-09-18T05:14:06.175203Z","steps":["trace[335820349] 'process raft request' (duration: 477.83475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:06.175426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:05.697109Z","time spent":"478.19946ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:06.193399Z","caller":"traceutil/trace.go:171","msg":"trace[1977833699] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"481.482498ms","start":"2026-09-18T05:14:05.711897Z","end":"2026-09-18T05:14:06.193379Z","steps":["trace[1977833699] 'process raft request' (duration: 481.200704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:06.193522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:05.711867Z","time spent":"481.598595ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:14:11.638203Z","caller":"traceutil/trace.go:171","msg":"trace[45500273] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"163.215371ms","start":"2026-09-18T05:14:11.474959Z","end":"2026-09-18T05:14:11.638174Z","steps":["trace[45500273] 'process raft request' (duration: 162.16627ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:11.852345Z","caller":"traceutil/trace.go:171","msg":"trace[1300869929] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"137.509334ms","start":"2026-09-18T05:14:11.714807Z","end":"2026-09-18T05:14:11.852316Z","steps":["trace[1300869929] 'process raft request' (duration: 137.317675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:12.695982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.46557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vz4xq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T05:14:12.696087Z","caller":"traceutil/trace.go:171","msg":"trace[1305475831] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vz4xq; range_end:; response_count:1; response_revision:652; }","duration":"464.594686ms","start":"2026-09-18T05:14:12.231458Z","end":"2026-09-18T05:14:12.696053Z","steps":["trace[1305475831] 'range keys from in-memory index tree' (duration: 464.23758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:12.696429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:12.231445Z","time spent":"464.964984ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-vz4xq\" "} {"level":"info","ts":"2026-09-18T05:14:12.699214Z","caller":"traceutil/trace.go:171","msg":"trace[2111029296] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"466.584411ms","start":"2026-09-18T05:14:12.232596Z","end":"2026-09-18T05:14:12.699181Z","steps":["trace[2111029296] 'process raft request' (duration: 465.138883ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:12.699229Z","caller":"traceutil/trace.go:171","msg":"trace[1321461434] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"226.44394ms","start":"2026-09-18T05:14:12.472715Z","end":"2026-09-18T05:14:12.699159Z","steps":["trace[1321461434] 'read index received' (duration: 224.611203ms)","trace[1321461434] 'applied index is now lower than readState.Index' (duration: 1.831277ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:14:12.699386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:12.232583Z","time spent":"466.710056ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:14:12.699755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.984136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-18T05:14:12.699913Z","caller":"traceutil/trace.go:171","msg":"trace[135267986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"227.140084ms","start":"2026-09-18T05:14:12.472669Z","end":"2026-09-18T05:14:12.699809Z","steps":["trace[135267986] 'agreement among raft nodes before linearized reading' (duration: 226.604408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:12.700293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.668508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T05:14:12.700428Z","caller":"traceutil/trace.go:171","msg":"trace[241780389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"114.863598ms","start":"2026-09-18T05:14:12.585535Z","end":"2026-09-18T05:14:12.700399Z","steps":["trace[241780389] 'agreement among raft nodes before linearized reading' (duration: 114.06987ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:27.45537Z","caller":"traceutil/trace.go:171","msg":"trace[2009296081] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"126.113498ms","start":"2026-09-18T05:14:27.329231Z","end":"2026-09-18T05:14:27.455344Z","steps":["trace[2009296081] 'read index received' (duration: 125.851238ms)","trace[2009296081] 'applied index is now lower than readState.Index' (duration: 261.65µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:14:27.45549Z","caller":"traceutil/trace.go:171","msg":"trace[1814042223] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"137.132347ms","start":"2026-09-18T05:14:27.318326Z","end":"2026-09-18T05:14:27.455458Z","steps":["trace[1814042223] 'process raft request' (duration: 136.863036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:27.45561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.362679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:14:27.456089Z","caller":"traceutil/trace.go:171","msg":"trace[1339876638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"126.868968ms","start":"2026-09-18T05:14:27.329182Z","end":"2026-09-18T05:14:27.456051Z","steps":["trace[1339876638] 'agreement among raft nodes before linearized reading' (duration: 126.296166ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:30.853701Z","caller":"traceutil/trace.go:171","msg":"trace[40540344] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"387.260183ms","start":"2026-09-18T05:14:30.466421Z","end":"2026-09-18T05:14:30.853681Z","steps":["trace[40540344] 'read index received' (duration: 387.152999ms)","trace[40540344] 'applied index is now lower than readState.Index' (duration: 106.184µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:14:30.853815Z","caller":"traceutil/trace.go:171","msg":"trace[1303932152] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"422.78856ms","start":"2026-09-18T05:14:30.43101Z","end":"2026-09-18T05:14:30.853798Z","steps":["trace[1303932152] 'process raft request' (duration: 422.509419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:30.854012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:30.430985Z","time spent":"422.916025ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:14:30.854028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.595697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:14:30.854162Z","caller":"traceutil/trace.go:171","msg":"trace[1228552402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"387.720121ms","start":"2026-09-18T05:14:30.466382Z","end":"2026-09-18T05:14:30.854103Z","steps":["trace[1228552402] 'agreement among raft nodes before linearized reading' (duration: 387.595217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:30.854246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:30.466367Z","time spent":"387.864926ms","remote":"127.0.0.1:39138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:15:18.706383Z","caller":"traceutil/trace.go:171","msg":"trace[1371460077] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"122.866033ms","start":"2026-09-18T05:15:18.583483Z","end":"2026-09-18T05:15:18.706349Z","steps":["trace[1371460077] 'process raft request' (duration: 122.565506ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:26.938835Z","caller":"traceutil/trace.go:171","msg":"trace[932894323] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"172.251804ms","start":"2026-09-18T05:15:26.766562Z","end":"2026-09-18T05:15:26.938814Z","steps":["trace[932894323] 'process raft request' (duration: 172.124661ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:30.880931Z","caller":"traceutil/trace.go:171","msg":"trace[386968762] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"158.664961ms","start":"2026-09-18T05:15:30.722247Z","end":"2026-09-18T05:15:30.880912Z","steps":["trace[386968762] 'process raft request' (duration: 158.500377ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:30.881111Z","caller":"traceutil/trace.go:171","msg":"trace[46931025] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"160.304196ms","start":"2026-09-18T05:15:30.720798Z","end":"2026-09-18T05:15:30.881102Z","steps":["trace[46931025] 'process raft request' (duration: 159.814625ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:30.881423Z","caller":"traceutil/trace.go:171","msg":"trace[1530234918] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"158.938437ms","start":"2026-09-18T05:15:30.722466Z","end":"2026-09-18T05:15:30.881405Z","steps":["trace[1530234918] 'process raft request' (duration: 158.335563ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:30.881499Z","caller":"traceutil/trace.go:171","msg":"trace[1162083591] linearizableReadLoop","detail":"{readStateIndex:1635; appliedIndex:1631; }","duration":"148.278727ms","start":"2026-09-18T05:15:30.733166Z","end":"2026-09-18T05:15:30.881445Z","steps":["trace[1162083591] 'read index received' (duration: 56.282663ms)","trace[1162083591] 'applied index is now lower than readState.Index' (duration: 91.988144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:30.881663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.471681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/vhosts.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:15:30.881706Z","caller":"traceutil/trace.go:171","msg":"trace[762216288] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/vhosts.rabbitmq.com; range_end:; response_count:0; response_revision:1583; }","duration":"148.585233ms","start":"2026-09-18T05:15:30.733107Z","end":"2026-09-18T05:15:30.881692Z","steps":["trace[762216288] 'agreement among raft nodes before linearized reading' (duration: 148.490861ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:30.880968Z","caller":"traceutil/trace.go:171","msg":"trace[1581901123] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"145.585749ms","start":"2026-09-18T05:15:30.735365Z","end":"2026-09-18T05:15:30.880951Z","steps":["trace[1581901123] 'process raft request' (duration: 145.525477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:52.153908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.318745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:15:52.154043Z","caller":"traceutil/trace.go:171","msg":"trace[1042326682] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"198.780646ms","start":"2026-09-18T05:15:51.955244Z","end":"2026-09-18T05:15:52.154024Z","steps":["trace[1042326682] 'read index received' (duration: 57.681µs)","trace[1042326682] 'applied index is now lower than readState.Index' (duration: 198.720595ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:52.154164Z","caller":"traceutil/trace.go:171","msg":"trace[132172520] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"298.868949ms","start":"2026-09-18T05:15:51.85527Z","end":"2026-09-18T05:15:52.154139Z","steps":["trace[132172520] 'process raft request' (duration: 85.226158ms)","trace[132172520] 'compare' (duration: 213.179553ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:52.154285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.044971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a747cf25-7d4f-42dc-a235-f6e364ac54fe\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T05:15:52.154334Z","caller":"traceutil/trace.go:171","msg":"trace[1775185560] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a747cf25-7d4f-42dc-a235-f6e364ac54fe; range_end:; response_count:1; response_revision:1910; }","duration":"199.132933ms","start":"2026-09-18T05:15:51.955185Z","end":"2026-09-18T05:15:52.154318Z","steps":["trace[1775185560] 'agreement among raft nodes before linearized reading' (duration: 198.96819ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:52.167156Z","caller":"traceutil/trace.go:171","msg":"trace[374044643] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"172.152412ms","start":"2026-09-18T05:15:51.994973Z","end":"2026-09-18T05:15:52.167125Z","steps":["trace[374044643] 'process raft request' (duration: 171.998069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:52.167737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.380761ms","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-09-18T05:15:52.167784Z","caller":"traceutil/trace.go:171","msg":"trace[2141831243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1911; }","duration":"142.459043ms","start":"2026-09-18T05:15:52.025312Z","end":"2026-09-18T05:15:52.167771Z","steps":["trace[2141831243] 'agreement among raft nodes before linearized reading' (duration: 142.308791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:52.16799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.301167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T05:15:52.168076Z","caller":"traceutil/trace.go:171","msg":"trace[1836328189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1911; }","duration":"150.377618ms","start":"2026-09-18T05:15:52.01764Z","end":"2026-09-18T05:15:52.168017Z","steps":["trace[1836328189] 'agreement among raft nodes before linearized reading' (duration: 149.92839ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:58.281086Z","caller":"traceutil/trace.go:171","msg":"trace[1955251334] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"103.146088ms","start":"2026-09-18T05:15:58.177883Z","end":"2026-09-18T05:15:58.281029Z","steps":["trace[1955251334] 'process raft request' (duration: 102.856043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:20.647136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.53123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:16:20.647235Z","caller":"traceutil/trace.go:171","msg":"trace[1166440253] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2115; }","duration":"130.685692ms","start":"2026-09-18T05:16:20.516537Z","end":"2026-09-18T05:16:20.647223Z","steps":["trace[1166440253] 'count revisions from in-memory index tree' (duration: 130.453898ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:26.539601Z","caller":"traceutil/trace.go:171","msg":"trace[395397308] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"128.671215ms","start":"2026-09-18T05:16:26.410885Z","end":"2026-09-18T05:16:26.539556Z","steps":["trace[395397308] 'read index received' (duration: 128.452791ms)","trace[395397308] 'applied index is now lower than readState.Index' (duration: 217.234µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:16:26.539711Z","caller":"traceutil/trace.go:171","msg":"trace[368759862] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"155.574445ms","start":"2026-09-18T05:16:26.384126Z","end":"2026-09-18T05:16:26.5397Z","steps":["trace[368759862] 'process raft request' (duration: 155.27835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:26.540022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.667374ms","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-09-18T05:16:26.540202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.319584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:16:26.540215Z","caller":"traceutil/trace.go:171","msg":"trace[1943051268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2147; }","duration":"128.918628ms","start":"2026-09-18T05:16:26.411274Z","end":"2026-09-18T05:16:26.540192Z","steps":["trace[1943051268] 'agreement among raft nodes before linearized reading' (duration: 128.562123ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:26.540241Z","caller":"traceutil/trace.go:171","msg":"trace[1461805749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2147; }","duration":"129.428136ms","start":"2026-09-18T05:16:26.410801Z","end":"2026-09-18T05:16:26.540229Z","steps":["trace[1461805749] 'agreement among raft nodes before linearized reading' (duration: 129.286344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:26.540317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.769619ms","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-09-18T05:16:26.540419Z","caller":"traceutil/trace.go:171","msg":"trace[1241162771] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2147; }","duration":"123.930951ms","start":"2026-09-18T05:16:26.416467Z","end":"2026-09-18T05:16:26.540398Z","steps":["trace[1241162771] 'agreement among raft nodes before linearized reading' (duration: 123.706898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.357437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.217331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:16:27.35758Z","caller":"traceutil/trace.go:171","msg":"trace[2038917603] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2149; }","duration":"633.372973ms","start":"2026-09-18T05:16:26.724175Z","end":"2026-09-18T05:16:27.357548Z","steps":["trace[2038917603] 'count revisions from in-memory index tree' (duration: 633.082859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.357646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:26.724107Z","time spent":"633.521335ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:16:27.357617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.201885ms","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-09-18T05:16:27.357731Z","caller":"traceutil/trace.go:171","msg":"trace[449688554] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2149; }","duration":"237.319096ms","start":"2026-09-18T05:16:27.120397Z","end":"2026-09-18T05:16:27.357716Z","steps":["trace[449688554] 'range keys from in-memory index tree' (duration: 237.187665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.358982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.752604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:16:27.359364Z","caller":"traceutil/trace.go:171","msg":"trace[1703467785] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"512.208233ms","start":"2026-09-18T05:16:26.84711Z","end":"2026-09-18T05:16:27.359318Z","steps":["trace[1703467785] 'read index received' (duration: 188.875961ms)","trace[1703467785] 'applied index is now lower than readState.Index' (duration: 323.328522ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:16:27.359444Z","caller":"traceutil/trace.go:171","msg":"trace[1478045977] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"560.869637ms","start":"2026-09-18T05:16:26.798538Z","end":"2026-09-18T05:16:27.359408Z","steps":["trace[1478045977] 'process raft request' (duration: 237.544104ms)","trace[1478045977] 'compare' (duration: 321.436525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:16:27.359599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.484368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-18T05:16:27.359654Z","caller":"traceutil/trace.go:171","msg":"trace[1991530327] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2150; }","duration":"512.574989ms","start":"2026-09-18T05:16:26.847062Z","end":"2026-09-18T05:16:27.359637Z","steps":["trace[1991530327] 'agreement among raft nodes before linearized reading' (duration: 512.368936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.359705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:26.847033Z","time spent":"512.659551ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T05:16:27.359764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:26.798499Z","time spent":"561.140441ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:16:27.3597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.588367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:27.359905Z","caller":"traceutil/trace.go:171","msg":"trace[349542827] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2150; }","duration":"271.830961ms","start":"2026-09-18T05:16:27.088056Z","end":"2026-09-18T05:16:27.359887Z","steps":["trace[349542827] 'agreement among raft nodes before linearized reading' (duration: 271.596167ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:27.761179Z","caller":"traceutil/trace.go:171","msg":"trace[1724221986] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"218.176025ms","start":"2026-09-18T05:16:27.542975Z","end":"2026-09-18T05:16:27.761151Z","steps":["trace[1724221986] 'read index received' (duration: 217.912901ms)","trace[1724221986] 'applied index is now lower than readState.Index' (duration: 262.134µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:16:27.761313Z","caller":"traceutil/trace.go:171","msg":"trace[853803462] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"250.163031ms","start":"2026-09-18T05:16:27.511136Z","end":"2026-09-18T05:16:27.761299Z","steps":["trace[853803462] 'process raft request' (duration: 249.824316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.761678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.686223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-18T05:16:27.761724Z","caller":"traceutil/trace.go:171","msg":"trace[1724630527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"218.770984ms","start":"2026-09-18T05:16:27.542942Z","end":"2026-09-18T05:16:27.761713Z","steps":["trace[1724630527] 'agreement among raft nodes before linearized reading' (duration: 218.617571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:28.306355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.432499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:16:28.306469Z","caller":"traceutil/trace.go:171","msg":"trace[1977631191] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"535.375705ms","start":"2026-09-18T05:16:27.771068Z","end":"2026-09-18T05:16:28.306444Z","steps":["trace[1977631191] 'process raft request' (duration: 23.747462ms)","trace[1977631191] 'compare' (duration: 511.179276ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:16:28.306557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:27.77104Z","time spent":"535.472096ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:16:28.683847Z","caller":"traceutil/trace.go:171","msg":"trace[1461912824] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"139.278326ms","start":"2026-09-18T05:16:28.544532Z","end":"2026-09-18T05:16:28.68381Z","steps":["trace[1461912824] 'process raft request' (duration: 139.029232ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:00.850705Z","caller":"traceutil/trace.go:171","msg":"trace[1073099149] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"108.635248ms","start":"2026-09-18T05:17:00.742052Z","end":"2026-09-18T05:17:00.850688Z","steps":["trace[1073099149] 'process raft request' (duration: 108.442165ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:20.655977Z","caller":"traceutil/trace.go:171","msg":"trace[1172067466] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"100.910203ms","start":"2026-09-18T05:17:20.555039Z","end":"2026-09-18T05:17:20.65595Z","steps":["trace[1172067466] 'process raft request' (duration: 100.796032ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:20.8465Z","caller":"traceutil/trace.go:171","msg":"trace[66908209] linearizableReadLoop","detail":"{readStateIndex:2545; appliedIndex:2544; }","duration":"157.614415ms","start":"2026-09-18T05:17:20.688842Z","end":"2026-09-18T05:17:20.846456Z","steps":["trace[66908209] 'read index received' (duration: 157.406902ms)","trace[66908209] 'applied index is now lower than readState.Index' (duration: 206.443µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:17:20.846629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.769246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:17:20.846774Z","caller":"traceutil/trace.go:171","msg":"trace[2039575444] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2448; }","duration":"157.831707ms","start":"2026-09-18T05:17:20.68883Z","end":"2026-09-18T05:17:20.846661Z","steps":["trace[2039575444] 'agreement among raft nodes before linearized reading' (duration: 157.745736ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:20.847302Z","caller":"traceutil/trace.go:171","msg":"trace[1716160903] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"175.827866ms","start":"2026-09-18T05:17:20.671463Z","end":"2026-09-18T05:17:20.847291Z","steps":["trace[1716160903] 'process raft request' (duration: 174.805263ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:36.669693Z","caller":"traceutil/trace.go:171","msg":"trace[979350007] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"105.375495ms","start":"2026-09-18T05:17:36.564286Z","end":"2026-09-18T05:17:36.669662Z","steps":["trace[979350007] 'process raft request' (duration: 105.203453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:37.14328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.241917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:17:37.143352Z","caller":"traceutil/trace.go:171","msg":"trace[731608104] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2627; }","duration":"300.359908ms","start":"2026-09-18T05:17:36.842976Z","end":"2026-09-18T05:17:37.143336Z","steps":["trace[731608104] 'count revisions from in-memory index tree' (duration: 300.192277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:37.143392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:36.842962Z","time spent":"300.42017ms","remote":"127.0.0.1:39516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:17:37.14373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.288688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-18T05:17:37.143798Z","caller":"traceutil/trace.go:171","msg":"trace[1274661644] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2627; }","duration":"217.317388ms","start":"2026-09-18T05:17:36.926432Z","end":"2026-09-18T05:17:37.143749Z","steps":["trace[1274661644] 'range keys from in-memory index tree' (duration: 217.183256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:37.143993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.635706ms","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-09-18T05:17:37.144023Z","caller":"traceutil/trace.go:171","msg":"trace[534186819] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2627; }","duration":"140.691707ms","start":"2026-09-18T05:17:37.00332Z","end":"2026-09-18T05:17:37.144012Z","steps":["trace[534186819] 'count revisions from in-memory index tree' (duration: 140.563956ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:37.144936Z","caller":"traceutil/trace.go:171","msg":"trace[576987140] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"129.841273ms","start":"2026-09-18T05:17:37.015077Z","end":"2026-09-18T05:17:37.144918Z","steps":["trace[576987140] 'process raft request' (duration: 127.19172ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:07.827402Z","caller":"traceutil/trace.go:171","msg":"trace[1014953657] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2906; }","duration":"304.010495ms","start":"2026-09-18T05:18:07.523368Z","end":"2026-09-18T05:18:07.827379Z","steps":["trace[1014953657] 'read index received' (duration: 296.350185ms)","trace[1014953657] 'applied index is now lower than readState.Index' (duration: 7.65883ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:18:07.82774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.159386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:18:07.827771Z","caller":"traceutil/trace.go:171","msg":"trace[1240093202] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2798; }","duration":"304.50792ms","start":"2026-09-18T05:18:07.523251Z","end":"2026-09-18T05:18:07.827759Z","steps":["trace[1240093202] 'agreement among raft nodes before linearized reading' (duration: 304.243247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:07.827809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:07.523229Z","time spent":"304.56951ms","remote":"127.0.0.1:60702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-09-18T05:18:07.82783Z","caller":"traceutil/trace.go:171","msg":"trace[1663002504] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"335.616614ms","start":"2026-09-18T05:18:07.492182Z","end":"2026-09-18T05:18:07.827798Z","steps":["trace[1663002504] 'process raft request' (duration: 327.55895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:07.828015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:07.492151Z","time spent":"335.762016ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:18:13.587089Z","caller":"traceutil/trace.go:171","msg":"trace[1861829369] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"342.100932ms","start":"2026-09-18T05:18:13.244954Z","end":"2026-09-18T05:18:13.587055Z","steps":["trace[1861829369] 'process raft request' (duration: 341.9028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:13.587573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:13.244927Z","time spent":"342.267804ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:18:13.75183Z","caller":"traceutil/trace.go:171","msg":"trace[1534010302] linearizableReadLoop","detail":"{readStateIndex:2937; appliedIndex:2935; }","duration":"379.325146ms","start":"2026-09-18T05:18:13.372456Z","end":"2026-09-18T05:18:13.751781Z","steps":["trace[1534010302] 'read index received' (duration: 214.398977ms)","trace[1534010302] 'applied index is now lower than readState.Index' (duration: 164.924389ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:13.752058Z","caller":"traceutil/trace.go:171","msg":"trace[557987409] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"465.477969ms","start":"2026-09-18T05:18:13.286545Z","end":"2026-09-18T05:18:13.752023Z","steps":["trace[557987409] 'process raft request' (duration: 398.300497ms)","trace[557987409] 'compare' (duration: 66.685616ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:18:13.752332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:13.28652Z","time spent":"465.61589ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:18:13.752339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.231328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T05:18:13.752417Z","caller":"traceutil/trace.go:171","msg":"trace[360922267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2827; }","duration":"338.357498ms","start":"2026-09-18T05:18:13.414043Z","end":"2026-09-18T05:18:13.752401Z","steps":["trace[360922267] 'agreement among raft nodes before linearized reading' (duration: 338.126606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:13.752494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:13.414021Z","time spent":"338.43826ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T05:18:13.752573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.042034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:18:13.752686Z","caller":"traceutil/trace.go:171","msg":"trace[59107794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2827; }","duration":"380.200476ms","start":"2026-09-18T05:18:13.372416Z","end":"2026-09-18T05:18:13.752616Z","steps":["trace[59107794] 'agreement among raft nodes before linearized reading' (duration: 379.66399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:13.752776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:13.372397Z","time spent":"380.331327ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T05:19:32.267366Z","caller":"traceutil/trace.go:171","msg":"trace[241278933] transaction","detail":"{read_only:false; response_revision:3239; number_of_response:1; }","duration":"113.980796ms","start":"2026-09-18T05:19:32.153359Z","end":"2026-09-18T05:19:32.26734Z","steps":["trace[241278933] 'process raft request' (duration: 113.81489ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:02.413694Z","caller":"traceutil/trace.go:171","msg":"trace[548279502] transaction","detail":"{read_only:false; response_revision:4188; number_of_response:1; }","duration":"106.387755ms","start":"2026-09-18T05:22:02.307283Z","end":"2026-09-18T05:22:02.413671Z","steps":["trace[548279502] 'process raft request' (duration: 106.168799ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:03.409532Z","caller":"traceutil/trace.go:171","msg":"trace[732684851] linearizableReadLoop","detail":"{readStateIndex:4359; appliedIndex:4358; }","duration":"125.386487ms","start":"2026-09-18T05:22:03.284128Z","end":"2026-09-18T05:22:03.409515Z","steps":["trace[732684851] 'read index received' (duration: 46.461598ms)","trace[732684851] 'applied index is now lower than readState.Index' (duration: 78.924229ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:03.409886Z","caller":"traceutil/trace.go:171","msg":"trace[1590331609] transaction","detail":"{read_only:false; response_revision:4194; number_of_response:1; }","duration":"138.261657ms","start":"2026-09-18T05:22:03.271613Z","end":"2026-09-18T05:22:03.409874Z","steps":["trace[1590331609] 'process raft request' (duration: 59.026031ms)","trace[1590331609] 'compare' (duration: 78.783566ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:22:03.41012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.97353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:22:03.410149Z","caller":"traceutil/trace.go:171","msg":"trace[135623181] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:4194; }","duration":"126.039872ms","start":"2026-09-18T05:22:03.284101Z","end":"2026-09-18T05:22:03.410141Z","steps":["trace[135623181] 'agreement among raft nodes before linearized reading' (duration: 125.96005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:03.410339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.166605ms","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-09-18T05:22:03.410365Z","caller":"traceutil/trace.go:171","msg":"trace[934808997] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:4194; }","duration":"126.204935ms","start":"2026-09-18T05:22:03.284153Z","end":"2026-09-18T05:22:03.410358Z","steps":["trace[934808997] 'agreement among raft nodes before linearized reading' (duration: 126.161234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:03.41125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.701687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:22:03.411278Z","caller":"traceutil/trace.go:171","msg":"trace[1339052977] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:4194; }","duration":"126.743959ms","start":"2026-09-18T05:22:03.284527Z","end":"2026-09-18T05:22:03.411271Z","steps":["trace[1339052977] 'agreement among raft nodes before linearized reading' (duration: 126.704498ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:03.714855Z","caller":"traceutil/trace.go:171","msg":"trace[2046723234] transaction","detail":"{read_only:false; response_revision:4198; number_of_response:1; }","duration":"117.582801ms","start":"2026-09-18T05:22:03.597225Z","end":"2026-09-18T05:22:03.714808Z","steps":["trace[2046723234] 'process raft request' (duration: 93.398075ms)","trace[2046723234] 'compare' (duration: 23.963601ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:06.267233Z","caller":"traceutil/trace.go:171","msg":"trace[1253724550] transaction","detail":"{read_only:false; response_revision:4211; number_of_response:1; }","duration":"142.362747ms","start":"2026-09-18T05:22:06.124843Z","end":"2026-09-18T05:22:06.267205Z","steps":["trace[1253724550] 'process raft request' (duration: 142.164543ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:36.801593Z","caller":"traceutil/trace.go:171","msg":"trace[888773480] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"172.149976ms","start":"2026-09-18T05:22:36.629203Z","end":"2026-09-18T05:22:36.801353Z","steps":["trace[888773480] 'process raft request' (duration: 171.565794ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:36.804994Z","caller":"traceutil/trace.go:171","msg":"trace[304822051] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4937; }","duration":"153.77851ms","start":"2026-09-18T05:22:36.651198Z","end":"2026-09-18T05:22:36.804977Z","steps":["trace[304822051] 'read index received' (duration: 149.555372ms)","trace[304822051] 'applied index is now lower than readState.Index' (duration: 4.222418ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:36.805027Z","caller":"traceutil/trace.go:171","msg":"trace[44628691] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"175.655249ms","start":"2026-09-18T05:22:36.629347Z","end":"2026-09-18T05:22:36.805002Z","steps":["trace[44628691] 'process raft request' (duration: 175.527277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:36.80516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.945643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:22:36.805186Z","caller":"traceutil/trace.go:171","msg":"trace[766926269] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4752; }","duration":"154.013105ms","start":"2026-09-18T05:22:36.651166Z","end":"2026-09-18T05:22:36.805179Z","steps":["trace[766926269] 'agreement among raft nodes before linearized reading' (duration: 153.907103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:36.805306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.045305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T05:22:36.805412Z","caller":"traceutil/trace.go:171","msg":"trace[1272915228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4752; }","duration":"154.164598ms","start":"2026-09-18T05:22:36.651235Z","end":"2026-09-18T05:22:36.805399Z","steps":["trace[1272915228] 'agreement among raft nodes before linearized reading' (duration: 153.893092ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:42.038362Z","caller":"traceutil/trace.go:171","msg":"trace[28196029] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"126.896419ms","start":"2026-09-18T05:22:41.911438Z","end":"2026-09-18T05:22:42.038335Z","steps":["trace[28196029] 'read index received' (duration: 77.887201ms)","trace[28196029] 'applied index is now lower than readState.Index' (duration: 49.008078ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:42.038464Z","caller":"traceutil/trace.go:171","msg":"trace[1434808654] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"130.298319ms","start":"2026-09-18T05:22:41.908141Z","end":"2026-09-18T05:22:42.03844Z","steps":["trace[1434808654] 'process raft request' (duration: 81.26779ms)","trace[1434808654] 'compare' (duration: 48.772263ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:22:42.038693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.237915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-09-18T05:22:42.038736Z","caller":"traceutil/trace.go:171","msg":"trace[1776579996] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4914; }","duration":"127.327137ms","start":"2026-09-18T05:22:41.911395Z","end":"2026-09-18T05:22:42.038722Z","steps":["trace[1776579996] 'agreement among raft nodes before linearized reading' (duration: 127.134554ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:49.004246Z","caller":"traceutil/trace.go:171","msg":"trace[1405957563] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"245.634574ms","start":"2026-09-18T05:22:48.758577Z","end":"2026-09-18T05:22:49.004212Z","steps":["trace[1405957563] 'process raft request' (duration: 245.44047ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:49.057023Z","caller":"traceutil/trace.go:171","msg":"trace[554812932] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5170; }","duration":"230.465711ms","start":"2026-09-18T05:22:48.826497Z","end":"2026-09-18T05:22:49.056962Z","steps":["trace[554812932] 'read index received' (duration: 177.694389ms)","trace[554812932] 'applied index is now lower than readState.Index' (duration: 52.769702ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:49.057217Z","caller":"traceutil/trace.go:171","msg":"trace[1384491335] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"290.762789ms","start":"2026-09-18T05:22:48.766438Z","end":"2026-09-18T05:22:49.057201Z","steps":["trace[1384491335] 'process raft request' (duration: 290.285059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:49.057551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.987422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-18T05:22:49.057625Z","caller":"traceutil/trace.go:171","msg":"trace[1260544946] 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:4978; }","duration":"231.171895ms","start":"2026-09-18T05:22:48.826429Z","end":"2026-09-18T05:22:49.057601Z","steps":["trace[1260544946] 'agreement among raft nodes before linearized reading' (duration: 230.884549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:49.089448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.528838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T05:22:49.089551Z","caller":"traceutil/trace.go:171","msg":"trace[2109859273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4979; }","duration":"226.698442ms","start":"2026-09-18T05:22:48.862832Z","end":"2026-09-18T05:22:49.08953Z","steps":["trace[2109859273] 'agreement among raft nodes before linearized reading' (duration: 226.443647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:49.171245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.667115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cb56e273-4813-4eb6-bc22-ddb582a12ce2\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-18T05:22:49.171388Z","caller":"traceutil/trace.go:171","msg":"trace[857641164] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cb56e273-4813-4eb6-bc22-ddb582a12ce2; range_end:; response_count:1; response_revision:4985; }","duration":"103.88328ms","start":"2026-09-18T05:22:49.067481Z","end":"2026-09-18T05:22:49.171364Z","steps":["trace[857641164] 'agreement among raft nodes before linearized reading' (duration: 103.638834ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:57.394216Z","caller":"traceutil/trace.go:171","msg":"trace[266022385] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"340.721456ms","start":"2026-09-18T05:22:57.053475Z","end":"2026-09-18T05:22:57.394197Z","steps":["trace[266022385] 'process raft request' (duration: 340.623125ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:57.394388Z","caller":"traceutil/trace.go:171","msg":"trace[342222215] linearizableReadLoop","detail":"{readStateIndex:5345; appliedIndex:5345; }","duration":"328.819585ms","start":"2026-09-18T05:22:57.065551Z","end":"2026-09-18T05:22:57.394371Z","steps":["trace[342222215] 'read index received' (duration: 328.810235ms)","trace[342222215] 'applied index is now lower than readState.Index' (duration: 7.91µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:22:57.394478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:22:57.053458Z","time spent":"340.817838ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:22:57.394655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.09464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5615708e-1aa3-473c-b525-f07fbf964777\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T05:22:57.394723Z","caller":"traceutil/trace.go:171","msg":"trace[1197727107] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5615708e-1aa3-473c-b525-f07fbf964777; range_end:; response_count:1; response_revision:5147; }","duration":"329.208544ms","start":"2026-09-18T05:22:57.065501Z","end":"2026-09-18T05:22:57.394709Z","steps":["trace[1197727107] 'agreement among raft nodes before linearized reading' (duration: 328.990349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:57.394775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:22:57.065477Z","time spent":"329.288346ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5615708e-1aa3-473c-b525-f07fbf964777\" "} {"level":"info","ts":"2026-09-18T05:22:57.406734Z","caller":"traceutil/trace.go:171","msg":"trace[1914174310] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"205.741008ms","start":"2026-09-18T05:22:57.20098Z","end":"2026-09-18T05:22:57.406721Z","steps":["trace[1914174310] 'process raft request' (duration: 205.626616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:57.406974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.286179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5615708e-1aa3-473c-b525-f07fbf964777\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T05:22:57.40702Z","caller":"traceutil/trace.go:171","msg":"trace[472667748] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5615708e-1aa3-473c-b525-f07fbf964777; range_end:; response_count:1; response_revision:5149; }","duration":"254.36989ms","start":"2026-09-18T05:22:57.152642Z","end":"2026-09-18T05:22:57.407012Z","steps":["trace[472667748] 'agreement among raft nodes before linearized reading' (duration: 254.201547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:57.407203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.704623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-18T05:22:57.407224Z","caller":"traceutil/trace.go:171","msg":"trace[173263773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5149; }","duration":"185.791204ms","start":"2026-09-18T05:22:57.221427Z","end":"2026-09-18T05:22:57.407219Z","steps":["trace[173263773] 'agreement among raft nodes before linearized reading' (duration: 185.730313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:57.407626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.950822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-18T05:22:57.40765Z","caller":"traceutil/trace.go:171","msg":"trace[724336126] 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:5149; }","duration":"252.257859ms","start":"2026-09-18T05:22:57.155384Z","end":"2026-09-18T05:22:57.407642Z","steps":["trace[724336126] 'agreement among raft nodes before linearized reading' (duration: 251.910742ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:03.594308Z","caller":"traceutil/trace.go:171","msg":"trace[1166756931] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"167.261667ms","start":"2026-09-18T05:23:03.426859Z","end":"2026-09-18T05:23:03.59412Z","steps":["trace[1166756931] 'process raft request' (duration: 166.112295ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:03.635353Z","caller":"traceutil/trace.go:171","msg":"trace[183602085] linearizableReadLoop","detail":"{readStateIndex:5422; appliedIndex:5419; }","duration":"110.848259ms","start":"2026-09-18T05:23:03.524482Z","end":"2026-09-18T05:23:03.63533Z","steps":["trace[183602085] 'read index received' (duration: 68.158474ms)","trace[183602085] 'applied index is now lower than readState.Index' (duration: 42.688545ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:03.635602Z","caller":"traceutil/trace.go:171","msg":"trace[377632995] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"206.512312ms","start":"2026-09-18T05:23:03.429068Z","end":"2026-09-18T05:23:03.63558Z","steps":["trace[377632995] 'process raft request' (duration: 191.613913ms)","trace[377632995] 'compare' (duration: 14.476781ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:03.635677Z","caller":"traceutil/trace.go:171","msg":"trace[2133797611] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"114.45248ms","start":"2026-09-18T05:23:03.52121Z","end":"2026-09-18T05:23:03.635662Z","steps":["trace[2133797611] 'process raft request' (duration: 114.062983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:03.63572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.209916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T05:23:03.635801Z","caller":"traceutil/trace.go:171","msg":"trace[1889027572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5222; }","duration":"111.336838ms","start":"2026-09-18T05:23:03.524446Z","end":"2026-09-18T05:23:03.635783Z","steps":["trace[1889027572] 'agreement among raft nodes before linearized reading' (duration: 111.094703ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:21.128069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2868} {"level":"info","ts":"2026-09-18T05:23:21.208354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2868,"took":"78.994686ms","hash":3968471813,"current-db-size-bytes":52400128,"current-db-size":"52 MB","current-db-size-in-use-bytes":27529216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T05:23:21.208417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3968471813,"revision":2868,"compact-revision":-1} {"level":"info","ts":"2026-09-18T05:23:38.099457Z","caller":"traceutil/trace.go:171","msg":"trace[575035352] linearizableReadLoop","detail":"{readStateIndex:5740; appliedIndex:5739; }","duration":"257.041915ms","start":"2026-09-18T05:23:37.842376Z","end":"2026-09-18T05:23:38.099418Z","steps":["trace[575035352] 'read index received' (duration: 178.84604ms)","trace[575035352] 'applied index is now lower than readState.Index' (duration: 78.194185ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:38.099626Z","caller":"traceutil/trace.go:171","msg":"trace[440648663] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"289.835729ms","start":"2026-09-18T05:23:37.809744Z","end":"2026-09-18T05:23:38.09958Z","steps":["trace[440648663] 'process raft request' (duration: 211.531632ms)","trace[440648663] 'compare' (duration: 77.88166ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:38.099729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.289721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:23:38.099812Z","caller":"traceutil/trace.go:171","msg":"trace[2053078393] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5531; }","duration":"257.389563ms","start":"2026-09-18T05:23:37.842366Z","end":"2026-09-18T05:23:38.099755Z","steps":["trace[2053078393] 'agreement among raft nodes before linearized reading' (duration: 257.25397ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:39.523411Z","caller":"traceutil/trace.go:171","msg":"trace[8451245] transaction","detail":"{read_only:false; response_revision:5978; number_of_response:1; }","duration":"182.554035ms","start":"2026-09-18T05:24:39.340824Z","end":"2026-09-18T05:24:39.523378Z","steps":["trace[8451245] 'process raft request' (duration: 146.773339ms)","trace[8451245] 'compare' (duration: 35.42808ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:39.523622Z","caller":"traceutil/trace.go:171","msg":"trace[1526131227] linearizableReadLoop","detail":"{readStateIndex:6201; appliedIndex:6199; }","duration":"149.233292ms","start":"2026-09-18T05:24:39.374322Z","end":"2026-09-18T05:24:39.523556Z","steps":["trace[1526131227] 'read index received' (duration: 113.203751ms)","trace[1526131227] 'applied index is now lower than readState.Index' (duration: 36.027681ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:39.523773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.436365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:39.523816Z","caller":"traceutil/trace.go:171","msg":"trace[1014562713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5979; }","duration":"149.528427ms","start":"2026-09-18T05:24:39.374273Z","end":"2026-09-18T05:24:39.523802Z","steps":["trace[1014562713] 'agreement among raft nodes before linearized reading' (duration: 149.406415ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:39.523805Z","caller":"traceutil/trace.go:171","msg":"trace[746024728] transaction","detail":"{read_only:false; response_revision:5979; number_of_response:1; }","duration":"181.09887ms","start":"2026-09-18T05:24:39.342658Z","end":"2026-09-18T05:24:39.523756Z","steps":["trace[746024728] 'process raft request' (duration: 180.599281ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:40.253669Z","caller":"traceutil/trace.go:171","msg":"trace[15657860] transaction","detail":"{read_only:false; response_revision:5986; number_of_response:1; }","duration":"119.406559ms","start":"2026-09-18T05:24:40.134239Z","end":"2026-09-18T05:24:40.253645Z","steps":["trace[15657860] 'process raft request' (duration: 119.270397ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:40.311735Z","caller":"traceutil/trace.go:171","msg":"trace[1907511470] transaction","detail":"{read_only:false; response_revision:5987; number_of_response:1; }","duration":"141.384541ms","start":"2026-09-18T05:24:40.170314Z","end":"2026-09-18T05:24:40.311699Z","steps":["trace[1907511470] 'process raft request' (duration: 118.001416ms)","trace[1907511470] 'compare' (duration: 23.156551ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:40.707427Z","caller":"traceutil/trace.go:171","msg":"trace[1195013021] transaction","detail":"{read_only:false; response_revision:5990; number_of_response:1; }","duration":"151.599842ms","start":"2026-09-18T05:24:40.555793Z","end":"2026-09-18T05:24:40.707393Z","steps":["trace[1195013021] 'process raft request' (duration: 128.274498ms)","trace[1195013021] 'compare' (duration: 23.013328ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:40.707416Z","caller":"traceutil/trace.go:171","msg":"trace[565453033] transaction","detail":"{read_only:false; response_revision:5991; number_of_response:1; }","duration":"151.487171ms","start":"2026-09-18T05:24:40.555901Z","end":"2026-09-18T05:24:40.707388Z","steps":["trace[565453033] 'process raft request' (duration: 151.359278ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:40.707586Z","caller":"traceutil/trace.go:171","msg":"trace[1040358932] transaction","detail":"{read_only:false; response_revision:5992; number_of_response:1; }","duration":"151.579482ms","start":"2026-09-18T05:24:40.555962Z","end":"2026-09-18T05:24:40.707541Z","steps":["trace[1040358932] 'process raft request' (duration: 151.351267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.615283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.404921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:42.615373Z","caller":"traceutil/trace.go:171","msg":"trace[733465027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6001; }","duration":"245.554714ms","start":"2026-09-18T05:24:42.369797Z","end":"2026-09-18T05:24:42.615351Z","steps":["trace[733465027] 'range keys from in-memory index tree' (duration: 245.329778ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.835049Z","caller":"traceutil/trace.go:171","msg":"trace[31100173] transaction","detail":"{read_only:false; response_revision:6005; number_of_response:1; }","duration":"117.024345ms","start":"2026-09-18T05:24:42.717992Z","end":"2026-09-18T05:24:42.835017Z","steps":["trace[31100173] 'process raft request' (duration: 116.824062ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.835293Z","caller":"traceutil/trace.go:171","msg":"trace[225074776] transaction","detail":"{read_only:false; response_revision:6006; number_of_response:1; }","duration":"115.515738ms","start":"2026-09-18T05:24:42.719731Z","end":"2026-09-18T05:24:42.835247Z","steps":["trace[225074776] 'process raft request' (duration: 115.236943ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:44.320728Z","caller":"traceutil/trace.go:171","msg":"trace[786960369] linearizableReadLoop","detail":"{readStateIndex:6233; appliedIndex:6232; }","duration":"105.274972ms","start":"2026-09-18T05:24:44.215401Z","end":"2026-09-18T05:24:44.320676Z","steps":["trace[786960369] 'read index received' (duration: 40.336893ms)","trace[786960369] 'applied index is now lower than readState.Index' (duration: 64.936959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:44.320859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.434335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:24:44.320845Z","caller":"traceutil/trace.go:171","msg":"trace[1753241833] transaction","detail":"{read_only:false; response_revision:6010; number_of_response:1; }","duration":"146.807567ms","start":"2026-09-18T05:24:44.173979Z","end":"2026-09-18T05:24:44.320787Z","steps":["trace[1753241833] 'process raft request' (duration: 81.852428ms)","trace[1753241833] 'compare' (duration: 64.719355ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:44.320897Z","caller":"traceutil/trace.go:171","msg":"trace[2081130918] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:6010; }","duration":"105.521107ms","start":"2026-09-18T05:24:44.215362Z","end":"2026-09-18T05:24:44.320883Z","steps":["trace[2081130918] 'agreement among raft nodes before linearized reading' (duration: 105.432286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:45.01662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.730905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:24:45.016728Z","caller":"traceutil/trace.go:171","msg":"trace[210585074] linearizableReadLoop","detail":"{readStateIndex:6238; appliedIndex:6237; }","duration":"176.85523ms","start":"2026-09-18T05:24:44.839854Z","end":"2026-09-18T05:24:45.016709Z","steps":["trace[210585074] 'read index received' (duration: 41.85493ms)","trace[210585074] 'applied index is now lower than readState.Index' (duration: 134.998629ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:45.016832Z","caller":"traceutil/trace.go:171","msg":"trace[1624393506] transaction","detail":"{read_only:false; response_revision:6015; number_of_response:1; }","duration":"211.256699ms","start":"2026-09-18T05:24:44.805559Z","end":"2026-09-18T05:24:45.016815Z","steps":["trace[1624393506] 'process raft request' (duration: 76.202479ms)","trace[1624393506] 'compare' (duration: 134.614073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:45.017153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.300498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-18T05:24:45.017214Z","caller":"traceutil/trace.go:171","msg":"trace[949601065] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6015; }","duration":"177.397949ms","start":"2026-09-18T05:24:44.839797Z","end":"2026-09-18T05:24:45.017195Z","steps":["trace[949601065] 'agreement among raft nodes before linearized reading' (duration: 177.241606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:45.017291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.808748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:45.017564Z","caller":"traceutil/trace.go:171","msg":"trace[1741532416] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:6015; }","duration":"151.123324ms","start":"2026-09-18T05:24:44.86643Z","end":"2026-09-18T05:24:45.017553Z","steps":["trace[1741532416] 'agreement among raft nodes before linearized reading' (duration: 150.826359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:45.017459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.678686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:24:45.017697Z","caller":"traceutil/trace.go:171","msg":"trace[266314976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6015; }","duration":"176.940071ms","start":"2026-09-18T05:24:44.840749Z","end":"2026-09-18T05:24:45.017689Z","steps":["trace[266314976] 'agreement among raft nodes before linearized reading' (duration: 176.483514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:45.018136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.705052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:24:45.01821Z","caller":"traceutil/trace.go:171","msg":"trace[1198287067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6015; }","duration":"153.487736ms","start":"2026-09-18T05:24:44.864703Z","end":"2026-09-18T05:24:45.018191Z","steps":["trace[1198287067] 'agreement among raft nodes before linearized reading' (duration: 152.604321ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:45.217331Z","caller":"traceutil/trace.go:171","msg":"trace[61276978] transaction","detail":"{read_only:false; response_revision:6016; number_of_response:1; }","duration":"195.690119ms","start":"2026-09-18T05:24:45.021609Z","end":"2026-09-18T05:24:45.2173Z","steps":["trace[61276978] 'process raft request' (duration: 117.959145ms)","trace[61276978] 'compare' (duration: 77.317637ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:45.217942Z","caller":"traceutil/trace.go:171","msg":"trace[2037214796] transaction","detail":"{read_only:false; response_revision:6017; number_of_response:1; }","duration":"196.23023ms","start":"2026-09-18T05:24:45.021692Z","end":"2026-09-18T05:24:45.217923Z","steps":["trace[2037214796] 'process raft request' (duration: 195.736971ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:45.218114Z","caller":"traceutil/trace.go:171","msg":"trace[1393547949] transaction","detail":"{read_only:false; response_revision:6018; number_of_response:1; }","duration":"196.174619ms","start":"2026-09-18T05:24:45.02191Z","end":"2026-09-18T05:24:45.218084Z","steps":["trace[1393547949] 'process raft request' (duration: 195.890574ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:45.449546Z","caller":"traceutil/trace.go:171","msg":"trace[811230709] linearizableReadLoop","detail":"{readStateIndex:6242; appliedIndex:6241; }","duration":"119.701896ms","start":"2026-09-18T05:24:45.329823Z","end":"2026-09-18T05:24:45.449525Z","steps":["trace[811230709] 'read index received' (duration: 57.051208ms)","trace[811230709] 'applied index is now lower than readState.Index' (duration: 62.649938ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:45.44959Z","caller":"traceutil/trace.go:171","msg":"trace[1829539200] transaction","detail":"{read_only:false; response_revision:6019; number_of_response:1; }","duration":"134.629371ms","start":"2026-09-18T05:24:45.314936Z","end":"2026-09-18T05:24:45.449565Z","steps":["trace[1829539200] 'process raft request' (duration: 71.986783ms)","trace[1829539200] 'compare' (duration: 62.467224ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:45.449899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.055882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-18T05:24:45.450038Z","caller":"traceutil/trace.go:171","msg":"trace[1685246851] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6019; }","duration":"120.237116ms","start":"2026-09-18T05:24:45.329792Z","end":"2026-09-18T05:24:45.450029Z","steps":["trace[1685246851] 'agreement among raft nodes before linearized reading' (duration: 119.843169ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:45.626408Z","caller":"traceutil/trace.go:171","msg":"trace[394770126] linearizableReadLoop","detail":"{readStateIndex:6243; appliedIndex:6242; }","duration":"118.408084ms","start":"2026-09-18T05:24:45.507969Z","end":"2026-09-18T05:24:45.626377Z","steps":["trace[394770126] 'read index received' (duration: 50.024985ms)","trace[394770126] 'applied index is now lower than readState.Index' (duration: 68.381569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:45.62675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.768141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:45.626843Z","caller":"traceutil/trace.go:171","msg":"trace[477387001] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6019; }","duration":"118.907714ms","start":"2026-09-18T05:24:45.507917Z","end":"2026-09-18T05:24:45.626825Z","steps":["trace[477387001] 'agreement among raft nodes before linearized reading' (duration: 118.696459ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:46.441138Z","caller":"traceutil/trace.go:171","msg":"trace[2025740618] transaction","detail":"{read_only:false; response_revision:6023; number_of_response:1; }","duration":"114.127877ms","start":"2026-09-18T05:24:46.326981Z","end":"2026-09-18T05:24:46.441109Z","steps":["trace[2025740618] 'process raft request' (duration: 113.862482ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:50.366815Z","caller":"traceutil/trace.go:171","msg":"trace[816868784] transaction","detail":"{read_only:false; response_revision:6039; number_of_response:1; }","duration":"109.041031ms","start":"2026-09-18T05:24:50.257719Z","end":"2026-09-18T05:24:50.36676Z","steps":["trace[816868784] 'process raft request' (duration: 108.768885ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:52.597317Z","caller":"traceutil/trace.go:171","msg":"trace[613411259] transaction","detail":"{read_only:false; response_revision:6050; number_of_response:1; }","duration":"125.187321ms","start":"2026-09-18T05:24:52.472097Z","end":"2026-09-18T05:24:52.597284Z","steps":["trace[613411259] 'process raft request' (duration: 124.982407ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:52.623935Z","caller":"traceutil/trace.go:171","msg":"trace[1559603287] transaction","detail":"{read_only:false; response_revision:6051; number_of_response:1; }","duration":"119.336398ms","start":"2026-09-18T05:24:52.504578Z","end":"2026-09-18T05:24:52.623914Z","steps":["trace[1559603287] 'process raft request' (duration: 119.231746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:53.460948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4251574738611592616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T05:24:53.521773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.394606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-18T05:24:53.521972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.795873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T05:24:53.522031Z","caller":"traceutil/trace.go:171","msg":"trace[334252554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6051; }","duration":"655.891545ms","start":"2026-09-18T05:24:52.866125Z","end":"2026-09-18T05:24:53.522017Z","steps":["trace[334252554] 'range keys from in-memory index tree' (duration: 655.666051ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:53.522121Z","caller":"traceutil/trace.go:171","msg":"trace[497525542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6051; }","duration":"614.673491ms","start":"2026-09-18T05:24:52.907313Z","end":"2026-09-18T05:24:53.521986Z","steps":["trace[497525542] 'range keys from in-memory index tree' (duration: 614.220943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:53.522218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:52.907294Z","time spent":"614.906125ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T05:24:53.52213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:52.866107Z","time spent":"656.002727ms","remote":"127.0.0.1:39386","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-09-18T05:24:53.522885Z","caller":"traceutil/trace.go:171","msg":"trace[674744179] linearizableReadLoop","detail":"{readStateIndex:6277; appliedIndex:6276; }","duration":"562.88845ms","start":"2026-09-18T05:24:52.95998Z","end":"2026-09-18T05:24:53.522868Z","steps":["trace[674744179] 'read index received' (duration: 531.860425ms)","trace[674744179] 'applied index is now lower than readState.Index' (duration: 31.027175ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:53.522988Z","caller":"traceutil/trace.go:171","msg":"trace[1675701396] transaction","detail":"{read_only:false; response_revision:6052; number_of_response:1; }","duration":"568.223883ms","start":"2026-09-18T05:24:52.954721Z","end":"2026-09-18T05:24:53.522945Z","steps":["trace[1675701396] 'process raft request' (duration: 537.173788ms)","trace[1675701396] 'compare' (duration: 30.861402ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:53.523058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.072583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:24:53.523229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:52.9547Z","time spent":"568.415387ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:53.523405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.654597ms","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-09-18T05:24:53.523464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.228499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:53.523489Z","caller":"traceutil/trace.go:171","msg":"trace[1945092337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6052; }","duration":"133.745959ms","start":"2026-09-18T05:24:53.389725Z","end":"2026-09-18T05:24:53.523471Z","steps":["trace[1945092337] 'agreement among raft nodes before linearized reading' (duration: 133.611207ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:53.523516Z","caller":"traceutil/trace.go:171","msg":"trace[1943269182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6052; }","duration":"150.31064ms","start":"2026-09-18T05:24:53.37319Z","end":"2026-09-18T05:24:53.523501Z","steps":["trace[1943269182] 'agreement among raft nodes before linearized reading' (duration: 150.199618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:53.523556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.389561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-18T05:24:53.52327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.324992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:24:53.523615Z","caller":"traceutil/trace.go:171","msg":"trace[1570403716] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6052; }","duration":"183.489983ms","start":"2026-09-18T05:24:53.340105Z","end":"2026-09-18T05:24:53.523595Z","steps":["trace[1570403716] 'agreement among raft nodes before linearized reading' (duration: 183.3019ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:53.523629Z","caller":"traceutil/trace.go:171","msg":"trace[955514100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6052; }","duration":"314.738399ms","start":"2026-09-18T05:24:53.208878Z","end":"2026-09-18T05:24:53.523616Z","steps":["trace[955514100] 'agreement among raft nodes before linearized reading' (duration: 314.22688ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:53.523198Z","caller":"traceutil/trace.go:171","msg":"trace[527681201] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:6052; }","duration":"563.223956ms","start":"2026-09-18T05:24:52.959921Z","end":"2026-09-18T05:24:53.523145Z","steps":["trace[527681201] 'agreement among raft nodes before linearized reading' (duration: 563.046843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:53.523737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:53.208861Z","time spent":"314.78618ms","remote":"127.0.0.1:39386","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-09-18T05:24:53.523739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:52.959897Z","time spent":"563.822727ms","remote":"127.0.0.1:45416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:24:53.524344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.350785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:24:53.524395Z","caller":"traceutil/trace.go:171","msg":"trace[407477303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6052; }","duration":"136.663601ms","start":"2026-09-18T05:24:53.387718Z","end":"2026-09-18T05:24:53.524382Z","steps":["trace[407477303] 'agreement among raft nodes before linearized reading' (duration: 135.593102ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:54.753322Z","caller":"traceutil/trace.go:171","msg":"trace[557676408] transaction","detail":"{read_only:false; response_revision:6061; number_of_response:1; }","duration":"123.547628ms","start":"2026-09-18T05:24:54.629728Z","end":"2026-09-18T05:24:54.753275Z","steps":["trace[557676408] 'process raft request' (duration: 123.254523ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:55.672933Z","caller":"traceutil/trace.go:171","msg":"trace[1332190065] transaction","detail":"{read_only:false; response_revision:6065; number_of_response:1; }","duration":"122.983367ms","start":"2026-09-18T05:24:55.549922Z","end":"2026-09-18T05:24:55.672906Z","steps":["trace[1332190065] 'process raft request' (duration: 59.759837ms)","trace[1332190065] 'compare' (duration: 62.837813ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:55.875692Z","caller":"traceutil/trace.go:171","msg":"trace[1753970010] transaction","detail":"{read_only:false; response_revision:6067; number_of_response:1; }","duration":"198.720826ms","start":"2026-09-18T05:24:55.676943Z","end":"2026-09-18T05:24:55.875664Z","steps":["trace[1753970010] 'process raft request' (duration: 123.492496ms)","trace[1753970010] 'compare' (duration: 75.048807ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:55.876072Z","caller":"traceutil/trace.go:171","msg":"trace[1337085742] transaction","detail":"{read_only:false; response_revision:6068; number_of_response:1; }","duration":"198.731386ms","start":"2026-09-18T05:24:55.677292Z","end":"2026-09-18T05:24:55.876024Z","steps":["trace[1337085742] 'process raft request' (duration: 198.323749ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:55.876242Z","caller":"traceutil/trace.go:171","msg":"trace[1717056387] transaction","detail":"{read_only:false; response_revision:6069; number_of_response:1; }","duration":"198.627204ms","start":"2026-09-18T05:24:55.677584Z","end":"2026-09-18T05:24:55.876211Z","steps":["trace[1717056387] 'process raft request' (duration: 198.305449ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:55.876326Z","caller":"traceutil/trace.go:171","msg":"trace[633189234] transaction","detail":"{read_only:false; response_revision:6070; number_of_response:1; }","duration":"198.477681ms","start":"2026-09-18T05:24:55.677829Z","end":"2026-09-18T05:24:55.876307Z","steps":["trace[633189234] 'process raft request' (duration: 198.242457ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:55.876282Z","caller":"traceutil/trace.go:171","msg":"trace[430892788] linearizableReadLoop","detail":"{readStateIndex:6296; appliedIndex:6292; }","duration":"100.408281ms","start":"2026-09-18T05:24:55.775853Z","end":"2026-09-18T05:24:55.876261Z","steps":["trace[430892788] 'read index received' (duration: 24.51465ms)","trace[430892788] 'applied index is now lower than readState.Index' (duration: 75.891771ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:55.876624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.757708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-18T05:24:55.876733Z","caller":"traceutil/trace.go:171","msg":"trace[984358836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6070; }","duration":"100.87876ms","start":"2026-09-18T05:24:55.775837Z","end":"2026-09-18T05:24:55.876716Z","steps":["trace[984358836] 'agreement among raft nodes before linearized reading' (duration: 100.523174ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:56.752878Z","caller":"traceutil/trace.go:171","msg":"trace[99113644] transaction","detail":"{read_only:false; response_revision:6073; number_of_response:1; }","duration":"104.584002ms","start":"2026-09-18T05:24:56.64825Z","end":"2026-09-18T05:24:56.752834Z","steps":["trace[99113644] 'process raft request' (duration: 12.705252ms)","trace[99113644] 'compare' (duration: 91.565255ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:01.476144Z","caller":"traceutil/trace.go:171","msg":"trace[1418104050] linearizableReadLoop","detail":"{readStateIndex:6651; appliedIndex:6650; }","duration":"103.710376ms","start":"2026-09-18T05:26:01.372414Z","end":"2026-09-18T05:26:01.476124Z","steps":["trace[1418104050] 'read index received' (duration: 103.551393ms)","trace[1418104050] 'applied index is now lower than readState.Index' (duration: 158.213µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:01.476379Z","caller":"traceutil/trace.go:171","msg":"trace[1491371050] transaction","detail":"{read_only:false; response_revision:6411; number_of_response:1; }","duration":"148.56294ms","start":"2026-09-18T05:26:01.327802Z","end":"2026-09-18T05:26:01.476365Z","steps":["trace[1491371050] 'process raft request' (duration: 148.198913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:01.476249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.817458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:26:01.476524Z","caller":"traceutil/trace.go:171","msg":"trace[567204570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6411; }","duration":"104.113143ms","start":"2026-09-18T05:26:01.372385Z","end":"2026-09-18T05:26:01.476499Z","steps":["trace[567204570] 'agreement among raft nodes before linearized reading' (duration: 103.822167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:05.769842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.338269ms","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-09-18T05:26:05.76992Z","caller":"traceutil/trace.go:171","msg":"trace[438616721] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:6451; }","duration":"199.472241ms","start":"2026-09-18T05:26:05.570428Z","end":"2026-09-18T05:26:05.7699Z","steps":["trace[438616721] 'count revisions from in-memory index tree' (duration: 199.175046ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:06.468276Z","caller":"traceutil/trace.go:171","msg":"trace[1565711773] transaction","detail":"{read_only:false; response_revision:6458; number_of_response:1; }","duration":"102.261757ms","start":"2026-09-18T05:26:06.365981Z","end":"2026-09-18T05:26:06.468243Z","steps":["trace[1565711773] 'process raft request' (duration: 88.602339ms)","trace[1565711773] 'compare' (duration: 13.408934ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:52.775327Z","caller":"traceutil/trace.go:171","msg":"trace[39797191] linearizableReadLoop","detail":"{readStateIndex:7112; appliedIndex:7111; }","duration":"161.875637ms","start":"2026-09-18T05:26:52.613427Z","end":"2026-09-18T05:26:52.775302Z","steps":["trace[39797191] 'read index received' (duration: 161.543953ms)","trace[39797191] 'applied index is now lower than readState.Index' (duration: 330.264µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:26:52.775546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.097796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T05:26:52.775585Z","caller":"traceutil/trace.go:171","msg":"trace[22715276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6857; }","duration":"162.19303ms","start":"2026-09-18T05:26:52.613382Z","end":"2026-09-18T05:26:52.775575Z","steps":["trace[22715276] 'agreement among raft nodes before linearized reading' (duration: 162.098906ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:52.775433Z","caller":"traceutil/trace.go:171","msg":"trace[1187741858] transaction","detail":"{read_only:false; response_revision:6857; number_of_response:1; }","duration":"166.028619ms","start":"2026-09-18T05:26:52.60937Z","end":"2026-09-18T05:26:52.775399Z","steps":["trace[1187741858] 'process raft request' (duration: 165.730386ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:56.662195Z","caller":"traceutil/trace.go:171","msg":"trace[770153018] transaction","detail":"{read_only:false; response_revision:6877; number_of_response:1; }","duration":"453.682598ms","start":"2026-09-18T05:26:56.208485Z","end":"2026-09-18T05:26:56.662168Z","steps":["trace[770153018] 'process raft request' (duration: 453.441449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:56.662367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:26:56.208462Z","time spent":"453.805223ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:26:56.662741Z","caller":"traceutil/trace.go:171","msg":"trace[1178160701] linearizableReadLoop","detail":"{readStateIndex:7133; appliedIndex:7132; }","duration":"204.001597ms","start":"2026-09-18T05:26:56.458724Z","end":"2026-09-18T05:26:56.662725Z","steps":["trace[1178160701] 'read index received' (duration: 203.056109ms)","trace[1178160701] 'applied index is now lower than readState.Index' (duration: 943.938µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:26:56.662918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.189245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T05:26:56.662955Z","caller":"traceutil/trace.go:171","msg":"trace[726833051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6877; }","duration":"204.262578ms","start":"2026-09-18T05:26:56.458682Z","end":"2026-09-18T05:26:56.662945Z","steps":["trace[726833051] 'agreement among raft nodes before linearized reading' (duration: 204.134433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:56.663037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.72263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:26:56.663092Z","caller":"traceutil/trace.go:171","msg":"trace[1846588704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6877; }","duration":"144.805094ms","start":"2026-09-18T05:26:56.518271Z","end":"2026-09-18T05:26:56.663076Z","steps":["trace[1846588704] 'agreement among raft nodes before linearized reading' (duration: 144.645458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:13.850709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.005955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:27:13.851287Z","caller":"traceutil/trace.go:171","msg":"trace[424465339] linearizableReadLoop","detail":"{readStateIndex:7284; appliedIndex:7283; }","duration":"176.783316ms","start":"2026-09-18T05:27:13.674478Z","end":"2026-09-18T05:27:13.851261Z","steps":["trace[424465339] 'read index received' (duration: 14.071253ms)","trace[424465339] 'applied index is now lower than readState.Index' (duration: 162.707822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:27:13.851513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.002615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-18T05:27:13.851565Z","caller":"traceutil/trace.go:171","msg":"trace[227701543] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7022; }","duration":"177.120509ms","start":"2026-09-18T05:27:13.674434Z","end":"2026-09-18T05:27:13.851554Z","steps":["trace[227701543] 'agreement among raft nodes before linearized reading' (duration: 176.915311ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:27:13.851935Z","caller":"traceutil/trace.go:171","msg":"trace[1452066014] transaction","detail":"{read_only:false; response_revision:7022; number_of_response:1; }","duration":"245.033441ms","start":"2026-09-18T05:27:13.606297Z","end":"2026-09-18T05:27:13.851331Z","steps":["trace[1452066014] 'process raft request' (duration: 82.327169ms)","trace[1452066014] 'compare' (duration: 161.309188ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:27:23.084056Z","caller":"traceutil/trace.go:171","msg":"trace[1614832921] transaction","detail":"{read_only:false; response_revision:7062; number_of_response:1; }","duration":"115.475215ms","start":"2026-09-18T05:27:22.968552Z","end":"2026-09-18T05:27:23.084028Z","steps":["trace[1614832921] 'process raft request' (duration: 115.279267ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:28:21.134306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5327} {"level":"info","ts":"2026-09-18T05:28:21.225147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5327,"took":"89.068394ms","hash":675665963,"current-db-size-bytes":52400128,"current-db-size":"52 MB","current-db-size-in-use-bytes":18640896,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T05:28:21.225219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":675665963,"revision":5327,"compact-revision":2868}