{"level":"warn","ts":"2026-09-18T05:09:05.373123Z","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:09:05.373264Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.59: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.59:2380","--initial-cluster=instance=https://162.253.55.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.59: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:09:05.373457Z","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:09:05.373477Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-18T05:09:05.373539Z","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:09:05.374427Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"]} {"level":"info","ts":"2026-09-18T05:09:05.374637Z","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.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.59: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:09:05.381483Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.427951ms"} {"level":"info","ts":"2026-09-18T05:09:05.390037Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f5132dc9ac390b66","cluster-id":"458d1cb56ab56fc4"} {"level":"info","ts":"2026-09-18T05:09:05.390219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:09:05.390254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:09:05.390289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f5132dc9ac390b66 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:09:05.390299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:09:05.390378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"warn","ts":"2026-09-18T05:09:05.400926Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:09:05.402295Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:09:05.404789Z","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:09:05.406814Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f5132dc9ac390b66","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:09:05.407167Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f5132dc9ac390b66","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:09:05.407278Z","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:09:05.407434Z","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:09:05.407455Z","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:09:05.409417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"info","ts":"2026-09-18T05:09:05.409728Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","added-peer-id":"f5132dc9ac390b66","added-peer-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-18T05:09:05.412981Z","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:09:05.413332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f5132dc9ac390b66","initial-advertise-peer-urls":["https://162.253.55.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:09:05.413538Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:09:05.414463Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-18T05:09:05.414497Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-18T05:09:06.19159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:09:06.191683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:09:06.1918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgPreVoteResp from f5132dc9ac390b66 at term 1"} {"level":"info","ts":"2026-09-18T05:09:06.19183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:09:06.191846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgVoteResp from f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-18T05:09:06.191863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:09:06.191894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f5132dc9ac390b66 elected leader f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-18T05:09:06.315047Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f5132dc9ac390b66","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.59:2379]}","request-path":"/0/members/f5132dc9ac390b66/attributes","cluster-id":"458d1cb56ab56fc4","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:09:06.315161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:09:06.315454Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:09:06.315047Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:09:06.315525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:09:06.315615Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:09:06.31727Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:09:06.317334Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:09:06.317353Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:09:06.319364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.59:2379"} {"level":"info","ts":"2026-09-18T05:09:06.320291Z","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:09:09.624448Z","caller":"traceutil/trace.go:171","msg":"trace[1500184739] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"125.668626ms","start":"2026-09-18T05:09:09.498589Z","end":"2026-09-18T05:09:09.624257Z","steps":["trace[1500184739] 'process raft request' (duration: 81.679071ms)","trace[1500184739] 'compare' (duration: 43.68305ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:09:14.882214Z","caller":"traceutil/trace.go:171","msg":"trace[1380049029] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"125.815428ms","start":"2026-09-18T05:09:14.756364Z","end":"2026-09-18T05:09:14.88218Z","steps":["trace[1380049029] 'process raft request' (duration: 125.570234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:09:15.207392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.943905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:09:15.207533Z","caller":"traceutil/trace.go:171","msg":"trace[1587506468] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"302.073478ms","start":"2026-09-18T05:09:14.905436Z","end":"2026-09-18T05:09:15.207509Z","steps":["trace[1587506468] 'process raft request' (duration: 147.407756ms)","trace[1587506468] 'compare' (duration: 153.771415ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:09:15.207591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:09:14.905419Z","time spent":"302.150763ms","remote":"127.0.0.1:46446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:09:15.521123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.044278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:09:15.521266Z","caller":"traceutil/trace.go:171","msg":"trace[648909771] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"302.24329ms","start":"2026-09-18T05:09:15.218996Z","end":"2026-09-18T05:09:15.521239Z","steps":["trace[648909771] 'process raft request' (duration: 158.954289ms)","trace[648909771] 'compare' (duration: 142.841758ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:09:15.521341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:09:15.218979Z","time spent":"302.334295ms","remote":"127.0.0.1:46446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:09:52.02904Z","caller":"traceutil/trace.go:171","msg":"trace[1220830830] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"140.909491ms","start":"2026-09-18T05:09:51.888096Z","end":"2026-09-18T05:09:52.029005Z","steps":["trace[1220830830] 'process raft request' (duration: 140.725264ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:09:55.820755Z","caller":"traceutil/trace.go:171","msg":"trace[354467603] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"124.943756ms","start":"2026-09-18T05:09:55.69576Z","end":"2026-09-18T05:09:55.820704Z","steps":["trace[354467603] 'process raft request' (duration: 124.686276ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:09:55.830206Z","caller":"traceutil/trace.go:171","msg":"trace[368186126] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"123.983099ms","start":"2026-09-18T05:09:55.706199Z","end":"2026-09-18T05:09:55.830182Z","steps":["trace[368186126] 'process raft request' (duration: 123.090067ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:09:55.830508Z","caller":"traceutil/trace.go:171","msg":"trace[1842125461] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"124.090194ms","start":"2026-09-18T05:09:55.706406Z","end":"2026-09-18T05:09:55.830496Z","steps":["trace[1842125461] 'process raft request' (duration: 122.970304ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:09:55.830624Z","caller":"traceutil/trace.go:171","msg":"trace[643178546] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"119.203645ms","start":"2026-09-18T05:09:55.711411Z","end":"2026-09-18T05:09:55.830615Z","steps":["trace[643178546] 'process raft request' (duration: 118.029013ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:09:55.830729Z","caller":"traceutil/trace.go:171","msg":"trace[1001874865] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"113.04738ms","start":"2026-09-18T05:09:55.717673Z","end":"2026-09-18T05:09:55.830721Z","steps":["trace[1001874865] 'process raft request' (duration: 111.799985ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:09:55.830871Z","caller":"traceutil/trace.go:171","msg":"trace[1425306621] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"107.144044ms","start":"2026-09-18T05:09:55.723715Z","end":"2026-09-18T05:09:55.830859Z","steps":["trace[1425306621] 'process raft request' (duration: 105.817396ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:10:02.325559Z","caller":"traceutil/trace.go:171","msg":"trace[1291229236] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"121.519664ms","start":"2026-09-18T05:10:02.204005Z","end":"2026-09-18T05:10:02.325525Z","steps":["trace[1291229236] 'read index received' (duration: 30.986542ms)","trace[1291229236] 'applied index is now lower than readState.Index' (duration: 90.531672ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:10:02.325662Z","caller":"traceutil/trace.go:171","msg":"trace[1825435010] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"236.241485ms","start":"2026-09-18T05:10:02.089376Z","end":"2026-09-18T05:10:02.325617Z","steps":["trace[1825435010] 'process raft request' (duration: 145.602599ms)","trace[1825435010] 'compare' (duration: 90.410258ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:10:02.325849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.799575ms","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:10:02.325882Z","caller":"traceutil/trace.go:171","msg":"trace[1942527001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"121.932859ms","start":"2026-09-18T05:10:02.203939Z","end":"2026-09-18T05:10:02.325872Z","steps":["trace[1942527001] 'agreement among raft nodes before linearized reading' (duration: 121.707332ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:10:02.591518Z","caller":"traceutil/trace.go:171","msg":"trace[1312156001] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"260.312438ms","start":"2026-09-18T05:10:02.331177Z","end":"2026-09-18T05:10:02.591489Z","steps":["trace[1312156001] 'process raft request' (duration: 260.141562ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:10:02.635354Z","caller":"traceutil/trace.go:171","msg":"trace[1614442665] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"193.476485ms","start":"2026-09-18T05:10:02.441853Z","end":"2026-09-18T05:10:02.635329Z","steps":["trace[1614442665] 'process raft request' (duration: 192.262653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:10:03.207525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.544479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:10:03.207739Z","caller":"traceutil/trace.go:171","msg":"trace[325318530] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"488.281463ms","start":"2026-09-18T05:10:02.719437Z","end":"2026-09-18T05:10:03.207718Z","steps":["trace[325318530] 'read index received' (duration: 280.443995ms)","trace[325318530] 'applied index is now lower than readState.Index' (duration: 207.836258ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:10:03.207781Z","caller":"traceutil/trace.go:171","msg":"trace[1704895660] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"494.443887ms","start":"2026-09-18T05:10:02.713319Z","end":"2026-09-18T05:10:03.207763Z","steps":["trace[1704895660] 'process raft request' (duration: 494.337603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:10:03.207879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:10:02.713281Z","time spent":"494.559761ms","remote":"127.0.0.1:46558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:10:03.207898Z","caller":"traceutil/trace.go:171","msg":"trace[3069063] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"536.095668ms","start":"2026-09-18T05:10:02.671745Z","end":"2026-09-18T05:10:03.207841Z","steps":["trace[3069063] 'process raft request' (duration: 327.899777ms)","trace[3069063] 'compare' (duration: 207.330782ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:10:03.208048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.012758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:10:03.208074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.438815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ltrj2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T05:10:03.208082Z","caller":"traceutil/trace.go:171","msg":"trace[193145327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"402.09359ms","start":"2026-09-18T05:10:02.805975Z","end":"2026-09-18T05:10:03.208068Z","steps":["trace[193145327] 'agreement among raft nodes before linearized reading' (duration: 402.036138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:10:03.207985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.544132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T05:10:03.208102Z","caller":"traceutil/trace.go:171","msg":"trace[13870000] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ltrj2; range_end:; response_count:1; response_revision:614; }","duration":"433.542039ms","start":"2026-09-18T05:10:02.774551Z","end":"2026-09-18T05:10:03.208093Z","steps":["trace[13870000] 'agreement among raft nodes before linearized reading' (duration: 433.444906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:10:03.208112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:10:02.805949Z","time spent":"402.156233ms","remote":"127.0.0.1:46258","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:10:03.208139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:10:02.774521Z","time spent":"433.611852ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-ltrj2\" "} {"level":"warn","ts":"2026-09-18T05:10:03.208195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:10:02.671711Z","time spent":"536.325515ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:10:03.208179Z","caller":"traceutil/trace.go:171","msg":"trace[1971713239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"488.75186ms","start":"2026-09-18T05:10:02.71938Z","end":"2026-09-18T05:10:03.208132Z","steps":["trace[1971713239] 'agreement among raft nodes before linearized reading' (duration: 488.455509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:10:03.20832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:10:02.719364Z","time spent":"488.897294ms","remote":"127.0.0.1:46502","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:10:56.95715Z","caller":"traceutil/trace.go:171","msg":"trace[2051361269] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"105.032603ms","start":"2026-09-18T05:10:56.852084Z","end":"2026-09-18T05:10:56.957117Z","steps":["trace[2051361269] 'process raft request' (duration: 100.308205ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:18.486452Z","caller":"traceutil/trace.go:171","msg":"trace[245964404] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"158.052325ms","start":"2026-09-18T05:11:18.328371Z","end":"2026-09-18T05:11:18.486424Z","steps":["trace[245964404] 'process raft request' (duration: 150.125923ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:30.897041Z","caller":"traceutil/trace.go:171","msg":"trace[1770520627] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"103.749286ms","start":"2026-09-18T05:11:30.793267Z","end":"2026-09-18T05:11:30.897017Z","steps":["trace[1770520627] 'process raft request' (duration: 103.709295ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:30.89727Z","caller":"traceutil/trace.go:171","msg":"trace[2063518424] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"160.676387ms","start":"2026-09-18T05:11:30.736546Z","end":"2026-09-18T05:11:30.897222Z","steps":["trace[2063518424] 'process raft request' (duration: 82.469286ms)","trace[2063518424] 'compare' (duration: 77.474214ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:11:30.897459Z","caller":"traceutil/trace.go:171","msg":"trace[860384623] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"158.918718ms","start":"2026-09-18T05:11:30.738501Z","end":"2026-09-18T05:11:30.897419Z","steps":["trace[860384623] 'process raft request' (duration: 158.155871ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:30.897606Z","caller":"traceutil/trace.go:171","msg":"trace[1633473448] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"108.589793ms","start":"2026-09-18T05:11:30.788987Z","end":"2026-09-18T05:11:30.897576Z","steps":["trace[1633473448] 'process raft request' (duration: 107.771375ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:30.897667Z","caller":"traceutil/trace.go:171","msg":"trace[209062289] linearizableReadLoop","detail":"{readStateIndex:1723; appliedIndex:1721; }","duration":"129.392663ms","start":"2026-09-18T05:11:30.768251Z","end":"2026-09-18T05:11:30.897643Z","steps":["trace[209062289] 'read index received' (duration: 50.726913ms)","trace[209062289] 'applied index is now lower than readState.Index' (duration: 78.66435ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:11:30.897939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.763719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/rabbitmq-cluster-operator-67fdb85b4f\" ","response":"range_response_count:1 size:5563"} {"level":"info","ts":"2026-09-18T05:11:30.89801Z","caller":"traceutil/trace.go:171","msg":"trace[1515677855] range","detail":"{range_begin:/registry/replicasets/openstack/rabbitmq-cluster-operator-67fdb85b4f; range_end:; response_count:1; response_revision:1659; }","duration":"128.858132ms","start":"2026-09-18T05:11:30.769133Z","end":"2026-09-18T05:11:30.897991Z","steps":["trace[1515677855] 'agreement among raft nodes before linearized reading' (duration: 128.651867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:30.898005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.743411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/rabbitmq-messaging-topology-operator-5c9876cc97\" ","response":"range_response_count:1 size:5842"} {"level":"warn","ts":"2026-09-18T05:11:30.897953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.869877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:11:30.898137Z","caller":"traceutil/trace.go:171","msg":"trace[1334311380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1659; }","duration":"118.163435ms","start":"2026-09-18T05:11:30.779952Z","end":"2026-09-18T05:11:30.898115Z","steps":["trace[1334311380] 'agreement among raft nodes before linearized reading' (duration: 117.864208ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:30.898064Z","caller":"traceutil/trace.go:171","msg":"trace[93151957] range","detail":"{range_begin:/registry/replicasets/openstack/rabbitmq-messaging-topology-operator-5c9876cc97; range_end:; response_count:1; response_revision:1659; }","duration":"129.848113ms","start":"2026-09-18T05:11:30.768201Z","end":"2026-09-18T05:11:30.898049Z","steps":["trace[93151957] 'agreement among raft nodes before linearized reading' (duration: 129.540216ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:11:40.012347Z","caller":"traceutil/trace.go:171","msg":"trace[971211602] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"104.233459ms","start":"2026-09-18T05:11:39.908077Z","end":"2026-09-18T05:11:40.01231Z","steps":["trace[971211602] 'process raft request' (duration: 104.036514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:47.853204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.814539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T05:11:47.853327Z","caller":"traceutil/trace.go:171","msg":"trace[1929678157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1911; }","duration":"235.968602ms","start":"2026-09-18T05:11:47.617309Z","end":"2026-09-18T05:11:47.853277Z","steps":["trace[1929678157] 'range keys from in-memory index tree' (duration: 234.809797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:48.303182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.519713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:11:48.303418Z","caller":"traceutil/trace.go:171","msg":"trace[737335271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1913; }","duration":"102.82193ms","start":"2026-09-18T05:11:48.200559Z","end":"2026-09-18T05:11:48.303381Z","steps":["trace[737335271] 'count revisions from in-memory index tree' (duration: 102.435691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:11:48.303976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.985484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T05:11:48.304598Z","caller":"traceutil/trace.go:171","msg":"trace[1324393494] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1913; }","duration":"158.671759ms","start":"2026-09-18T05:11:48.145893Z","end":"2026-09-18T05:11:48.304565Z","steps":["trace[1324393494] 'range keys from in-memory index tree' (duration: 157.453873ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:00.865698Z","caller":"traceutil/trace.go:171","msg":"trace[761879137] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"172.901165ms","start":"2026-09-18T05:12:00.692768Z","end":"2026-09-18T05:12:00.865669Z","steps":["trace[761879137] 'process raft request' (duration: 172.619799ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:01.233947Z","caller":"traceutil/trace.go:171","msg":"trace[55101954] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"139.691377ms","start":"2026-09-18T05:12:01.094224Z","end":"2026-09-18T05:12:01.233916Z","steps":["trace[55101954] 'process raft request' (duration: 77.193006ms)","trace[55101954] 'compare' (duration: 62.285977ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:21.663151Z","caller":"traceutil/trace.go:171","msg":"trace[1053041068] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"144.996786ms","start":"2026-09-18T05:12:21.518131Z","end":"2026-09-18T05:12:21.663127Z","steps":["trace[1053041068] 'read index received' (duration: 131.922377ms)","trace[1053041068] 'applied index is now lower than readState.Index' (duration: 13.073839ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:21.663326Z","caller":"traceutil/trace.go:171","msg":"trace[1381321909] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"149.335872ms","start":"2026-09-18T05:12:21.513875Z","end":"2026-09-18T05:12:21.663211Z","steps":["trace[1381321909] 'process raft request' (duration: 136.114431ms)","trace[1381321909] 'compare' (duration: 13.039138ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:12:21.663976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.817212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T05:12:21.664111Z","caller":"traceutil/trace.go:171","msg":"trace[1107469615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2151; }","duration":"146.010176ms","start":"2026-09-18T05:12:21.518079Z","end":"2026-09-18T05:12:21.66409Z","steps":["trace[1107469615] 'agreement among raft nodes before linearized reading' (duration: 145.69791ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:22.153388Z","caller":"traceutil/trace.go:171","msg":"trace[2007712575] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"101.816097ms","start":"2026-09-18T05:12:22.051529Z","end":"2026-09-18T05:12:22.153345Z","steps":["trace[2007712575] 'process raft request' (duration: 35.648787ms)","trace[2007712575] 'compare' (duration: 65.881594ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:12:22.717085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.376628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:12:22.717226Z","caller":"traceutil/trace.go:171","msg":"trace[390629248] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"423.14095ms","start":"2026-09-18T05:12:22.29406Z","end":"2026-09-18T05:12:22.717201Z","steps":["trace[390629248] 'read index received' (duration: 115.504917ms)","trace[390629248] 'applied index is now lower than readState.Index' (duration: 307.634013ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:22.717398Z","caller":"traceutil/trace.go:171","msg":"trace[1173287078] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"464.644562ms","start":"2026-09-18T05:12:22.252726Z","end":"2026-09-18T05:12:22.71737Z","steps":["trace[1173287078] 'process raft request' (duration: 156.796455ms)","trace[1173287078] 'compare' (duration: 307.040542ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:12:22.717504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:22.252694Z","time spent":"464.765355ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:12:22.717792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.709591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:12:22.718335Z","caller":"traceutil/trace.go:171","msg":"trace[1829450952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2156; }","duration":"424.321894ms","start":"2026-09-18T05:12:22.293989Z","end":"2026-09-18T05:12:22.718311Z","steps":["trace[1829450952] 'agreement among raft nodes before linearized reading' (duration: 423.726252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:22.718461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:22.293958Z","time spent":"424.482957ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:12:22.717981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.091603ms","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:12:22.718941Z","caller":"traceutil/trace.go:171","msg":"trace[26614944] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2156; }","duration":"145.097683ms","start":"2026-09-18T05:12:22.573826Z","end":"2026-09-18T05:12:22.718924Z","steps":["trace[26614944] 'agreement among raft nodes before linearized reading' (duration: 143.879649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:23.009886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.293658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:12:23.010311Z","caller":"traceutil/trace.go:171","msg":"trace[1757274993] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"286.676127ms","start":"2026-09-18T05:12:22.723562Z","end":"2026-09-18T05:12:23.010238Z","steps":["trace[1757274993] 'process raft request' (duration: 93.834829ms)","trace[1757274993] 'compare' (duration: 192.047102ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:23.101059Z","caller":"traceutil/trace.go:171","msg":"trace[777169978] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"159.2601ms","start":"2026-09-18T05:12:22.941772Z","end":"2026-09-18T05:12:23.101032Z","steps":["trace[777169978] 'process raft request' (duration: 159.114027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:23.512479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.589983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-18T05:12:23.512862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.771712ms","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:12:23.512867Z","caller":"traceutil/trace.go:171","msg":"trace[993628787] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"188.823053ms","start":"2026-09-18T05:12:23.323954Z","end":"2026-09-18T05:12:23.512777Z","steps":["trace[993628787] 'process raft request' (duration: 141.984487ms)","trace[993628787] 'compare' (duration: 45.803137ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:23.512922Z","caller":"traceutil/trace.go:171","msg":"trace[1320995342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2159; }","duration":"149.881204ms","start":"2026-09-18T05:12:23.363027Z","end":"2026-09-18T05:12:23.512908Z","steps":["trace[1320995342] 'agreement among raft nodes before linearized reading' (duration: 149.67866ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:12:23.512648Z","caller":"traceutil/trace.go:171","msg":"trace[653549812] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"149.511607ms","start":"2026-09-18T05:12:23.363077Z","end":"2026-09-18T05:12:23.512589Z","steps":["trace[653549812] 'read index received' (duration: 102.799273ms)","trace[653549812] 'applied index is now lower than readState.Index' (duration: 46.709574ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:23.512881Z","caller":"traceutil/trace.go:171","msg":"trace[511209814] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2158; }","duration":"351.066252ms","start":"2026-09-18T05:12:23.16179Z","end":"2026-09-18T05:12:23.512856Z","steps":["trace[511209814] 'range keys from in-memory index tree' (duration: 350.341158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:23.51319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:23.161763Z","time spent":"351.391039ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-18T05:14:00.810708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":821520672350209549,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T05:14:00.83997Z","caller":"traceutil/trace.go:171","msg":"trace[1617338361] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"556.237027ms","start":"2026-09-18T05:14:00.283704Z","end":"2026-09-18T05:14:00.839941Z","steps":["trace[1617338361] 'process raft request' (duration: 556.063292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:00.840147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.283683Z","time spent":"556.37616ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:14:01.498635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.450736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:14:01.498699Z","caller":"traceutil/trace.go:171","msg":"trace[2100672200] linearizableReadLoop","detail":"{readStateIndex:2890; appliedIndex:2888; }","duration":"1.18881107s","start":"2026-09-18T05:14:00.309879Z","end":"2026-09-18T05:14:01.49869Z","steps":["trace[2100672200] 'read index received' (duration: 529.923348ms)","trace[2100672200] 'applied index is now lower than readState.Index' (duration: 658.887022ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:14:01.498753Z","caller":"traceutil/trace.go:171","msg":"trace[2067602916] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"1.21481051s","start":"2026-09-18T05:14:00.283937Z","end":"2026-09-18T05:14:01.498747Z","steps":["trace[2067602916] 'process raft request' (duration: 665.181299ms)","trace[2067602916] 'compare' (duration: 549.097695ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:14:01.498781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.283921Z","time spent":"1.21484573s","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:14:01.499046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.006396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:14:01.499087Z","caller":"traceutil/trace.go:171","msg":"trace[711705269] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2784; }","duration":"537.08738ms","start":"2026-09-18T05:14:00.961993Z","end":"2026-09-18T05:14:01.49908Z","steps":["trace[711705269] 'agreement among raft nodes before linearized reading' (duration: 537.032158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.499103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.961973Z","time spent":"537.12516ms","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:14:01.49914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188984046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:14:01.499362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18948533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T05:14:01.499398Z","caller":"traceutil/trace.go:171","msg":"trace[1653029644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2784; }","duration":"1.189553772s","start":"2026-09-18T05:14:00.309839Z","end":"2026-09-18T05:14:01.499393Z","steps":["trace[1653029644] 'agreement among raft nodes before linearized reading' (duration: 1.189441988s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.499413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.309823Z","time spent":"1.189585142s","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T05:14:01.499386Z","caller":"traceutil/trace.go:171","msg":"trace[476862714] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2784; }","duration":"1.189222181s","start":"2026-09-18T05:14:00.310095Z","end":"2026-09-18T05:14:01.499317Z","steps":["trace[476862714] 'agreement among raft nodes before linearized reading' (duration: 1.188735478s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.499434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.806097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-18T05:14:01.499538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.915373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d65253b45ec2da\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-09-18T05:14:01.499556Z","caller":"traceutil/trace.go:171","msg":"trace[1424159238] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d65253b45ec2da; range_end:; response_count:1; response_revision:2784; }","duration":"108.988406ms","start":"2026-09-18T05:14:01.390564Z","end":"2026-09-18T05:14:01.499552Z","steps":["trace[1424159238] 'agreement among raft nodes before linearized reading' (duration: 108.920374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.499632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.031138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-18T05:14:01.499525Z","caller":"traceutil/trace.go:171","msg":"trace[669151415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2784; }","duration":"633.917371ms","start":"2026-09-18T05:14:00.865582Z","end":"2026-09-18T05:14:01.4995Z","steps":["trace[669151415] 'agreement among raft nodes before linearized reading' (duration: 633.746996ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:01.49968Z","caller":"traceutil/trace.go:171","msg":"trace[838375525] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2784; }","duration":"822.113131ms","start":"2026-09-18T05:14:00.677554Z","end":"2026-09-18T05:14:01.499668Z","steps":["trace[838375525] 'agreement among raft nodes before linearized reading' (duration: 821.995497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.499696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.865566Z","time spent":"634.107867ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T05:14:01.499718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.677539Z","time spent":"822.168512ms","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-18T05:14:01.499456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.310089Z","time spent":"1.189347685s","remote":"127.0.0.1:55904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:14:01.499651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.57261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-18T05:14:01.499951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06434889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:14:01.499858Z","caller":"traceutil/trace.go:171","msg":"trace[899460575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2784; }","duration":"283.819037ms","start":"2026-09-18T05:14:01.216023Z","end":"2026-09-18T05:14:01.499842Z","steps":["trace[899460575] 'agreement among raft nodes before linearized reading' (duration: 283.489167ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:01.499998Z","caller":"traceutil/trace.go:171","msg":"trace[203113018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2784; }","duration":"1.064440972s","start":"2026-09-18T05:14:00.435542Z","end":"2026-09-18T05:14:01.499983Z","steps":["trace[203113018] 'agreement among raft nodes before linearized reading' (duration: 1.06437024s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.500039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.43552Z","time spent":"1.064506394s","remote":"127.0.0.1:56180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:14:01.500091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182010714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T05:14:01.50011Z","caller":"traceutil/trace.go:171","msg":"trace[2140820435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2784; }","duration":"1.182041924s","start":"2026-09-18T05:14:00.318061Z","end":"2026-09-18T05:14:01.500103Z","steps":["trace[2140820435] 'agreement among raft nodes before linearized reading' (duration: 1.181969922s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:01.500145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:00.318055Z","time spent":"1.182068756s","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T05:14:01.500686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.797742ms","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:14:01.500744Z","caller":"traceutil/trace.go:171","msg":"trace[982423736] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2784; }","duration":"270.907076ms","start":"2026-09-18T05:14:01.229822Z","end":"2026-09-18T05:14:01.500729Z","steps":["trace[982423736] 'agreement among raft nodes before linearized reading' (duration: 270.453772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.128091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.027908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:14:02.12838Z","caller":"traceutil/trace.go:171","msg":"trace[1967020564] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"125.501462ms","start":"2026-09-18T05:14:02.002865Z","end":"2026-09-18T05:14:02.128367Z","steps":["trace[1967020564] 'process raft request' (duration: 125.473671ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:02.128663Z","caller":"traceutil/trace.go:171","msg":"trace[116050062] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"624.080859ms","start":"2026-09-18T05:14:01.504572Z","end":"2026-09-18T05:14:02.128653Z","steps":["trace[116050062] 'process raft request' (duration: 506.422002ms)","trace[116050062] 'compare' (duration: 116.680969ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:14:02.128712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.504556Z","time spent":"624.131521ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:02.128819Z","caller":"traceutil/trace.go:171","msg":"trace[1192513894] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"564.765863ms","start":"2026-09-18T05:14:01.564046Z","end":"2026-09-18T05:14:02.128811Z","steps":["trace[1192513894] 'process raft request' (duration: 564.227127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.12886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.56402Z","time spent":"564.820074ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:02.128948Z","caller":"traceutil/trace.go:171","msg":"trace[2042804377] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"532.834924ms","start":"2026-09-18T05:14:01.596106Z","end":"2026-09-18T05:14:02.128941Z","steps":["trace[2042804377] 'process raft request' (duration: 532.202846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.128987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.59608Z","time spent":"532.888445ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:02.129005Z","caller":"traceutil/trace.go:171","msg":"trace[693361558] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2890; }","duration":"624.021497ms","start":"2026-09-18T05:14:01.504674Z","end":"2026-09-18T05:14:02.128695Z","steps":["trace[693361558] 'read index received' (duration: 25.879823ms)","trace[693361558] 'applied index is now lower than readState.Index' (duration: 598.138334ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:14:02.129413Z","caller":"traceutil/trace.go:171","msg":"trace[1331171722] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"623.126531ms","start":"2026-09-18T05:14:01.506125Z","end":"2026-09-18T05:14:02.129251Z","steps":["trace[1331171722] 'process raft request' (duration: 622.035441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.129833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.157259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d5a6b3c8-7f62-46d2-aa54-3baad5e64686\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-09-18T05:14:02.129867Z","caller":"traceutil/trace.go:171","msg":"trace[327065057] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d5a6b3c8-7f62-46d2-aa54-3baad5e64686; range_end:; response_count:1; response_revision:2792; }","duration":"625.219861ms","start":"2026-09-18T05:14:01.504638Z","end":"2026-09-18T05:14:02.129858Z","steps":["trace[327065057] 'agreement among raft nodes before linearized reading' (duration: 625.124638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.12989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.504625Z","time spent":"625.258173ms","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1326,"request content":"key:\"/registry/persistentvolumes/pvc-d5a6b3c8-7f62-46d2-aa54-3baad5e64686\" "} {"level":"warn","ts":"2026-09-18T05:14:02.129879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.506106Z","time spent":"623.353028ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:02.13002Z","caller":"traceutil/trace.go:171","msg":"trace[670324650] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"623.438071ms","start":"2026-09-18T05:14:01.506575Z","end":"2026-09-18T05:14:02.130013Z","steps":["trace[670324650] 'process raft request' (duration: 621.622639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.130066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.506567Z","time spent":"623.475351ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:14:02.130185Z","caller":"traceutil/trace.go:171","msg":"trace[1107029712] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"623.08619ms","start":"2026-09-18T05:14:01.507091Z","end":"2026-09-18T05:14:02.130177Z","steps":["trace[1107029712] 'process raft request' (duration: 621.131424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.130239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.507075Z","time spent":"623.142791ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:14:02.13057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.958299ms","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:14:02.130598Z","caller":"traceutil/trace.go:171","msg":"trace[867470894] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2792; }","duration":"376.047152ms","start":"2026-09-18T05:14:01.754544Z","end":"2026-09-18T05:14:02.130591Z","steps":["trace[867470894] 'agreement among raft nodes before linearized reading' (duration: 375.97936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.130618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.754526Z","time spent":"376.085624ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T05:14:02.130832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.689171ms","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:02.130857Z","caller":"traceutil/trace.go:171","msg":"trace[1798521196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2792; }","duration":"326.753593ms","start":"2026-09-18T05:14:01.804097Z","end":"2026-09-18T05:14:02.130851Z","steps":["trace[1798521196] 'agreement among raft nodes before linearized reading' (duration: 326.702721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:14:02.130873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:14:01.804077Z","time spent":"326.792994ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:15:26.850544Z","caller":"traceutil/trace.go:171","msg":"trace[1010939373] linearizableReadLoop","detail":"{readStateIndex:3357; appliedIndex:3356; }","duration":"142.390943ms","start":"2026-09-18T05:15:26.708126Z","end":"2026-09-18T05:15:26.850517Z","steps":["trace[1010939373] 'read index received' (duration: 124.56604ms)","trace[1010939373] 'applied index is now lower than readState.Index' (duration: 17.823953ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:26.8506Z","caller":"traceutil/trace.go:171","msg":"trace[1502443212] transaction","detail":"{read_only:false; response_revision:3232; number_of_response:1; }","duration":"153.218885ms","start":"2026-09-18T05:15:26.697359Z","end":"2026-09-18T05:15:26.850578Z","steps":["trace[1502443212] 'process raft request' (duration: 135.418523ms)","trace[1502443212] 'compare' (duration: 17.640768ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:15:26.85078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.625488ms","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:15:26.850841Z","caller":"traceutil/trace.go:171","msg":"trace[1501467975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3232; }","duration":"142.725701ms","start":"2026-09-18T05:15:26.708097Z","end":"2026-09-18T05:15:26.850822Z","steps":["trace[1501467975] 'agreement among raft nodes before linearized reading' (duration: 142.554867ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:53.667014Z","caller":"traceutil/trace.go:171","msg":"trace[1288571616] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5127; }","duration":"107.83719ms","start":"2026-09-18T05:18:53.559145Z","end":"2026-09-18T05:18:53.666982Z","steps":["trace[1288571616] 'read index received' (duration: 86.965004ms)","trace[1288571616] 'applied index is now lower than readState.Index' (duration: 20.871296ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:18:53.667477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.680659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:18:53.66751Z","caller":"traceutil/trace.go:171","msg":"trace[824321771] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4934; }","duration":"159.761971ms","start":"2026-09-18T05:18:53.507741Z","end":"2026-09-18T05:18:53.667503Z","steps":["trace[824321771] 'agreement among raft nodes before linearized reading' (duration: 159.689259ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:53.667406Z","caller":"traceutil/trace.go:171","msg":"trace[339774630] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"151.699447ms","start":"2026-09-18T05:18:53.515696Z","end":"2026-09-18T05:18:53.667396Z","steps":["trace[339774630] 'process raft request' (duration: 130.462883ms)","trace[339774630] 'compare' (duration: 20.728273ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:54.668758Z","caller":"traceutil/trace.go:171","msg":"trace[1090520521] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"100.330367ms","start":"2026-09-18T05:18:54.568404Z","end":"2026-09-18T05:18:54.668734Z","steps":["trace[1090520521] 'process raft request' (duration: 100.164594ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:56.051652Z","caller":"traceutil/trace.go:171","msg":"trace[512988141] linearizableReadLoop","detail":"{readStateIndex:5201; appliedIndex:5200; }","duration":"154.785276ms","start":"2026-09-18T05:18:55.89685Z","end":"2026-09-18T05:18:56.051635Z","steps":["trace[512988141] 'read index received' (duration: 154.733385ms)","trace[512988141] 'applied index is now lower than readState.Index' (duration: 51.071µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:56.051754Z","caller":"traceutil/trace.go:171","msg":"trace[2124359529] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"191.534356ms","start":"2026-09-18T05:18:55.860209Z","end":"2026-09-18T05:18:56.051743Z","steps":["trace[2124359529] 'process raft request' (duration: 191.320731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:56.05204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.172674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:18:56.052119Z","caller":"traceutil/trace.go:171","msg":"trace[1339769542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5003; }","duration":"155.283986ms","start":"2026-09-18T05:18:55.896817Z","end":"2026-09-18T05:18:56.052101Z","steps":["trace[1339769542] 'agreement among raft nodes before linearized reading' (duration: 154.930749ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:01.6825Z","caller":"traceutil/trace.go:171","msg":"trace[313494077] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"237.664836ms","start":"2026-09-18T05:19:01.444799Z","end":"2026-09-18T05:19:01.682464Z","steps":["trace[313494077] 'process raft request' (duration: 237.519083ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:01.683451Z","caller":"traceutil/trace.go:171","msg":"trace[396168506] linearizableReadLoop","detail":"{readStateIndex:5309; appliedIndex:5309; }","duration":"234.791169ms","start":"2026-09-18T05:19:01.448636Z","end":"2026-09-18T05:19:01.683427Z","steps":["trace[396168506] 'read index received' (duration: 234.779529ms)","trace[396168506] 'applied index is now lower than readState.Index' (duration: 9.73µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:01.683687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.025533ms","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:19:01.683745Z","caller":"traceutil/trace.go:171","msg":"trace[958503874] 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:5109; }","duration":"235.113455ms","start":"2026-09-18T05:19:01.448614Z","end":"2026-09-18T05:19:01.683728Z","steps":["trace[958503874] 'agreement among raft nodes before linearized reading' (duration: 234.89831ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:01.954573Z","caller":"traceutil/trace.go:171","msg":"trace[2051359669] linearizableReadLoop","detail":"{readStateIndex:5311; appliedIndex:5309; }","duration":"268.23776ms","start":"2026-09-18T05:19:01.686307Z","end":"2026-09-18T05:19:01.954545Z","steps":["trace[2051359669] 'read index received' (duration: 267.311251ms)","trace[2051359669] 'applied index is now lower than readState.Index' (duration: 925.149µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:01.954658Z","caller":"traceutil/trace.go:171","msg":"trace[1259746697] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"502.694311ms","start":"2026-09-18T05:19:01.451944Z","end":"2026-09-18T05:19:01.954638Z","steps":["trace[1259746697] 'process raft request' (duration: 502.082028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:01.954778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:01.451926Z","time spent":"502.765523ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:19:01.955035Z","caller":"traceutil/trace.go:171","msg":"trace[595149245] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"366.836156ms","start":"2026-09-18T05:19:01.588124Z","end":"2026-09-18T05:19:01.95496Z","steps":["trace[595149245] 'process raft request' (duration: 366.331795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:01.955143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:01.588099Z","time spent":"366.986718ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:19:01.955402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.089127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b10fd83e-77fd-45d2-b942-c6fa5da7c286\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-18T05:19:01.955443Z","caller":"traceutil/trace.go:171","msg":"trace[460799942] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b10fd83e-77fd-45d2-b942-c6fa5da7c286; range_end:; response_count:1; response_revision:5111; }","duration":"269.150427ms","start":"2026-09-18T05:19:01.686281Z","end":"2026-09-18T05:19:01.955431Z","steps":["trace[460799942] 'agreement among raft nodes before linearized reading' (duration: 269.027005ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:01.970272Z","caller":"traceutil/trace.go:171","msg":"trace[69586441] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"279.58007ms","start":"2026-09-18T05:19:01.690641Z","end":"2026-09-18T05:19:01.970221Z","steps":["trace[69586441] 'process raft request' (duration: 278.897075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:01.971408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.813869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T05:19:01.971495Z","caller":"traceutil/trace.go:171","msg":"trace[746937715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5113; }","duration":"151.977643ms","start":"2026-09-18T05:19:01.819501Z","end":"2026-09-18T05:19:01.971478Z","steps":["trace[746937715] 'agreement among raft nodes before linearized reading' (duration: 151.681017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:01.971541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.397096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:19:01.971598Z","caller":"traceutil/trace.go:171","msg":"trace[1296087551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5113; }","duration":"166.505469ms","start":"2026-09-18T05:19:01.805078Z","end":"2026-09-18T05:19:01.971584Z","steps":["trace[1296087551] 'agreement among raft nodes before linearized reading' (duration: 166.400907ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:02.096653Z","caller":"traceutil/trace.go:171","msg":"trace[1351726274] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"120.040742ms","start":"2026-09-18T05:19:01.976593Z","end":"2026-09-18T05:19:02.096634Z","steps":["trace[1351726274] 'process raft request' (duration: 84.447418ms)","trace[1351726274] 'compare' (duration: 35.332518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:02.096755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.992473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:19:02.096804Z","caller":"traceutil/trace.go:171","msg":"trace[1225949209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5119; }","duration":"124.076225ms","start":"2026-09-18T05:19:01.972717Z","end":"2026-09-18T05:19:02.096793Z","steps":["trace[1225949209] 'agreement among raft nodes before linearized reading' (duration: 123.970073ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:02.268035Z","caller":"traceutil/trace.go:171","msg":"trace[1263093051] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"140.169392ms","start":"2026-09-18T05:19:02.127835Z","end":"2026-09-18T05:19:02.268005Z","steps":["trace[1263093051] 'process raft request' (duration: 116.558891ms)","trace[1263093051] 'compare' (duration: 23.401436ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:02.269133Z","caller":"traceutil/trace.go:171","msg":"trace[5331322] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"141.114541ms","start":"2026-09-18T05:19:02.127999Z","end":"2026-09-18T05:19:02.269113Z","steps":["trace[5331322] 'process raft request' (duration: 141.032909ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:02.269216Z","caller":"traceutil/trace.go:171","msg":"trace[113407332] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"141.345555ms","start":"2026-09-18T05:19:02.127847Z","end":"2026-09-18T05:19:02.269192Z","steps":["trace[113407332] 'process raft request' (duration: 140.08195ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:02.671759Z","caller":"traceutil/trace.go:171","msg":"trace[235053299] linearizableReadLoop","detail":"{readStateIndex:5324; appliedIndex:5323; }","duration":"202.174683ms","start":"2026-09-18T05:19:02.469568Z","end":"2026-09-18T05:19:02.671742Z","steps":["trace[235053299] 'read index received' (duration: 202.01002ms)","trace[235053299] 'applied index is now lower than readState.Index' (duration: 163.933µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:02.671882Z","caller":"traceutil/trace.go:171","msg":"trace[699388666] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"204.791666ms","start":"2026-09-18T05:19:02.467061Z","end":"2026-09-18T05:19:02.671853Z","steps":["trace[699388666] 'process raft request' (duration: 204.545732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:02.671995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.383897ms","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:19:02.672061Z","caller":"traceutil/trace.go:171","msg":"trace[921914127] 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:5124; }","duration":"202.488469ms","start":"2026-09-18T05:19:02.469534Z","end":"2026-09-18T05:19:02.672023Z","steps":["trace[921914127] 'agreement among raft nodes before linearized reading' (duration: 202.269924ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:02.67842Z","caller":"traceutil/trace.go:171","msg":"trace[2051623722] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"145.373058ms","start":"2026-09-18T05:19:02.533034Z","end":"2026-09-18T05:19:02.678407Z","steps":["trace[2051623722] 'process raft request' (duration: 145.294376ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:06.256539Z","caller":"traceutil/trace.go:171","msg":"trace[408969969] linearizableReadLoop","detail":"{readStateIndex:5368; appliedIndex:5367; }","duration":"149.344225ms","start":"2026-09-18T05:19:06.107169Z","end":"2026-09-18T05:19:06.256513Z","steps":["trace[408969969] 'read index received' (duration: 59.08634ms)","trace[408969969] 'applied index is now lower than readState.Index' (duration: 90.256765ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:06.256794Z","caller":"traceutil/trace.go:171","msg":"trace[728635227] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"189.732645ms","start":"2026-09-18T05:19:06.067031Z","end":"2026-09-18T05:19:06.256764Z","steps":["trace[728635227] 'process raft request' (duration: 99.224935ms)","trace[728635227] 'compare' (duration: 90.04936ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:06.256819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.628891ms","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:19:06.256876Z","caller":"traceutil/trace.go:171","msg":"trace[55293423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5167; }","duration":"149.717933ms","start":"2026-09-18T05:19:06.107141Z","end":"2026-09-18T05:19:06.256859Z","steps":["trace[55293423] 'agreement among raft nodes before linearized reading' (duration: 149.528059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:06.256937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.811206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T05:19:06.256988Z","caller":"traceutil/trace.go:171","msg":"trace[1669203825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5167; }","duration":"121.897507ms","start":"2026-09-18T05:19:06.135077Z","end":"2026-09-18T05:19:06.256975Z","steps":["trace[1669203825] 'agreement among raft nodes before linearized reading' (duration: 121.770024ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:06.351755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2813} {"level":"info","ts":"2026-09-18T05:19:06.434318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2813,"took":"81.609787ms","hash":2360057745,"current-db-size-bytes":51920896,"current-db-size":"52 MB","current-db-size-in-use-bytes":27611136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T05:19:06.434367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2360057745,"revision":2813,"compact-revision":-1} {"level":"info","ts":"2026-09-18T05:19:14.253628Z","caller":"traceutil/trace.go:171","msg":"trace[1886880165] linearizableReadLoop","detail":"{readStateIndex:5473; appliedIndex:5472; }","duration":"173.771871ms","start":"2026-09-18T05:19:14.079828Z","end":"2026-09-18T05:19:14.2536Z","steps":["trace[1886880165] 'read index received' (duration: 167.813881ms)","trace[1886880165] 'applied index is now lower than readState.Index' (duration: 5.9568ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:14.253664Z","caller":"traceutil/trace.go:171","msg":"trace[725698864] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"253.63669ms","start":"2026-09-18T05:19:13.999985Z","end":"2026-09-18T05:19:14.253622Z","steps":["trace[725698864] 'process raft request' (duration: 247.353164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:14.253798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.932435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:19:14.253847Z","caller":"traceutil/trace.go:171","msg":"trace[88476109] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5266; }","duration":"174.050447ms","start":"2026-09-18T05:19:14.079782Z","end":"2026-09-18T05:19:14.253832Z","steps":["trace[88476109] 'agreement among raft nodes before linearized reading' (duration: 173.935274ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.287904Z","caller":"traceutil/trace.go:171","msg":"trace[955312562] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"203.159428ms","start":"2026-09-18T05:19:14.08471Z","end":"2026-09-18T05:19:14.287869Z","steps":["trace[955312562] 'process raft request' (duration: 202.952023ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.735388Z","caller":"traceutil/trace.go:171","msg":"trace[180690290] linearizableReadLoop","detail":"{readStateIndex:5475; appliedIndex:5474; }","duration":"407.764593ms","start":"2026-09-18T05:19:14.327591Z","end":"2026-09-18T05:19:14.735355Z","steps":["trace[180690290] 'read index received' (duration: 352.474773ms)","trace[180690290] 'applied index is now lower than readState.Index' (duration: 55.2879ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:14.735448Z","caller":"traceutil/trace.go:171","msg":"trace[207331674] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"409.031359ms","start":"2026-09-18T05:19:14.32638Z","end":"2026-09-18T05:19:14.735412Z","steps":["trace[207331674] 'process raft request' (duration: 353.751739ms)","trace[207331674] 'compare' (duration: 54.616257ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:14.735945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.901971ms","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":"warn","ts":"2026-09-18T05:19:14.73597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.258406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-09-18T05:19:14.735971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.117875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-18T05:19:14.735986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:14.326336Z","time spent":"409.5594ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:19:14.736009Z","caller":"traceutil/trace.go:171","msg":"trace[1853969003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5268; }","duration":"389.001783ms","start":"2026-09-18T05:19:14.346989Z","end":"2026-09-18T05:19:14.73599Z","steps":["trace[1853969003] 'agreement among raft nodes before linearized reading' (duration: 388.86032ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.736028Z","caller":"traceutil/trace.go:171","msg":"trace[1152153379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5268; }","duration":"388.196906ms","start":"2026-09-18T05:19:14.347816Z","end":"2026-09-18T05:19:14.736013Z","steps":["trace[1152153379] 'agreement among raft nodes before linearized reading' (duration: 388.035783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:14.736054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:14.346967Z","time spent":"389.077555ms","remote":"127.0.0.1:46502","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:19:14.736078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:14.347784Z","time spent":"388.281889ms","remote":"127.0.0.1:46502","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":"info","ts":"2026-09-18T05:19:14.736011Z","caller":"traceutil/trace.go:171","msg":"trace[2089306398] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5268; }","duration":"381.306927ms","start":"2026-09-18T05:19:14.354693Z","end":"2026-09-18T05:19:14.736Z","steps":["trace[2089306398] 'agreement among raft nodes before linearized reading' (duration: 381.152114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:14.73621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:14.354678Z","time spent":"381.522262ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-18T05:19:14.736233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.810896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-18T05:19:14.736258Z","caller":"traceutil/trace.go:171","msg":"trace[451453300] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5268; }","duration":"186.872726ms","start":"2026-09-18T05:19:14.549379Z","end":"2026-09-18T05:19:14.736251Z","steps":["trace[451453300] 'agreement among raft nodes before linearized reading' (duration: 186.809915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:14.735667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.05993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:19:14.736329Z","caller":"traceutil/trace.go:171","msg":"trace[491703841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5268; }","duration":"408.757964ms","start":"2026-09-18T05:19:14.327551Z","end":"2026-09-18T05:19:14.736309Z","steps":["trace[491703841] 'agreement among raft nodes before linearized reading' (duration: 407.973787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:14.736392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:14.327537Z","time spent":"408.837665ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T05:19:14.73655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.509318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T05:19:14.736622Z","caller":"traceutil/trace.go:171","msg":"trace[353681272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5268; }","duration":"404.615351ms","start":"2026-09-18T05:19:14.331992Z","end":"2026-09-18T05:19:14.736607Z","steps":["trace[353681272] 'agreement among raft nodes before linearized reading' (duration: 403.572809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:14.736693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:14.331976Z","time spent":"404.706042ms","remote":"127.0.0.1:46502","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:14.859594Z","caller":"traceutil/trace.go:171","msg":"trace[1973981046] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"116.880908ms","start":"2026-09-18T05:19:14.742682Z","end":"2026-09-18T05:19:14.859563Z","steps":["trace[1973981046] 'process raft request' (duration: 98.683899ms)","trace[1973981046] 'compare' (duration: 17.626138ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:14.859709Z","caller":"traceutil/trace.go:171","msg":"trace[2087889894] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"116.932239ms","start":"2026-09-18T05:19:14.742757Z","end":"2026-09-18T05:19:14.859689Z","steps":["trace[2087889894] 'process raft request' (duration: 116.618513ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.859923Z","caller":"traceutil/trace.go:171","msg":"trace[1471167987] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"117.082853ms","start":"2026-09-18T05:19:14.742796Z","end":"2026-09-18T05:19:14.859879Z","steps":["trace[1471167987] 'process raft request' (duration: 116.630534ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.860009Z","caller":"traceutil/trace.go:171","msg":"trace[114986948] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"117.265206ms","start":"2026-09-18T05:19:14.742729Z","end":"2026-09-18T05:19:14.859995Z","steps":["trace[114986948] 'process raft request' (duration: 116.580082ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.860991Z","caller":"traceutil/trace.go:171","msg":"trace[1186989048] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"109.965918ms","start":"2026-09-18T05:19:14.751007Z","end":"2026-09-18T05:19:14.860973Z","steps":["trace[1186989048] 'process raft request' (duration: 108.472658ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:14.998116Z","caller":"traceutil/trace.go:171","msg":"trace[1182058833] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"131.473345ms","start":"2026-09-18T05:19:14.866604Z","end":"2026-09-18T05:19:14.998078Z","steps":["trace[1182058833] 'process raft request' (duration: 102.919546ms)","trace[1182058833] 'compare' (duration: 28.374295ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:15.017466Z","caller":"traceutil/trace.go:171","msg":"trace[98563342] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"150.260955ms","start":"2026-09-18T05:19:14.867181Z","end":"2026-09-18T05:19:15.017442Z","steps":["trace[98563342] 'process raft request' (duration: 150.107952ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:15.017894Z","caller":"traceutil/trace.go:171","msg":"trace[1415193617] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"150.345496ms","start":"2026-09-18T05:19:14.867534Z","end":"2026-09-18T05:19:15.017879Z","steps":["trace[1415193617] 'process raft request' (duration: 149.872037ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:15.017885Z","caller":"traceutil/trace.go:171","msg":"trace[1034049358] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"149.859296ms","start":"2026-09-18T05:19:14.867935Z","end":"2026-09-18T05:19:15.017794Z","steps":["trace[1034049358] 'process raft request' (duration: 149.671613ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:15.017917Z","caller":"traceutil/trace.go:171","msg":"trace[592041184] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"148.340936ms","start":"2026-09-18T05:19:14.869541Z","end":"2026-09-18T05:19:15.017882Z","steps":["trace[592041184] 'process raft request' (duration: 148.167452ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:15.188267Z","caller":"traceutil/trace.go:171","msg":"trace[1462554837] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"111.119862ms","start":"2026-09-18T05:19:15.077116Z","end":"2026-09-18T05:19:15.188235Z","steps":["trace[1462554837] 'process raft request' (duration: 110.778395ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:15.188468Z","caller":"traceutil/trace.go:171","msg":"trace[1585907941] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"105.334715ms","start":"2026-09-18T05:19:15.083101Z","end":"2026-09-18T05:19:15.188435Z","steps":["trace[1585907941] 'process raft request' (duration: 104.995708ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.431608Z","caller":"traceutil/trace.go:171","msg":"trace[617672679] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"124.340283ms","start":"2026-09-18T05:19:39.307251Z","end":"2026-09-18T05:19:39.431591Z","steps":["trace[617672679] 'process raft request' (duration: 117.906593ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.431811Z","caller":"traceutil/trace.go:171","msg":"trace[589668838] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"124.107748ms","start":"2026-09-18T05:19:39.307568Z","end":"2026-09-18T05:19:39.431676Z","steps":["trace[589668838] 'process raft request' (duration: 123.920664ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.432426Z","caller":"traceutil/trace.go:171","msg":"trace[2115258229] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"124.564908ms","start":"2026-09-18T05:19:39.307854Z","end":"2026-09-18T05:19:39.432419Z","steps":["trace[2115258229] 'process raft request' (duration: 123.71268ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.432496Z","caller":"traceutil/trace.go:171","msg":"trace[1306315599] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"124.582877ms","start":"2026-09-18T05:19:39.307901Z","end":"2026-09-18T05:19:39.432483Z","steps":["trace[1306315599] 'process raft request' (duration: 124.482446ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.433595Z","caller":"traceutil/trace.go:171","msg":"trace[604412470] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"125.486685ms","start":"2026-09-18T05:19:39.308099Z","end":"2026-09-18T05:19:39.433586Z","steps":["trace[604412470] 'process raft request' (duration: 125.419484ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.433681Z","caller":"traceutil/trace.go:171","msg":"trace[1912643779] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"124.777671ms","start":"2026-09-18T05:19:39.308896Z","end":"2026-09-18T05:19:39.433674Z","steps":["trace[1912643779] 'process raft request' (duration: 124.652339ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.433723Z","caller":"traceutil/trace.go:171","msg":"trace[1118292890] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"124.659619ms","start":"2026-09-18T05:19:39.309036Z","end":"2026-09-18T05:19:39.433696Z","steps":["trace[1118292890] 'process raft request' (duration: 124.543426ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.433799Z","caller":"traceutil/trace.go:171","msg":"trace[2076340679] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"124.68789ms","start":"2026-09-18T05:19:39.309096Z","end":"2026-09-18T05:19:39.433784Z","steps":["trace[2076340679] 'process raft request' (duration: 124.497176ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.435115Z","caller":"traceutil/trace.go:171","msg":"trace[1571459987] linearizableReadLoop","detail":"{readStateIndex:5725; appliedIndex:5713; }","duration":"125.448716ms","start":"2026-09-18T05:19:39.309659Z","end":"2026-09-18T05:19:39.435108Z","steps":["trace[1571459987] 'read index received' (duration: 10.920569ms)","trace[1571459987] 'applied index is now lower than readState.Index' (duration: 114.527447ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:39.43524Z","caller":"traceutil/trace.go:171","msg":"trace[971822589] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"126.084148ms","start":"2026-09-18T05:19:39.309134Z","end":"2026-09-18T05:19:39.435218Z","steps":["trace[971822589] 'process raft request' (duration: 124.475875ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.435371Z","caller":"traceutil/trace.go:171","msg":"trace[572296795] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"126.182879ms","start":"2026-09-18T05:19:39.309171Z","end":"2026-09-18T05:19:39.435354Z","steps":["trace[572296795] 'process raft request' (duration: 125.906664ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:39.435494Z","caller":"traceutil/trace.go:171","msg":"trace[1290634602] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"126.135848ms","start":"2026-09-18T05:19:39.309351Z","end":"2026-09-18T05:19:39.435487Z","steps":["trace[1290634602] 'process raft request' (duration: 125.742851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:39.435659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.987516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-18T05:19:39.435706Z","caller":"traceutil/trace.go:171","msg":"trace[1837604377] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5512; }","duration":"126.052157ms","start":"2026-09-18T05:19:39.309643Z","end":"2026-09-18T05:19:39.435695Z","steps":["trace[1837604377] 'agreement among raft nodes before linearized reading' (duration: 125.935244ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:50.484021Z","caller":"traceutil/trace.go:171","msg":"trace[1820246414] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"106.207232ms","start":"2026-09-18T05:19:50.377795Z","end":"2026-09-18T05:19:50.484002Z","steps":["trace[1820246414] 'process raft request' (duration: 105.685101ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:20.792828Z","caller":"traceutil/trace.go:171","msg":"trace[548305851] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"136.465192ms","start":"2026-09-18T05:20:20.656341Z","end":"2026-09-18T05:20:20.792807Z","steps":["trace[548305851] 'process raft request' (duration: 136.273118ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:20.797873Z","caller":"traceutil/trace.go:171","msg":"trace[1256464214] transaction","detail":"{read_only:false; response_revision:5856; number_of_response:1; }","duration":"134.87514ms","start":"2026-09-18T05:20:20.662974Z","end":"2026-09-18T05:20:20.797849Z","steps":["trace[1256464214] 'process raft request' (duration: 134.527793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:25.539902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.519889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-18T05:22:25.54017Z","caller":"traceutil/trace.go:171","msg":"trace[2118441186] transaction","detail":"{read_only:false; response_revision:6616; number_of_response:1; }","duration":"102.955825ms","start":"2026-09-18T05:22:25.437176Z","end":"2026-09-18T05:22:25.540132Z","steps":["trace[2118441186] 'process raft request' (duration: 102.833381ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:25.799024Z","caller":"traceutil/trace.go:171","msg":"trace[612659908] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6616; }","duration":"100.372883ms","start":"2026-09-18T05:22:25.698624Z","end":"2026-09-18T05:22:25.798997Z","steps":["trace[612659908] 'process raft request' (duration: 100.28976ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:25.939181Z","caller":"traceutil/trace.go:171","msg":"trace[1833965595] transaction","detail":"{read_only:false; response_revision:6617; number_of_response:1; }","duration":"111.580706ms","start":"2026-09-18T05:22:25.827557Z","end":"2026-09-18T05:22:25.939138Z","steps":["trace[1833965595] 'process raft request' (duration: 111.324827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:26.278098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.00382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:22:26.27856Z","caller":"traceutil/trace.go:171","msg":"trace[1330901141] transaction","detail":"{read_only:false; response_revision:6627; number_of_response:1; }","duration":"171.888014ms","start":"2026-09-18T05:22:26.106623Z","end":"2026-09-18T05:22:26.278511Z","steps":["trace[1330901141] 'process raft request' (duration: 70.433978ms)","trace[1330901141] 'compare' (duration: 100.850755ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:26.278653Z","caller":"traceutil/trace.go:171","msg":"trace[213007704] transaction","detail":"{read_only:false; response_revision:6628; number_of_response:1; }","duration":"170.666241ms","start":"2026-09-18T05:22:26.10795Z","end":"2026-09-18T05:22:26.278616Z","steps":["trace[213007704] 'process raft request' (duration: 170.238556ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:26.278972Z","caller":"traceutil/trace.go:171","msg":"trace[407848467] transaction","detail":"{read_only:false; response_revision:6630; number_of_response:1; }","duration":"170.432353ms","start":"2026-09-18T05:22:26.108498Z","end":"2026-09-18T05:22:26.27893Z","steps":["trace[407848467] 'process raft request' (duration: 170.05407ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:26.279134Z","caller":"traceutil/trace.go:171","msg":"trace[1048170661] transaction","detail":"{read_only:false; response_revision:6629; number_of_response:1; }","duration":"170.683601ms","start":"2026-09-18T05:22:26.108428Z","end":"2026-09-18T05:22:26.279112Z","steps":["trace[1048170661] 'process raft request' (duration: 169.826281ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:26.279005Z","caller":"traceutil/trace.go:171","msg":"trace[1476346069] transaction","detail":"{read_only:false; response_revision:6631; number_of_response:1; }","duration":"167.76575ms","start":"2026-09-18T05:22:26.111221Z","end":"2026-09-18T05:22:26.278987Z","steps":["trace[1476346069] 'process raft request' (duration: 167.409987ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:18.938847Z","caller":"traceutil/trace.go:171","msg":"trace[1738092685] transaction","detail":"{read_only:false; response_revision:7065; number_of_response:1; }","duration":"118.455943ms","start":"2026-09-18T05:23:18.820375Z","end":"2026-09-18T05:23:18.938831Z","steps":["trace[1738092685] 'process raft request' (duration: 95.535577ms)","trace[1738092685] 'compare' (duration: 22.648797ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:06.360121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5179} {"level":"info","ts":"2026-09-18T05:24:06.445493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5179,"took":"83.204348ms","hash":49548432,"current-db-size-bytes":51920896,"current-db-size":"52 MB","current-db-size-in-use-bytes":19484672,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-18T05:24:06.445615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":49548432,"revision":5179,"compact-revision":2813} {"level":"info","ts":"2026-09-18T05:25:03.295761Z","caller":"traceutil/trace.go:171","msg":"trace[423635480] transaction","detail":"{read_only:false; response_revision:7616; number_of_response:1; }","duration":"216.26011ms","start":"2026-09-18T05:25:03.079473Z","end":"2026-09-18T05:25:03.295733Z","steps":["trace[423635480] 'process raft request' (duration: 214.638283ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:03.295858Z","caller":"traceutil/trace.go:171","msg":"trace[1533743670] linearizableReadLoop","detail":"{readStateIndex:7905; appliedIndex:7904; }","duration":"216.005533ms","start":"2026-09-18T05:25:03.079831Z","end":"2026-09-18T05:25:03.295837Z","steps":["trace[1533743670] 'read index received' (duration: 214.410347ms)","trace[1533743670] 'applied index is now lower than readState.Index' (duration: 1.594316ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:03.295944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.101496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:03.29602Z","caller":"traceutil/trace.go:171","msg":"trace[1414850519] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7617; }","duration":"216.205438ms","start":"2026-09-18T05:25:03.079806Z","end":"2026-09-18T05:25:03.296011Z","steps":["trace[1414850519] 'agreement among raft nodes before linearized reading' (duration: 216.096435ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:09.149235Z","caller":"traceutil/trace.go:171","msg":"trace[1622441293] transaction","detail":"{read_only:false; response_revision:7645; number_of_response:1; }","duration":"116.147861ms","start":"2026-09-18T05:25:09.033049Z","end":"2026-09-18T05:25:09.149197Z","steps":["trace[1622441293] 'process raft request' (duration: 115.891603ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:17.695456Z","caller":"traceutil/trace.go:171","msg":"trace[326117741] linearizableReadLoop","detail":"{readStateIndex:7982; appliedIndex:7981; }","duration":"114.013783ms","start":"2026-09-18T05:25:17.581411Z","end":"2026-09-18T05:25:17.695425Z","steps":["trace[326117741] 'read index received' (duration: 39.626873ms)","trace[326117741] 'applied index is now lower than readState.Index' (duration: 74.38547ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:17.695514Z","caller":"traceutil/trace.go:171","msg":"trace[1697921787] transaction","detail":"{read_only:false; response_revision:7690; number_of_response:1; }","duration":"161.81369ms","start":"2026-09-18T05:25:17.533675Z","end":"2026-09-18T05:25:17.695489Z","steps":["trace[1697921787] 'process raft request' (duration: 87.476682ms)","trace[1697921787] 'compare' (duration: 74.108082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:17.69565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.21492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:25:17.695709Z","caller":"traceutil/trace.go:171","msg":"trace[663465013] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:7690; }","duration":"114.336723ms","start":"2026-09-18T05:25:17.581355Z","end":"2026-09-18T05:25:17.695692Z","steps":["trace[663465013] 'agreement among raft nodes before linearized reading' (duration: 114.183399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:22.520247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.16236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:25:22.520344Z","caller":"traceutil/trace.go:171","msg":"trace[33886906] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:7715; }","duration":"155.323615ms","start":"2026-09-18T05:25:22.365001Z","end":"2026-09-18T05:25:22.520325Z","steps":["trace[33886906] 'count revisions from in-memory index tree' (duration: 154.998576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:22.521284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.47586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:548236"} {"level":"info","ts":"2026-09-18T05:25:22.521338Z","caller":"traceutil/trace.go:171","msg":"trace[18307798] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:7715; }","duration":"149.531621ms","start":"2026-09-18T05:25:22.371793Z","end":"2026-09-18T05:25:22.521325Z","steps":["trace[18307798] 'range keys from in-memory index tree' (duration: 149.011077ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:24.274585Z","caller":"traceutil/trace.go:171","msg":"trace[1939989212] transaction","detail":"{read_only:false; response_revision:7723; number_of_response:1; }","duration":"102.356243ms","start":"2026-09-18T05:25:24.172205Z","end":"2026-09-18T05:25:24.274561Z","steps":["trace[1939989212] 'process raft request' (duration: 102.195879ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:26.431051Z","caller":"traceutil/trace.go:171","msg":"trace[889189567] linearizableReadLoop","detail":"{readStateIndex:8029; appliedIndex:8028; }","duration":"101.404872ms","start":"2026-09-18T05:25:26.329622Z","end":"2026-09-18T05:25:26.431027Z","steps":["trace[889189567] 'read index received' (duration: 36.648761ms)","trace[889189567] 'applied index is now lower than readState.Index' (duration: 64.754771ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:26.431132Z","caller":"traceutil/trace.go:171","msg":"trace[923294602] transaction","detail":"{read_only:false; response_revision:7735; number_of_response:1; }","duration":"122.68702ms","start":"2026-09-18T05:25:26.308407Z","end":"2026-09-18T05:25:26.431094Z","steps":["trace[923294602] 'process raft request' (duration: 57.911938ms)","trace[923294602] 'compare' (duration: 64.329629ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:26.431311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.66931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cert-manager-controller-challenges\" ","response":"range_response_count:1 size:1599"} {"level":"info","ts":"2026-09-18T05:25:26.431352Z","caller":"traceutil/trace.go:171","msg":"trace[1941813827] range","detail":"{range_begin:/registry/clusterroles/cert-manager-controller-challenges; range_end:; response_count:1; response_revision:7735; }","duration":"101.764343ms","start":"2026-09-18T05:25:26.329576Z","end":"2026-09-18T05:25:26.43134Z","steps":["trace[1941813827] 'agreement among raft nodes before linearized reading' (duration: 101.605778ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:33.595839Z","caller":"traceutil/trace.go:171","msg":"trace[711387983] linearizableReadLoop","detail":"{readStateIndex:8378; appliedIndex:8377; }","duration":"164.867079ms","start":"2026-09-18T05:26:33.430942Z","end":"2026-09-18T05:26:33.595809Z","steps":["trace[711387983] 'read index received' (duration: 164.711974ms)","trace[711387983] 'applied index is now lower than readState.Index' (duration: 154.055µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:26:33.595895Z","caller":"traceutil/trace.go:171","msg":"trace[2072410425] transaction","detail":"{read_only:false; response_revision:8071; number_of_response:1; }","duration":"170.304473ms","start":"2026-09-18T05:26:33.425552Z","end":"2026-09-18T05:26:33.595856Z","steps":["trace[2072410425] 'process raft request' (duration: 170.105377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:26:33.596058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.094425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-09-18T05:26:33.596109Z","caller":"traceutil/trace.go:171","msg":"trace[1444906419] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:8071; }","duration":"165.184837ms","start":"2026-09-18T05:26:33.43091Z","end":"2026-09-18T05:26:33.596095Z","steps":["trace[1444906419] 'agreement among raft nodes before linearized reading' (duration: 165.008823ms)"],"step_count":1}